Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.03.2021 07: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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16990712 kB
MemAvailable 31666188 kB
Buffers 0 kB
Cached 14717116 kB
SwapCached 0 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-03-05 07:26:52
DTS_END                         2021-03-05 07:26:52.6880
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/38488
TRN_ID                          239216
ATT_ID                          619
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: 9157.81 19680 1098937
customer order: creation 178.48 383 21418
customer order: refuse 14.90 321 1788
order to supplier: creation 47.92 1394 5750
order to supplier: removal 3.43 107760 411
invoice (draft): creation 47.50 3392 5700
invoice (draft): removal 50.87 917 6104
invoice accept: apply 43.36 15887 5203
invoice accept: cancel 45.35 798 5442
customer reserve: creation 3981.59 156 477791
customer reserve: removal 4447.83 66 533740
realization accept: apply 86.90 166 10428
realization accept: cancel 77.14 122 9257
payment from customer: creation 61.18 96 7341
payment from customer: removal 5.57 92 668
payment to supplier: creation 51.18 108 6141
payment to supplier: removal 3.33 73 400
service: total inventory turnovers 4.20 2857 504
service: total monetary turnovers 6.11 471 733
service: aggregate perf. data 0.94 14920 113
service: refresh index statistics 0.04 243620 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-03-2021 07:28:58 to 05-03-2021 07:29:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3124
TEST_TIME 2 3635
TEST_TIME 3 3626
TEST_TIME 4 4128
TEST_TIME 5 4427
TEST_TIME 6 4392
TEST_TIME 7 4604
TEST_TIME 8 4806
TEST_TIME 9 4936
TEST_TIME 10 5377
TEST_TIME 11 5914
TEST_TIME 12 5934
TEST_TIME 13 6222
TEST_TIME 14 7093
TEST_TIME 15 7536
TEST_TIME 16 7821
TEST_TIME 17 8382
TEST_TIME 18 8602
TEST_TIME 19 8532
TEST_TIME 20 8573
TEST_TIME 21 9141
TEST_TIME 22 9502
TEST_TIME 23 9870
TEST_TIME 24 11129
TEST_TIME 25 11091
TEST_TIME 26 10902
TEST_TIME 27 10798
TEST_TIME 28 10874
TEST_TIME 29 10671
TEST_TIME 30 10712
TEST_TIME 31 10439
TEST_TIME 32 10145
TEST_TIME 33 9981
TEST_TIME 34 9963
TEST_TIME 35 10019
TEST_TIME 36 9933
TEST_TIME 37 9788
TEST_TIME 38 9696
TEST_TIME 39 9552
TEST_TIME 40 9460
TEST_TIME 41 9709
TEST_TIME 42 9750
TEST_TIME 43 9773
TEST_TIME 44 9884
TEST_TIME 45 9788
TEST_TIME 46 9715
TEST_TIME 47 9935
TEST_TIME 48 9893
TEST_TIME 49 9989
TEST_TIME 50 9953
TEST_TIME 51 9903
TEST_TIME 52 10010
TEST_TIME 53 9978
TEST_TIME 54 9818
TEST_TIME 55 9734
TEST_TIME 56 9638
TEST_TIME 57 9541
TEST_TIME 58 9492
TEST_TIME 59 9423
TEST_TIME 60 9434
TEST_TIME 61 9405
TEST_TIME 62 9465
TEST_TIME 63 9412
TEST_TIME 64 9418
TEST_TIME 65 9405
TEST_TIME 66 9419
TEST_TIME 67 9476
TEST_TIME 68 9505
TEST_TIME 69 9515
TEST_TIME 70 9544
TEST_TIME 71 9617
TEST_TIME 72 9532
TEST_TIME 73 9441
TEST_TIME 74 9381
TEST_TIME 75 9336
TEST_TIME 76 9252
TEST_TIME 77 9209
TEST_TIME 78 9195
TEST_TIME 79 9191
TEST_TIME 80 9162
TEST_TIME 81 9134
TEST_TIME 82 9122
TEST_TIME 83 9102
TEST_TIME 84 9116
TEST_TIME 85 9129
TEST_TIME 86 9156
TEST_TIME 87 9371
TEST_TIME 88 9339
TEST_TIME 89 9367
TEST_TIME 90 9293
TEST_TIME 91 9287
TEST_TIME 92 9309
TEST_TIME 93 9324
TEST_TIME 94 9292
TEST_TIME 95 9293
TEST_TIME 96 9316
TEST_TIME 97 9300
TEST_TIME 98 9350
TEST_TIME 99 9331
TEST_TIME 100 9350
TEST_TIME 101 9362
TEST_TIME 102 9402
TEST_TIME 103 9416
TEST_TIME 104 9376
TEST_TIME 105 9302
TEST_TIME 106 9276
TEST_TIME 107 9224
TEST_TIME 108 9165
TEST_TIME 109 9145
TEST_TIME 110 9139
TEST_TIME 111 9110
TEST_TIME 112 9120
TEST_TIME 113 9104
TEST_TIME 114 9087
TEST_TIME 115 9060
TEST_TIME 116 9059
TEST_TIME 117 9187
TEST_TIME 118 9169
TEST_TIME 119 9139
TEST_TIME 120 9162

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-03-2021 07:29:01 to 05-03-2021 07:29:05.

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 1540541 1540541 0 0.00 0 14842 6 0 0
doc_list_biud 1540538 1540538 0 0.00 0 28699 29 0 0
sp_add_invoice_to_stock 5277 5203 74 1.40 25 162650 15888 0 74
sp_cancel_adding_invoice 5442 5442 0 0.00 5 82634 798 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 32134 322 0 0
sp_cancel_customer_reserve 533740 533740 0 0.00 1 17092 67 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 6250 92 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 5808 73 0 0
sp_cancel_supplier_invoice 6112 6104 8 0.13 2 18657 918 0 8
sp_cancel_supplier_order 411 411 0 0.00 8 1301135 107761 0 0
sp_cancel_write_off 9257 9257 0 0.00 1 9051 123 0 0
sp_client_order 21418 21418 0 0.00 4 16966 384 0 0
sp_customer_reserve 477791 477791 0 0.00 0 106993 157 0 0
sp_fill_shopping_cart 32865 32865 0 0.00 1 20458 49 0 0
sp_fill_shopping_cart_clo_res 477778 477778 0 0.00 0 25385 18 0 0
sp_get_clo_for_invoice 5190 5190 0 0.00 0 31503 385 0 0
sp_kill_cost_storno 15767 15767 0 0.00 0 5239 47 0 0
sp_kill_qstorno_ret_qs2qd 1083252 1083252 0 0.00 0 18647 37 0 0
sp_kill_qty_storno 572359 572359 0 0.00 0 28699 78 0 0
sp_lock_selected_doc 21498 21498 0 0.00 0 9313 5 0 0
sp_make_cost_storno 29100 29100 0 0.00 0 12656 43 0 0
sp_multiply_rows_for_pdistr 29099 29099 0 0.00 0 14837 29 0 0
sp_multiply_rows_for_qdistr 21418 21418 0 0.00 0 16820 215 0 0
sp_pay_from_customer 7341 7341 0 0.00 4 12329 96 0 0
sp_pay_to_supplier 6141 6141 0 0.00 5 21513 109 0 0
sp_payment_common 13482 13482 0 0.00 1 21320 19 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 28698 294 0 0
sp_qd_handle_on_invoice_upd_sts 10632 10632 0 0.00 0 18516 329 0 0
sp_qd_handle_on_reserve_upd_sts 10427 10427 0 0.00 0 24264 55 0 0
sp_reserve_write_off 10428 10428 0 0.00 6 24281 167 0 0
sp_supplier_invoice 5700 5700 0 0.00 6 86548 3392 0 0
sp_supplier_order 5750 5750 0 0.00 5 66299 1395 0 0
srv_aggregate_perf_data 113 113 0 0.00 2744 177427 14921 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 973 148814 46959 0 0
srv_find_qd_qs_mism 1049180 1049180 0 0.00 0 10511 5 0 0
srv_make_invnt_saldo 504 504 0 0.00 35 104441 2858 0 0
srv_make_money_saldo 733 733 0 0.00 1 15178 471 0 0
srv_recalc_idx_stat 5 5 0 0.00 147973 353929 243621 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5203 5203 0 0.00 0 10880 131 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 1945 58 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 4565 15 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1605 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 971 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 546 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 546 9 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 29245 1266 0 0
t$perf-norm:sp_cancel_write_off 1078 1078 0 0.00 0 763 5 0 0
t$perf-norm:sp_client_order 21418 21418 0 0.00 0 6702 14 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 939 5 0 0
t$perf-norm:sp_pay_from_customer 7341 7341 0 0.00 0 4445 7 0 0
t$perf-norm:sp_pay_to_supplier 6141 6141 0 0.00 0 9081 9 0 0
t$perf-norm:sp_reserve_write_off 10428 10428 0 0.00 0 7534 9 0 0
t$perf-norm:sp_supplier_invoice 5700 5700 0 0.00 0 4015 13 0 0
t$perf-norm:sp_supplier_order 5750 5750 0 0.00 0 6272 15 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 1376 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1002 21 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 655 6 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 1715 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10528 10380 148 1.40 0 7015 17 0 148
v_all_customers 38874 38874 0 0.00 0 43 1 0 0
v_all_suppliers 31906 31906 0 0.00 0 181 1 0 0
v_all_wares 42836 42836 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2206 13 0 0
v_cancel_client_order 3576 3576 0 0.00 0 2166 4 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 5546 15 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 3279 7 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 825 5 0 0
v_cancel_supplier_invoice 874 858 16 1.83 0 1760 16 0 16
v_cancel_supplier_order 822 822 0 0.00 0 2408 6 0 0
v_cancel_write_off 2156 2156 0 0.00 0 7077 14 0 0
v_max_id_clo_ord 5749 5749 0 0.00 0 268 1 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 1424 14 0 0
v_max_id_ord_sup 5698 5698 0 0.00 0 7098 9 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 178 2 0 0
v_max_non_paid_realizn 3951 3951 0 0.00 0 3391 5 0 0
v_min_id_clo_ord 5749 5749 0 0.00 0 3519 5 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 5342 9 0 0
v_min_id_ord_sup 5698 5698 0 0.00 0 8691 10 0 0
v_min_non_paid_invoice 6141 6141 0 0.00 0 21316 13 0 0
v_min_non_paid_realizn 7341 7341 0 0.00 0 6396 12 0 0
v_our_firm 10742 10742 0 0.00 0 26 1 0 0
v_reserve_write_off 20854 20854 0 0.00 0 8824 8 0 0
x_lock_depdocs_on_canc_invoice 11117 11117 0 0.00 0 16051 104 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 13435 337 0 0
x_make_qstorno_1000_1200 5750 5750 0 0.00 1 66285 1321 0 0
x_make_qstorno_1200_2000 5700 5700 0 0.00 2 86522 3190 0 0
x_make_qstorno_2100_3300 477791 477791 0 0.00 0 106992 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-03-2021 07:29:06 to 05-03-2021 07:29:10.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
04:56:56 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:57:57 38673 8192000000 9731536752 10029228032 806356320 8.961 100 91 181 0 836845424 8632320 21856784
04:59:44 17666 8192000000 9767441856 10077913088 846398448 9.364 100 84 168 0 880003216 13473936 20130832
05:01:07 4332 8192000000 9812564384 10133073920 880721952 9.707 100 86 172 0 922249952 22164608 19363392
05:02:13 16371 8192000000 9875830288 10207051776 924025344 10.136 100 81 162 0 982982272 41345312 17611616
05:03:33 10319 8192000000 9921684576 10265960448 958464000 10.474 100 78 156 1 1034140512 59413984 16262528
05:04:45 6955 8192000000 9968613152 10324439040 995486976 10.835 100 85 171 1 1085501744 72966992 17047776
05:05:54 3069 8192000000 10008504416 10371977216 1024223216 11.113 100 87 176 1 1119726016 77549040 17953760
05:07:00 2518 8192000000 10012773312 10380902400 1051504560 11.375 100 93 187 3 1122818784 52210848 19103376
05:08:04 3781 8192000000 10027251904 10399375360 1066086800 11.515 100 81 162 0 1143874016 61348560 16438656
05:09:09 6345 8192000000 9989100944 10369335296 1069970576 11.552 100 45 89 0 1117996144 38752544 9273024
05:10:16 57555 8192000000 10012786224 10403475456 1083235392 11.678 100 45 92 2 1121900512 29004960 9660160
05:12:14 5431 8192000000 10009112256 10414256128 1091428048 11.756 100 39 76 4 1122993712 22493616 9072048
05:13:21 6850 8192000000 10019037280 10422964224 1094748688 11.788 100 38 76 0 1127260192 23700416 8811088
05:14:29 6230 8192000000 9998079600 10406871040 1086761616 11.712 99 21 42 0 1110866496 19754992 4349888
05:15:36 31383 8192000000 10022151056 10408202240 1100402576 11.841 100 68 139 1 1128328176 12699776 15225824
05:17:07 10607 8192000000 10016178896 10427068416 1095386352 11.794 100 47 91 3 1139490848 33901520 10202976
05:18:23 9769 8192000000 9979447840 10376441856 1026560592 11.135 96 25 50 1 1056313776 24035360 5717824
05:19:34 9363 8192000000 9952008192 10355236864 1020046432 11.072 98 25 50 0 1046331712 20845600 5439680
05:20:44 52477 8192000000 9883979056 10252632064 888174768 9.781 87 31 61 1 906553760 12307328 6071664
05:22:37 4037 8192000000 9952543200 10331320320 1022799504 11.099 99 49 95 2 1059319904 26109104 10411296
05:23:42 11288 8192000000 9948697728 10343464960 1038210848 11.247 99 26 52 1 1062876352 18933728 5731776
05:24:54 10067 8192000000 9995420352 10397122560 1060312752 11.459 100 40 79 3 1087206896 17870192 9023952
05:26:10 24805 8192000000 9979585344 10390302720 1071169136 11.563 100 10 20 0 1094912640 21498144 2245360
05:27:47 28597 8192000000 10030319984 10428137472 1094006928 11.781 100 51 102 3 1127756752 23347872 10401952
05:29:29 11070 8192000000 10086777504 10486087680 1116860192 11.997 100 51 101 1 1183385120 55377520 11147408
05:30:43 25567 8192000000 10126229008 10540855296 1134912816 12.168 100 44 86 2 1252196688 107750464 9533408
05:32:12 24979 8192000000 10190792080 10600472576 1131558480 12.136 99 57 113 2 1288892432 144681680 12652272
05:33:47 3911 8192000000 10198211488 10608803840 1156977488 12.375 100 50 99 0 1304787840 136243856 11566496
05:34:57 4554 8192000000 10250553776 10665873408 1171137968 12.507 100 70 140 1 1353788352 166718880 15931504
05:36:09 5666 8192000000 10301264512 10706993152 1178183904 12.573 100 73 144 1 1401337424 206759360 16394160
05:37:18 2298 8192000000 10353675120 10759454720 1184400416 12.631 100 87 171 1 1454188496 250047168 19740912
05:38:24 972 8192000000 10370442480 10778038272 1192056992 12.703 100 85 172 1 1474304048 261314320 20932736
05:39:29 2843 8192000000 10399849072 10814050304 1187257024 12.658 100 80 159 1 1497069584 292284240 17528320
05:40:38 1703 8192000000 10335465920 10768490496 1170516448 12.502 99 71 142 1 1435800272 248899472 16384352
05:41:44 12589 8192000000 10326387376 10749181952 1154850688 12.355 99 40 80 0 1429939648 265052832 10036128
05:43:04 10048 8192000000 10336344832 10787467264 1147062368 12.282 100 39 76 0 1449306944 292601552 9643024
05:44:22 9539 8192000000 10330880272 10746609664 1135004336 12.169 99 47 94 0 1407759520 261717936 11037248
05:45:43 9152 8192000000 10316532352 10732707840 1110663472 11.939 98 39 77 1 1399538624 278650736 10224416
05:46:54 18762 8192000000 10349642048 10811232256 1107587136 11.910 98 38 76 3 1447421504 330088752 9745616
05:48:15 4500 8192000000 10347615488 10767536128 1134705728 12.166 100 46 91 0 1443017088 297035904 11275456
05:49:21 28667 8192000000 10255658704 10735226880 1131791936 12.138 100 25 50 0 1352563392 214009072 6762384
05:51:05 66623 8192000000 10052653952 10502922240 1108802368 11.921 98 7 14 0 1171629616 61360896 1466352
05:53:19 23534 8192000000 10085393088 10514432000 1098371200 11.822 97 21 41 1 1152047104 49286272 4389632
05:54:43 99461 8192000000 10038192272 10494066688 1073860752 11.589 97 9 17 0 1092624816 17137456 1626608
05:57:23 125344 8192000000 10040881488 10472194048 1080400160 11.651 97 33 66 0 1113973104 26325664 7247280
06:00:30 51160 8192000000 10072503424 10512232448 1121176368 12.038 100 39 78 1 1155465008 26370208 7918432
06:02:26 148813 8192000000 10140634064 10593837056 1090517680 11.748 96 20 39 0 1149900224 55442320 3940224
06:06:22 53371 8192000000 10156603152 10649845760 1133603520 12.155 100 23 46 0 1277122944 138024336 5495088
06:08:23 41887 8192000000 10105196768 10537750528 1126859936 12.092 99 23 46 1 1176058784 44387200 4811648
06:10:19 58879 8192000000 10088572352 10549428224 1115024176 11.980 98 18 36 3 1203171024 84156592 3990256
06:12:24 86362 8192000000 10090075424 10592706560 1136113408 12.179 100 24 48 0 1224564224 83123728 5327088
06:14:51 44410 8192000000 10139147936 10595532800 1137503376 12.192 99 24 48 0 1191306352 48781488 5021488
06:17:30 67244 8192000000 10141990928 10599608320 1113003232 11.961 97 17 34 0 1220615632 103915968 3696432
06:19:38 99556 8192000000 10063182864 10513686528 1125014000 12.074 99 23 46 0 1162693056 33420640 4258416
06:23:57 66546 8192000000 10057198896 10502774784 1092954800 11.771 96 6 10 0 1119414656 25483088 976768
06:26:04 75470 8192000000 10092478480 10534232064 1107911760 11.913 98 17 33 1 1162143664 50453840 3778064
06:28:31 46551 8192000000 10059613680 10500108288 1094306304 11.784 96 19 38 1 1141430832 43495552 3628976
06:30:20 49656 8192000000 10068663024 10512879616 1105157280 11.887 98 22 44 2 1163303280 53565776 4580224
06:32:16 66594 8192000000 10091593024 10571952128 1110623968 11.938 98 23 46 0 1184266464 69077360 4565136
06:34:24 46665 8192000000 10092175344 10532057088 1112387616 11.955 98 27 53 4 1175388224 57365344 5635264
06:36:21 64332 8192000000 10081440160 10535464960 1085879648 11.703 96 28 56 0 1173474624 81889760 5705216
06:38:26 106850 8192000000 10045317504 10477113344 1077014496 11.619 96 27 54 0 1113620784 30622192 5984096
06:41:42 31287 8192000000 10053107360 10514104320 1125270352 12.077 100 9 18 1 1169024640 41615200 2139088
06:43:16 19390 8192000000 10051484272 10534875136 1107422816 11.908 99 4 8 0 1149367024 41224240 719968
06:44:41 55951 8192000000 10051660000 10502107136 1104757888 11.883 98 16 32 1 1131489760 23732208 2999664
06:46:37 40312 8192000000 10084137568 10519543808 1105961424 11.894 99 40 80 0 1163232176 49111712 8159040
06:48:21 38451 8192000000 10137400512 10572980224 1120759680 12.034 100 34 68 0 1226302768 98223072 7320016
06:50:09 79723 8192000000 10208493424 10691911680 1135294048 12.171 100 20 41 0 1275440064 135463424 4682592
06:52:41 78510 8192000000 10085356448 10561470464 1122047488 12.046 99 15 30 0 1238975840 113118016 3810336
06:55:05 121506 8192000000 10088888192 10556649472 1115572800 11.985 98 15 30 1 1152527696 33852320 3102576
06:58:09 45171 8192000000 10105058784 10582802432 1124615280 12.071 98 13 26 0 1195670176 68422016 2632880
06:59:55 85347 8192000000 10102684720 10572128256 1108549408 11.919 97 18 34 1 1164424144 52364944 3509792
07:02:21 28574 8192000000 10100025056 10541891584 1118185632 12.010 97 41 82 2 1151206160 23610624 9409904
07:03:56 20962 8192000000 10113805232 10565001216 1141544144 12.230 100 35 70 2 1194139408 44947024 7648240
07:05:22 46924 8192000000 10098909408 10550325248 1124265600 12.067 98 18 36 2 1175878032 47774944 3837488
07:07:12 74384 8192000000 10095217152 10554982400 1130895216 12.130 99 16 32 0 1163354368 29564784 2894368
07:09:27 57010 8192000000 10082329856 10576326656 1112405376 11.955 98 9 18 0 1160654352 46376864 1872112
07:11:35 71754 8192000000 10065778432 10523037696 1115505360 11.985 98 22 44 0 1164959504 44125808 5328336
07:13:56 52564 8192000000 10070359776 10523287552 1123292352 12.058 99 9 17 0 1163713008 38539072 1881584
07:15:57 51892 8192000000 10079161424 10584645632 1120489520 12.032 99 10 20 1 1186128224 63324832 2313872
07:17:53 55554 8192000000 10074261504 10536677376 1106492704 11.899 98 24 47 2 1143405136 32011216 4901216
07:19:51 40419 8192000000 10089286160 10539118592 1096013536 11.800 97 15 30 1 1151532816 52218752 3300528
07:21:32 23305 8192000000 10115764288 10555023360 1117536528 12.004 99 33 62 3 1193827984 69399040 6892416
07:22:55 71706 8192000000 10110350736 10669568000 1119652960 12.024 99 20 40 2 1227125888 102671776 4801152
07:25:07 29793 8192000000 10133473280 10547675136 1132820032 12.148 99 48 96 0 1194731536 52008784 9902720
07:26:37 43184 8192000000 9091800848 9428631552 212798496 2.531 19 6 10 0 285253104 70954928 1499680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-03-2021 07:29:12 to 05-03-2021 07:29:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-03-2021 07:29:27 to 05-03-2021 07:29:29.

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          242004
MON$OLDEST_ACTIVE               242005
MON$OLDEST_SNAPSHOT             242005
MON$NEXT_TRANSACTION            242005
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                       2100288
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 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.2382 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Mar  5 07:27:27 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Fri Mar  5 07:27:28 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Fri Mar  5 07:27:37 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

05.03.21 07:29:30. 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-03-05 07:29:33 No severe PSQL-related problems occured
05.03.21 07:29:34. End of report.