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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-01 04:37:09
DTS_END                         2021-11-01 04:37:09.0340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37556
TRN_ID                          236286
ATT_ID                          698
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: 9040.84 21377 1084901
customer order: creation 172.07 389 20648
customer order: refuse 14.40 335 1728
order to supplier: creation 45.68 1361 5481
order to supplier: removal 3.56 111961 427
invoice (draft): creation 45.73 3487 5487
invoice (draft): removal 51.08 960 6130
invoice accept: apply 41.28 20036 4953
invoice accept: cancel 46.18 894 5542
customer reserve: creation 3791.56 200 454987
customer reserve: removal 4537.61 70 544513
realization accept: apply 82.04 179 9845
realization accept: cancel 80.24 122 9629
payment from customer: creation 59.88 112 7185
payment from customer: removal 6.03 83 724
payment to supplier: creation 48.91 128 5869
payment to supplier: removal 3.63 65 436
service: total inventory turnovers 3.68 3600 441
service: total monetary turnovers 6.31 581 757
service: aggregate perf. data 0.96 16413 115
service: refresh index statistics 0.03 266560 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-11-2021 04:38:32 to 01-11-2021 04:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7360
TEST_TIME 2 6024
TEST_TIME 3 5882
TEST_TIME 4 5417
TEST_TIME 5 5207
TEST_TIME 6 5394
TEST_TIME 7 5500
TEST_TIME 8 5622
TEST_TIME 9 5811
TEST_TIME 10 6279
TEST_TIME 11 6773
TEST_TIME 12 6675
TEST_TIME 13 6918
TEST_TIME 14 6850
TEST_TIME 15 7254
TEST_TIME 16 7622
TEST_TIME 17 7648
TEST_TIME 18 8080
TEST_TIME 19 8343
TEST_TIME 20 8526
TEST_TIME 21 8610
TEST_TIME 22 8967
TEST_TIME 23 9214
TEST_TIME 24 9754
TEST_TIME 25 10715
TEST_TIME 26 11186
TEST_TIME 27 11236
TEST_TIME 28 11117
TEST_TIME 29 11217
TEST_TIME 30 11205
TEST_TIME 31 10965
TEST_TIME 32 10730
TEST_TIME 33 10654
TEST_TIME 34 10455
TEST_TIME 35 10396
TEST_TIME 36 10403
TEST_TIME 37 10303
TEST_TIME 38 10348
TEST_TIME 39 10263
TEST_TIME 40 10254
TEST_TIME 41 10107
TEST_TIME 42 10204
TEST_TIME 43 10158
TEST_TIME 44 10049
TEST_TIME 45 10030
TEST_TIME 46 9991
TEST_TIME 47 9875
TEST_TIME 48 9940
TEST_TIME 49 10043
TEST_TIME 50 9941
TEST_TIME 51 9899
TEST_TIME 52 9936
TEST_TIME 53 10043
TEST_TIME 54 10027
TEST_TIME 55 10008
TEST_TIME 56 10155
TEST_TIME 57 10131
TEST_TIME 58 9971
TEST_TIME 59 9889
TEST_TIME 60 9811
TEST_TIME 61 9832
TEST_TIME 62 9839
TEST_TIME 63 9755
TEST_TIME 64 9679
TEST_TIME 65 9694
TEST_TIME 66 9665
TEST_TIME 67 9567
TEST_TIME 68 9525
TEST_TIME 69 9518
TEST_TIME 70 9518
TEST_TIME 71 9625
TEST_TIME 72 9705
TEST_TIME 73 9632
TEST_TIME 74 9683
TEST_TIME 75 9709
TEST_TIME 76 9682
TEST_TIME 77 9624
TEST_TIME 78 9586
TEST_TIME 79 9507
TEST_TIME 80 9487
TEST_TIME 81 9429
TEST_TIME 82 9374
TEST_TIME 83 9364
TEST_TIME 84 9336
TEST_TIME 85 9305
TEST_TIME 86 9266
TEST_TIME 87 9351
TEST_TIME 88 9323
TEST_TIME 89 9324
TEST_TIME 90 9314
TEST_TIME 91 9432
TEST_TIME 92 9397
TEST_TIME 93 9432
TEST_TIME 94 9451
TEST_TIME 95 9468
TEST_TIME 96 9413
TEST_TIME 97 9363
TEST_TIME 98 9343
TEST_TIME 99 9359
TEST_TIME 100 9301
TEST_TIME 101 9235
TEST_TIME 102 9196
TEST_TIME 103 9178
TEST_TIME 104 9223
TEST_TIME 105 9268
TEST_TIME 106 9244
TEST_TIME 107 9244
TEST_TIME 108 9265
TEST_TIME 109 9267
TEST_TIME 110 9264
TEST_TIME 111 9242
TEST_TIME 112 9281
TEST_TIME 113 9294
TEST_TIME 114 9220
TEST_TIME 115 9203
TEST_TIME 116 9183
TEST_TIME 117 9150
TEST_TIME 118 9099
TEST_TIME 119 9117
TEST_TIME 120 9046

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-11-2021 04:38:35 to 01-11-2021 04:38: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 1508203 1508203 0 0.00 0 16717 7 0 0
doc_list_biud 1508202 1508202 0 0.00 0 56248 32 0 0
sp_add_invoice_to_stock 5070 4953 117 2.30 58 302893 20037 0 117
sp_cancel_adding_invoice 5542 5542 0 0.00 5 60192 895 0 0
sp_cancel_client_order 1728 1728 0 0.00 4 11027 335 0 0
sp_cancel_customer_reserve 544513 544513 0 0.00 1 56252 70 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 6749 84 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 1891 65 0 0
sp_cancel_supplier_invoice 6148 6130 18 0.29 2 39613 961 0 18
sp_cancel_supplier_order 427 427 0 0.00 6 1038823 111962 0 0
sp_cancel_write_off 9629 9629 0 0.00 1 11094 122 0 0
sp_client_order 20648 20648 0 0.00 4 25400 389 0 0
sp_customer_reserve 454987 454987 0 0.00 0 66652 201 0 0
sp_fill_shopping_cart 31609 31609 0 0.00 1 12908 42 0 0
sp_fill_shopping_cart_clo_res 454977 454977 0 0.00 0 13998 17 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 34199 334 0 0
sp_kill_cost_storno 16331 16331 0 0.00 0 8100 56 0 0
sp_kill_qstorno_ret_qs2qd 1105212 1105212 0 0.00 0 56241 38 0 0
sp_kill_qty_storno 582752 582752 0 0.00 0 56247 81 0 0
sp_lock_selected_doc 20597 20597 0 0.00 0 6940 4 0 0
sp_make_cost_storno 27838 27838 0 0.00 0 16706 52 0 0
sp_multiply_rows_for_pdistr 27838 27838 0 0.00 0 10408 29 0 0
sp_multiply_rows_for_qdistr 20648 20648 0 0.00 0 12757 207 0 0
sp_pay_from_customer 7185 7185 0 0.00 4 20271 112 0 0
sp_pay_to_supplier 5869 5869 0 0.00 5 14589 129 0 0
sp_payment_common 13054 13054 0 0.00 2 20259 23 0 0
sp_qd_handle_on_cancel_clo 1728 1728 0 0.00 0 11021 291 0 0
sp_qd_handle_on_invoice_upd_sts 10480 10480 0 0.00 0 21323 349 0 0
sp_qd_handle_on_reserve_upd_sts 9845 9845 0 0.00 0 11552 59 0 0
sp_reserve_write_off 9845 9845 0 0.00 7 12725 179 0 0
sp_supplier_invoice 5487 5487 0 0.00 6 78690 3488 0 0
sp_supplier_order 5481 5481 0 0.00 6 30853 1362 0 0
srv_aggregate_perf_data 116 115 1 0.86 2920 415864 16414 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1710 128692 41675 0 0
srv_find_qd_qs_mism 1038376 1038376 0 0.00 0 14783 7 0 0
srv_make_invnt_saldo 441 441 0 0.00 59 91243 3600 0 0
srv_make_money_saldo 757 757 0 0.00 1 19944 581 0 0
srv_recalc_idx_stat 4 4 0 0.00 177506 355828 266561 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4954 4954 0 0.00 0 12290 162 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 9003 154 0 0
t$perf-norm:sp_cancel_client_order 1728 1728 0 0.00 0 4661 12 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4171 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 999 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 629 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 425 11 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 18800 1612 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 1652 6 0 0
t$perf-norm:sp_client_order 20648 20648 0 0.00 0 9032 16 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 4000 16 0 0
t$perf-norm:sp_pay_from_customer 7185 7185 0 0.00 0 7144 10 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 3456 7 0 0
t$perf-norm:sp_reserve_write_off 9845 9845 0 0.00 0 6568 10 0 0
t$perf-norm:sp_supplier_invoice 5487 5487 0 0.00 0 8784 18 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 4819 15 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 541 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 472 8 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 5178 27 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 3066 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10106 9872 234 2.31 0 5260 17 0 234
v_all_customers 37498 37498 0 0.00 0 86 1 0 0
v_all_suppliers 30376 30376 0 0.00 0 22 1 0 0
v_all_wares 41296 41296 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 269 6 0 0
v_cancel_client_order 3456 3456 0 0.00 0 4765 9 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 6743 14 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 1271 5 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1607 5 0 0
v_cancel_supplier_invoice 890 854 36 4.04 0 735 13 0 36
v_cancel_supplier_order 854 854 0 0.00 0 168 2 0 0
v_cancel_write_off 1872 1872 0 0.00 0 2859 14 0 0
v_max_id_clo_ord 5477 5477 0 0.00 0 1209 2 0 0
v_max_id_clo_res 982 982 0 0.00 0 3529 21 0 0
v_max_id_ord_sup 5484 5484 0 0.00 0 6793 6 0 0
v_max_non_paid_invoice 1642 1642 0 0.00 0 1472 4 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 5532 5 0 0
v_min_id_clo_ord 5477 5477 0 0.00 0 1740 5 0 0
v_min_id_clo_res 982 982 0 0.00 0 4260 16 0 0
v_min_id_ord_sup 5484 5484 0 0.00 0 7123 7 0 0
v_min_non_paid_invoice 5869 5869 0 0.00 0 4278 14 0 0
v_min_non_paid_realizn 7185 7185 0 0.00 0 4865 12 0 0
v_our_firm 10328 10328 0 0.00 0 14 1 0 0
v_reserve_write_off 19690 19690 0 0.00 0 4448 7 0 0
x_lock_depdocs_on_canc_invoice 11245 11245 0 0.00 0 16489 129 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 10018 332 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 30833 1287 0 0
x_make_qstorno_1200_2000 5487 5487 0 0.00 3 78011 3326 0 0
x_make_qstorno_2100_3300 454987 454987 0 0.00 0 66651 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-11-2021 04:38:40 to 01-11-2021 04:38:44.

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
02:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 25640 8192000000 9715513760 10009280512 789101584 8.786 100 88 175 1 818870000 8074208 21694208
02:09:49 12928 8192000000 9760817408 10066030592 836490592 9.265 100 86 172 0 870902784 14231376 20180816
02:11:12 13922 8192000000 9802535776 10123014144 867986880 9.580 100 80 160 0 915183264 29262016 17934368
02:12:30 10423 8192000000 9876675424 10207981568 921774656 10.114 100 79 158 0 988676816 49947520 16954640
02:13:46 6215 8192000000 9929422496 10272219136 962219296 10.511 100 87 176 0 1041008000 60686640 18102064
02:14:53 3117 8192000000 9983191168 10336960512 1000499760 10.883 100 80 158 1 1090529776 73866656 16163360
02:16:00 2083 8192000000 10014028224 10374189056 1033577424 11.203 100 91 180 0 1122581376 70794064 18209888
02:17:03 1406 8192000000 10022414544 10390200320 1059160016 11.448 100 97 199 1 1130009856 51140496 19709344
02:18:07 7518 8192000000 9994531216 10368540672 1063605872 11.491 100 67 133 0 1100311248 22797744 13907632
02:19:15 3596 8192000000 9998789072 10382536704 1071406384 11.566 100 51 100 0 1106056672 24188304 10461984
02:20:19 10985 8192000000 10000613776 10401452032 1087780256 11.722 100 37 73 1 1118170400 23095600 7294544
02:21:32 6858 8192000000 9997844608 10395480064 1088392656 11.727 100 35 70 1 1118635328 22273328 7969344
02:22:40 42115 8192000000 10003781008 10410827776 1091859216 11.760 100 41 80 1 1118102496 16852160 9391120
02:24:26 5939 8192000000 10025167728 10425769984 1094923952 11.789 100 41 82 1 1152542464 49419776 8198736
02:25:36 9711 8192000000 9992113760 10393194496 1074082976 11.591 98 34 68 1 1106326912 23983024 8260912
02:26:53 59793 8192000000 9988628208 10391302144 1064717792 11.502 98 29 58 0 1094790048 23462544 6609712
02:28:53 3604 8192000000 9970741424 10349793280 1005779440 10.935 95 70 139 2 1047549280 26782704 14987136
02:29:58 3455 8192000000 9929537664 10304045056 999364816 10.872 98 61 121 2 1031392016 18187280 13839920
02:31:02 7458 8192000000 9914360496 10291527680 982517232 10.709 96 33 67 0 1001046816 11237776 7291808
02:32:10 34130 8192000000 9927954640 10323374080 1016176160 11.035 98 17 35 1 1037574896 17877856 3520880
02:33:49 4544 8192000000 9964424960 10358194176 1057242144 11.430 100 51 97 0 1083467488 15344448 10880896
02:34:54 9024 8192000000 9993013440 10400677888 1067046976 11.524 100 36 72 1 1095099392 19673264 8379152
02:36:05 57615 8192000000 10003134624 10408845312 1071979536 11.571 100 26 51 1 1105871104 28932464 4959104
02:38:13 14853 8192000000 10047324640 10445934592 1105465984 11.889 100 56 112 1 1152057744 34507776 12083984
02:39:36 22416 8192000000 10069915712 10473197568 1120980992 12.036 100 43 86 1 1184199840 54216768 9002080
02:41:08 7222 8192000000 10103925456 10510467072 1130379216 12.125 100 41 82 1 1210618480 71355856 8883408
02:42:21 15264 8192000000 10147901696 10551812096 1143348736 12.247 100 57 113 1 1254439120 98789584 12300800
02:43:46 4580 8192000000 10164718240 10571886592 1153341600 12.341 100 55 110 1 1269777952 104503904 11932448
02:44:57 15138 8192000000 10215580576 10624651264 1161297760 12.415 100 58 113 1 1321608272 147118448 13192064
02:46:18 27956 8192000000 10230385680 10645307392 1168439248 12.482 100 48 92 0 1341254448 161330368 11484832
02:47:49 4345 8192000000 10270426672 10711822336 1166937344 12.468 99 56 111 1 1374538176 194863728 12737104
02:48:59 13159 8192000000 10280246160 10696081408 1187060560 12.656 100 52 100 1 1386287024 187342608 11883856
02:50:17 1709 8192000000 10342850144 10745978880 1179577568 12.586 100 82 166 0 1440536288 242317872 18640848
02:51:26 3942 8192000000 10371547728 10787504128 1187055024 12.656 100 69 139 0 1474749952 270908880 16786048
02:52:38 2947 8192000000 10385778224 10795536384 1185907952 12.645 100 77 154 0 1485464240 280952128 18604160
02:53:48 10572 8192000000 10345826576 10764677120 1128991808 12.112 97 49 97 1 1426535728 284670160 12873760
02:55:15 2532 8192000000 10406485168 10811314176 1155703888 12.363 100 76 148 2 1508566784 334996064 17866832
02:56:21 6345 8192000000 10377135568 10790850560 1145986176 12.272 100 55 109 2 1482528048 323307904 13233968
02:57:33 7089 8192000000 10379150096 10827165696 1138233680 12.199 100 39 78 0 1494706912 345944416 10528816
02:58:45 11465 8192000000 10390985488 10812145664 1120448496 12.031 99 41 83 0 1481148144 349950576 10749072
02:59:58 25007 8192000000 10356281920 10836459520 1125560736 12.079 100 36 72 0 1501384816 365708000 10116080
03:01:28 19704 8192000000 10341871104 10807451648 1120394832 12.031 100 24 48 1 1476710560 349360400 6955328
03:02:53 47820 8192000000 10052039776 10500169728 1074391728 11.594 97 13 26 0 1216193392 138979184 2822480
03:04:49 67421 8192000000 10107167232 10550779904 1113422080 11.965 99 15 29 0 1172360176 55954784 2983312
03:07:40 81265 8192000000 10057696368 10485981184 1069589408 11.548 95 17 35 0 1099653136 26181920 3881808
03:10:58 95229 8192000000 10085462480 10518540288 1093480304 11.776 97 18 36 0 1150539360 53329264 3729792
03:13:40 17156 8192000000 10107786672 10542673920 1143813248 12.251 100 35 69 0 1210786000 59531440 7441312
03:15:02 74836 8192000000 10147762768 10635325440 1142831056 12.242 99 27 54 0 1270859984 121802480 6226448
03:17:25 24911 8192000000 10134433056 10600976384 1136282928 12.181 99 13 26 0 1234923088 95703728 2936432
03:18:50 51659 8192000000 10057617680 10522861568 1131024640 12.131 100 22 42 2 1193579024 57631488 4922896
03:20:44 57533 8192000000 10062242432 10514661376 1118151008 12.010 98 16 30 0 1170294624 49610544 2533072
03:23:06 48944 8192000000 10080838656 10538967040 1139376576 12.210 100 5 10 0 1178883856 38783392 723888
03:24:56 62399 8192000000 10086301184 10566279168 1117108992 12.000 97 30 60 0 1175131616 52189088 5833536
03:27:01 29476 8192000000 10084913424 10514763776 1145726032 12.269 100 28 56 0 1179592720 28356368 5510320
03:28:31 48056 8192000000 10152208224 10590736384 1136417136 12.182 99 33 65 1 1238071920 95431648 6223136
03:30:26 70042 8192000000 10089693840 10549149696 1146363104 12.275 100 15 30 0 1198171872 48746720 3062048
03:32:37 58528 8192000000 10111852096 10574069760 1114880368 11.979 97 22 44 0 1207777936 88315200 4582368
03:34:40 119443 8192000000 10081887440 10526199808 1126578064 12.089 98 30 60 1 1160968992 27943360 6447568
03:37:42 37041 8192000000 10134051376 10586779648 1148599280 12.296 100 32 66 1 1240619824 85299392 6721152
03:39:25 41588 8192000000 10093460784 10626248704 1137574160 12.193 99 9 18 0 1196905568 57432672 1898736
03:41:28 41420 8192000000 10095115936 10552840192 1121309040 12.039 98 16 30 0 1166837552 42445184 3083328
03:43:12 92274 8192000000 10105033200 10532753408 1089800992 11.741 95 22 44 2 1146638080 51991344 4845744
03:45:52 46504 8192000000 10137144000 10573541376 1077804192 11.627 95 13 26 0 1190508816 109710208 2994416
03:47:42 42241 8192000000 10136455552 10575056896 1100740992 11.845 98 32 62 2 1211086144 102467328 7877824
03:49:24 54359 8192000000 10047202976 10500308992 1090257392 11.745 97 20 36 1 1144938608 50965184 3716032
03:51:19 20707 8192000000 10120718768 10549686272 1096439600 11.804 98 40 80 0 1193637936 89054096 8144240
03:52:46 24659 8192000000 10074793264 10497105920 1115089248 11.981 100 26 48 1 1141095072 21494784 4511040
03:54:10 12859 8192000000 10073557184 10492354560 1117247376 12.001 100 47 94 0 1162062496 35666496 9148624
03:55:26 100480 8192000000 10075485232 10516033536 1115078384 11.980 99 37 73 1 1177283760 55140976 7064400
03:58:08 25219 8192000000 10038844400 10491461632 1121966400 12.046 100 11 21 2 1154603200 30062592 2574208
03:59:39 28111 8192000000 10076172432 10538647552 1105137264 11.886 98 4 8 0 1165324384 59560320 626800
04:01:08 34465 8192000000 10066092608 10569105408 1122345760 12.049 100 16 32 0 1176744944 50854720 3544464
04:03:08 76758 8192000000 10054666912 10539999232 1121943392 12.045 99 13 26 0 1200528656 75573568 3011696
04:05:29 34868 8192000000 10094124880 10536325120 1107614080 11.910 97 20 40 0 1177503712 65613200 4276432
04:07:05 100880 8192000000 10124872464 10644475904 1146591904 12.277 100 17 34 0 1239144160 88997664 3554592
04:09:47 39147 8192000000 10066937216 10506190848 1113258464 11.963 97 14 28 0 1163877744 47669232 2950048
04:11:26 42967 8192000000 10136761952 10619228160 1138510592 12.202 99 34 68 0 1228866944 83353200 7003152
04:13:13 55762 8192000000 10113244464 10556846080 1124627040 12.071 98 42 84 0 1173755088 40697312 8430736
04:16:24 43776 8192000000 10099982432 10606014464 1147774176 12.289 100 8 16 0 1208712368 59046768 1891424
04:19:38 46954 8192000000 10140124048 10588893184 1122972768 12.055 97 28 57 0 1211684864 82614560 6097536
04:21:45 51946 8192000000 10051907616 10523009024 1102161936 11.858 97 15 29 1 1155442816 49521392 3759488
04:23:38 17634 8192000000 10089036928 10516516864 1139915392 12.215 100 48 95 2 1179983552 30433744 9634416
04:24:55 88581 8192000000 10082631216 10518753280 1110493760 11.937 97 21 42 1 1167824960 53089392 4241808
04:27:29 52089 8192000000 10085093040 10520768512 1109910992 11.932 97 33 66 2 1155942512 39189744 6841776
04:29:21 30881 8192000000 10141434656 10622271488 1135568336 12.174 99 40 80 0 1237035216 93601360 7865520
04:30:54 128692 8192000000 10077052112 10537250816 1127550784 12.098 99 31 60 0 1180156304 46703616 5901904
04:34:03 59064 8192000000 10064135552 10537046016 1128270528 12.105 99 14 28 2 1165930688 34689072 2971088
04:36:11 65842 8192000000 9205350192 9539969024 327857712 3.848 29 5 10 0 353350192 24445904 1046576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-11-2021 04:38:46 to 01-11-2021 04:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 117 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-11-2021 04:39:01 to 01-11-2021 04:39:03.

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          236540
MON$OLDEST_ACTIVE               236541
MON$OLDEST_SNAPSHOT             236541
MON$NEXT_TRANSACTION            236541
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                       2053472
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             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:53: 
:54: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:20 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Mon Nov  1 04:37:23 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
--- end of diff output ---

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

01.11.21 04:39:04. 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-11-01 04:39:07 No severe PSQL-related problems occured
01.11.21 04:39:08. End of report.