Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2021 07:29
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 16710604 kB
MemAvailable 31337320 kB
Buffers 0 kB
Cached 14902668 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-06 07:26:53
DTS_END                         2021-01-06 07:26:53.3240
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/41592
TRN_ID                          235584
ATT_ID                          655
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: 9218.26 26149 1106191
customer order: creation 166.36 443 19963
customer order: refuse 14.60 340 1752
order to supplier: creation 46.55 1337 5586
order to supplier: removal 3.53 111373 423
invoice (draft): creation 47.14 3173 5657
invoice (draft): removal 52.61 876 6313
invoice accept: apply 41.80 17756 5016
invoice accept: cancel 47.26 896 5671
customer reserve: creation 3862.08 174 463449
customer reserve: removal 4643.48 67 557217
realization accept: apply 82.38 161 9885
realization accept: cancel 81.86 125 9823
payment from customer: creation 59.00 111 7080
payment from customer: removal 5.82 84 698
payment to supplier: creation 49.81 119 5977
payment to supplier: removal 3.13 103 376
service: total inventory turnovers 3.93 3037 471
service: total monetary turnovers 6.08 524 730
service: aggregate perf. data 0.83 15010 99
service: refresh index statistics 0.04 367266 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-01-2021 07:29:37 to 06-01-2021 07:29:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2058
TEST_TIME 2 2256
TEST_TIME 3 3505
TEST_TIME 4 3431
TEST_TIME 5 3938
TEST_TIME 6 4255
TEST_TIME 7 4885
TEST_TIME 8 4916
TEST_TIME 9 5137
TEST_TIME 10 5374
TEST_TIME 11 5801
TEST_TIME 12 5898
TEST_TIME 13 6090
TEST_TIME 14 6268
TEST_TIME 15 6725
TEST_TIME 16 6725
TEST_TIME 17 6931
TEST_TIME 18 7088
TEST_TIME 19 7793
TEST_TIME 20 7818
TEST_TIME 21 8644
TEST_TIME 22 8850
TEST_TIME 23 8844
TEST_TIME 24 9253
TEST_TIME 25 9761
TEST_TIME 26 10986
TEST_TIME 27 11114
TEST_TIME 28 11197
TEST_TIME 29 11014
TEST_TIME 30 10768
TEST_TIME 31 10546
TEST_TIME 32 10381
TEST_TIME 33 10377
TEST_TIME 34 10219
TEST_TIME 35 10124
TEST_TIME 36 10127
TEST_TIME 37 10133
TEST_TIME 38 10029
TEST_TIME 39 9914
TEST_TIME 40 9897
TEST_TIME 41 9799
TEST_TIME 42 9651
TEST_TIME 43 9708
TEST_TIME 44 9619
TEST_TIME 45 9629
TEST_TIME 46 9647
TEST_TIME 47 9686
TEST_TIME 48 9667
TEST_TIME 49 9713
TEST_TIME 50 9868
TEST_TIME 51 9840
TEST_TIME 52 9835
TEST_TIME 53 9905
TEST_TIME 54 9904
TEST_TIME 55 9879
TEST_TIME 56 9837
TEST_TIME 57 9678
TEST_TIME 58 9585
TEST_TIME 59 9602
TEST_TIME 60 9468
TEST_TIME 61 9497
TEST_TIME 62 9430
TEST_TIME 63 9497
TEST_TIME 64 9542
TEST_TIME 65 9485
TEST_TIME 66 9469
TEST_TIME 67 9438
TEST_TIME 68 9508
TEST_TIME 69 9577
TEST_TIME 70 9551
TEST_TIME 71 9588
TEST_TIME 72 9619
TEST_TIME 73 9495
TEST_TIME 74 9400
TEST_TIME 75 9348
TEST_TIME 76 9351
TEST_TIME 77 9312
TEST_TIME 78 9293
TEST_TIME 79 9381
TEST_TIME 80 9388
TEST_TIME 81 9337
TEST_TIME 82 9303
TEST_TIME 83 9323
TEST_TIME 84 9418
TEST_TIME 85 9464
TEST_TIME 86 9437
TEST_TIME 87 9448
TEST_TIME 88 9518
TEST_TIME 89 9504
TEST_TIME 90 9438
TEST_TIME 91 9343
TEST_TIME 92 9366
TEST_TIME 93 9287
TEST_TIME 94 9218
TEST_TIME 95 9218
TEST_TIME 96 9188
TEST_TIME 97 9165
TEST_TIME 98 9162
TEST_TIME 99 9164
TEST_TIME 100 9195
TEST_TIME 101 9167
TEST_TIME 102 9150
TEST_TIME 103 9202
TEST_TIME 104 9221
TEST_TIME 105 9222
TEST_TIME 106 9304
TEST_TIME 107 9249
TEST_TIME 108 9224
TEST_TIME 109 9205
TEST_TIME 110 9182
TEST_TIME 111 9186
TEST_TIME 112 9159
TEST_TIME 113 9153
TEST_TIME 114 9209
TEST_TIME 115 9210
TEST_TIME 116 9219
TEST_TIME 117 9215
TEST_TIME 118 9240
TEST_TIME 119 9231
TEST_TIME 120 9218

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2021 07:29:41 to 06-01-2021 07:29:45.

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 1535403 1535403 0 0.00 0 13596 6 0 0
doc_list_biud 1535403 1535403 0 0.00 0 46099 31 0 0
sp_add_invoice_to_stock 5095 5016 79 1.55 43 192201 17756 0 79
sp_cancel_adding_invoice 5671 5671 0 0.00 5 62192 896 0 0
sp_cancel_client_order 1752 1752 0 0.00 5 26736 340 0 0
sp_cancel_customer_reserve 557217 557217 0 0.00 1 46101 67 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 3386 85 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 5456 103 0 0
sp_cancel_supplier_invoice 6318 6313 5 0.07 1 20077 877 0 5
sp_cancel_supplier_order 423 423 0 0.00 8 1304033 111374 0 0
sp_cancel_write_off 9823 9823 0 0.00 1 10982 125 0 0
sp_client_order 19963 19963 0 0.00 4 39730 444 0 0
sp_customer_reserve 463449 463449 0 0.00 0 133401 175 0 0
sp_fill_shopping_cart 31175 31175 0 0.00 1 16997 38 0 0
sp_fill_shopping_cart_clo_res 463448 463448 0 0.00 0 24836 19 0 0
sp_get_clo_for_invoice 5015 5015 0 0.00 0 37249 333 0 0
sp_kill_cost_storno 16568 16568 0 0.00 0 12105 59 0 0
sp_kill_qstorno_ret_qs2qd 1130993 1130993 0 0.00 0 46096 36 0 0
sp_kill_qty_storno 596098 596098 0 0.00 0 46099 78 0 0
sp_lock_selected_doc 20696 20696 0 0.00 0 7931 5 0 0
sp_make_cost_storno 27956 27956 0 0.00 0 13547 46 0 0
sp_multiply_rows_for_pdistr 27956 27956 0 0.00 0 6379 30 0 0
sp_multiply_rows_for_qdistr 19963 19963 0 0.00 0 21085 248 0 0
sp_pay_from_customer 7080 7080 0 0.00 4 23062 112 0 0
sp_pay_to_supplier 5977 5977 0 0.00 5 24652 119 0 0
sp_payment_common 13057 13057 0 0.00 1 23050 20 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 26612 307 0 0
sp_qd_handle_on_invoice_upd_sts 10685 10685 0 0.00 0 44254 378 0 0
sp_qd_handle_on_reserve_upd_sts 9885 9885 0 0.00 0 26198 51 0 0
sp_reserve_write_off 9885 9885 0 0.00 6 26429 162 0 0
sp_supplier_invoice 5657 5657 0 0.00 6 127812 3173 0 0
sp_supplier_order 5586 5586 0 0.00 5 51275 1337 0 0
srv_aggregate_perf_data 99 99 0 0.00 2786 265740 15010 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2380 181851 50163 0 0
srv_find_qd_qs_mism 1058698 1058698 0 0.00 0 11583 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 57 107356 3038 0 0
srv_make_money_saldo 730 730 0 0.00 1 12352 524 0 0
srv_recalc_idx_stat 5 5 0 0.00 149442 586565 367266 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5016 5016 0 0.00 0 17348 170 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 7262 98 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 4746 13 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1639 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 558 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 327 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 4102 23 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 19785 1450 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 9102 16 0 0
t$perf-norm:sp_client_order 19963 19963 0 0.00 0 14305 15 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 1508 7 0 0
t$perf-norm:sp_pay_from_customer 7080 7080 0 0.00 0 3747 6 0 0
t$perf-norm:sp_pay_to_supplier 5977 5977 0 0.00 0 8736 10 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 8756 10 0 0
t$perf-norm:sp_supplier_invoice 5657 5657 0 0.00 0 22608 26 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 5205 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 99 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 634 19 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 528 10 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 2698 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 51 13 0 0
v_add_invoice_to_stock 10178 10020 158 1.55 0 4344 15 0 158
v_all_customers 36598 36598 0 0.00 0 19 1 0 0
v_all_suppliers 31386 31386 0 0.00 0 20 1 0 0
v_all_wares 39926 39926 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 284 6 0 0
v_cancel_client_order 3504 3504 0 0.00 0 3016 6 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 3098 10 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1656 6 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 310 4 0 0
v_cancel_supplier_invoice 858 848 10 1.16 0 1888 18 0 10
v_cancel_supplier_order 846 846 0 0.00 0 1201 9 0 0
v_cancel_write_off 2048 2048 0 0.00 0 10741 25 0 0
v_max_id_clo_ord 5579 5579 0 0.00 0 1803 2 0 0
v_max_id_clo_res 967 967 0 0.00 0 4845 25 0 0
v_max_id_ord_sup 5633 5633 0 0.00 0 4497 4 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 789 2 0 0
v_max_non_paid_realizn 3780 3780 0 0.00 0 2309 4 0 0
v_min_id_clo_ord 5579 5579 0 0.00 0 1792 6 0 0
v_min_id_clo_res 967 967 0 0.00 0 18985 26 0 0
v_min_id_ord_sup 5633 5633 0 0.00 0 4236 7 0 0
v_min_non_paid_invoice 5977 5977 0 0.00 0 5545 9 0 0
v_min_non_paid_realizn 7080 7080 0 0.00 0 23043 16 0 0
v_our_firm 9928 9928 0 0.00 0 12 1 0 0
v_reserve_write_off 19770 19770 0 0.00 0 4393 7 0 0
x_lock_depdocs_on_canc_invoice 11560 11560 0 0.00 0 25979 116 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 18204 427 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 2 51265 1264 0 0
x_make_qstorno_1200_2000 5657 5657 0 0.00 3 117722 3028 0 0
x_make_qstorno_2100_3300 463449 463449 0 0.00 0 133399 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2021 07:29:46 to 06-01-2021 07:29:50.

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:57 6 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
04:57:57 35153 8192000000 9734394560 10033754112 809541664 8.993 100 85 170 1 837958368 7600816 20815888
04:59:42 20410 8192000000 9766170800 10074009600 842170640 9.322 100 90 180 0 878041088 14701520 21168928
05:01:07 19467 8192000000 9810176976 10129154048 865626864 9.556 100 82 164 0 918242048 33372416 19242768
05:02:33 16844 8192000000 9875388000 10207498240 914534368 10.042 100 78 154 3 980149424 48650672 16964384
05:03:56 7822 8192000000 9929867888 10279948288 963357824 10.522 100 70 137 1 1045700976 68084880 14258272
05:05:07 4426 8192000000 9962757504 10318163968 1002680928 10.905 100 78 152 3 1068586080 50357648 15547504
05:06:14 8973 8192000000 10004193040 10373427200 1026196400 11.132 100 70 140 1 1108158032 68297744 13663888
05:07:25 1764 8192000000 10034304112 10402365440 1054044944 11.399 100 89 176 3 1139923936 68009872 17869120
05:08:28 27468 8192000000 10021423776 10399137792 1072749328 11.578 100 66 128 2 1143206736 57157648 13299760
05:09:57 3048 8192000000 9990930624 10377424896 1075771904 11.607 100 48 95 0 1097198432 11126064 10300464
05:11:01 8409 8192000000 10001332320 10394402816 1084751024 11.693 100 37 74 0 1116750848 24553376 7446448
05:12:11 44006 8192000000 10014370752 10406199296 1089361872 11.737 100 51 101 1 1113802336 13157904 11282560
05:13:56 8555 8192000000 9996578816 10392985600 1078391200 11.632 99 42 84 2 1107926464 20067040 9468224
05:15:05 9404 8192000000 9992241488 10406338560 1092642544 11.768 100 31 63 1 1110929152 10657712 7628896
05:16:17 7094 8192000000 10003761056 10398851072 1095734560 11.797 100 41 83 3 1112336496 7858304 8743632
05:17:27 30393 8192000000 9990871984 10399887360 1048668112 11.348 96 17 34 2 1076060144 22878624 4513408
05:19:00 8429 8192000000 9967104592 10361925632 1048774688 11.349 100 43 85 4 1078988624 20534960 9678976
05:20:10 7371 8192000000 9942852176 10324508672 1004331936 10.921 97 38 76 5 1037829248 25554992 7942320
05:21:19 66081 8192000000 9911962640 10308120576 962293760 10.511 93 36 72 1 999822032 28676016 8852256
05:23:27 6106 8192000000 9967307456 10367451136 1045955872 11.322 100 44 88 2 1070639408 14381296 10302240
05:24:34 6623 8192000000 9983107984 10377715712 1061463328 11.470 100 42 82 2 1082477136 11185264 9828544
05:25:48 5273 8192000000 9977360080 10368819200 1064353920 11.498 100 42 84 1 1084764080 10862320 9547840
05:26:55 69822 8192000000 10001153264 10398859264 1070730336 11.559 100 36 71 2 1097189968 19027856 7431776
05:29:13 29625 8192000000 10057681648 10460151808 1098192800 11.820 100 45 90 2 1157692432 50331728 9167904
05:30:54 25804 8192000000 10107973936 10513170432 1114063488 11.971 100 56 112 1 1215413776 88769328 12580960
05:32:34 14111 8192000000 10159535024 10561638400 1131968944 12.140 100 57 115 1 1264196800 118726704 13501152
05:33:54 8299 8192000000 10157993696 10565320704 1143454032 12.248 100 48 94 4 1263390560 108663168 11273360
05:35:06 16225 8192000000 10198292496 10612379648 1142145584 12.236 100 53 105 2 1293967648 140349680 11472384
05:36:30 2961 8192000000 10249371776 10653786112 1163369776 12.435 100 74 145 5 1354499328 174984928 16144624
05:37:38 3026 8192000000 10289043648 10694975488 1174514640 12.539 100 69 135 0 1391007264 201156128 15336496
05:38:47 2699 8192000000 10309358368 10714980352 1170279280 12.499 99 78 150 5 1410085168 223913616 15892272
05:39:55 3488 8192000000 10334022256 10740539392 1183822784 12.626 100 79 155 2 1438638496 237266944 17548768
05:41:05 4565 8192000000 10336936592 10779762688 1190561376 12.689 100 69 136 1 1455224080 248659776 16002928
05:42:15 2902 8192000000 10381901520 10783207424 1188224720 12.667 100 87 171 0 1480036432 272389216 19422496
05:43:24 2380 8192000000 10416332288 10827587584 1163212000 12.433 99 70 141 1 1511059440 330889856 16957584
05:44:31 3129 8192000000 10424548736 10848432128 1159855280 12.402 100 85 170 1 1521651664 341253520 20542864
05:45:38 18665 8192000000 10415157824 10868424704 1164917136 12.449 100 41 83 1 1545880288 370278704 10684448
05:47:03 11116 8192000000 10428641744 10860482560 1142560752 12.240 100 39 78 2 1527542864 374454544 10527568
05:48:21 7697 8192000000 10346736112 10776727552 1134453696 12.163 100 43 80 0 1449233632 305399376 9380560
05:49:34 7268 8192000000 10367225232 10811621376 1149170928 12.302 100 37 73 1 1471705056 313439664 9094464
05:51:18 27440 8192000000 10335728672 10826153984 1135364352 12.172 99 24 48 1 1446999776 305323488 6311936
05:52:47 51815 8192000000 10087556400 10571075584 1129656976 12.118 98 10 20 1 1184584736 53143360 1784400
05:54:57 110049 8192000000 10063460160 10487214080 1029765744 11.166 91 18 36 0 1065531024 32372944 3392336
05:57:48 104263 8192000000 10060895120 10551275520 1107398640 11.908 100 24 48 1 1161136416 48068464 5669312
06:00:37 44609 8192000000 10092517056 10529861632 1119695632 12.024 99 31 61 0 1188200416 61590400 6914384
06:02:42 45881 8192000000 10052089456 10518163456 1106483328 11.899 98 14 26 1 1156455904 47060320 2912256
06:05:17 71900 8192000000 10051866752 10529714176 1093995424 11.781 98 16 32 1 1158053888 60770496 3287968
06:09:07 52644 8192000000 10034329120 10534297600 1099702560 11.835 98 11 22 1 1166940176 65134480 2103136
06:11:00 67479 8192000000 10083873744 10518695936 1088402704 11.727 97 20 40 1 1147003392 54556496 4044192
06:13:08 26499 8192000000 10094107824 10547228672 1131237696 12.133 100 25 50 0 1200876080 63777280 5861104
06:14:37 50740 8192000000 10139410720 10579415040 1132166816 12.142 99 17 34 2 1230586464 94827568 3592080
06:16:36 59055 8192000000 10096876576 10529259520 1146460864 12.276 100 17 34 0 1183987200 34192064 3334272
06:18:36 27639 8192000000 10137537168 10594893824 1130437872 12.125 99 32 64 2 1220818304 83938800 6441632
06:20:04 105123 8192000000 10065326240 10526019584 1063851920 11.493 93 17 34 2 1099711664 32748128 3111616
06:22:49 115139 8192000000 10092784640 10538721280 1103641280 11.872 97 28 56 1 1157766208 48261056 5863872
06:25:47 66740 8192000000 10115301440 10603335680 1126792272 12.091 99 8 16 0 1186272256 57637888 1842096
06:27:54 57651 8192000000 10150869920 10636349440 1123671376 12.062 99 12 24 0 1247294768 120905392 2718000
06:29:52 40050 8192000000 10076849168 10517204992 1114684448 11.977 98 18 36 0 1162712480 44324544 3703488
06:31:38 62454 8192000000 10083336240 10520743936 1061781264 11.474 93 32 64 1 1141241120 73023504 6436352
06:33:54 71034 8192000000 10096611936 10560507904 1102125664 11.858 98 16 32 1 1195431040 89501648 3803728
06:36:05 39584 8192000000 10050191744 10461528064 1083527552 11.681 96 52 100 4 1105832560 11635344 10669664
06:37:45 181851 8192000000 10089553440 10532573184 1110460128 11.937 98 17 34 0 1183223392 69391968 3371296
06:42:09 30621 8192000000 10053384112 10547499008 1112649664 11.957 98 5 10 0 1172531904 59027360 854880
06:44:11 116017 8192000000 10080576384 10553638912 1112117952 11.952 98 16 32 2 1173966544 58888240 2960352
06:47:10 59297 8192000000 10107570000 10528788480 1130383664 12.125 100 27 53 0 1159546384 23914096 5248624
06:49:10 72575 8192000000 10161348688 10658381824 1119441344 12.022 98 13 26 0 1251139536 128277840 3420352
06:52:17 24813 8192000000 10095293200 10522329088 1122896752 12.054 99 56 112 3 1182536752 47608640 12031360
06:53:48 91480 8192000000 10063958224 10551984128 1063170400 11.487 94 11 22 1 1099130688 33962144 1998144
06:56:21 24499 8192000000 10095663856 10518798336 1134694480 12.166 100 65 129 0 1195188464 47308320 13185664
06:57:54 83466 8192000000 10068265600 10554908672 1124601392 12.070 99 21 42 1 1178919744 49739504 4578848
07:00:22 71987 8192000000 10064452112 10530181120 1108191040 11.915 97 11 22 0 1157683280 46970224 2522016
07:02:38 31728 8192000000 10060506848 10500780032 1112035040 11.952 98 23 46 1 1139908192 22276432 5596720
07:04:10 45908 8192000000 10085922384 10522468352 1115324448 11.983 98 27 54 0 1178711824 57387632 5999744
07:06:05 54329 8192000000 10093467296 10540601344 1095592240 11.796 97 20 40 0 1140583760 41215392 3776128
07:08:08 57034 8192000000 10154216272 10595217408 1095734928 11.797 96 31 62 2 1210999248 108699344 6564976
07:10:09 89089 8192000000 10159758816 10626248704 1093669728 11.778 96 16 32 2 1205316368 108444208 3202432
07:12:42 28597 8192000000 10060776368 10482647040 1092904256 11.770 97 33 66 1 1121730432 22352624 6473552
07:14:14 74690 8192000000 10038096400 10484686848 1086235520 11.707 97 11 22 0 1125109200 36849360 2024320
07:16:30 87144 8192000000 10079499760 10557968384 1095299264 11.793 97 30 56 2 1118600352 17016496 6284592
07:18:58 20912 8192000000 10079327216 10522693632 1112131968 11.953 99 44 86 1 1166587920 44989168 9466784
07:20:34 148927 8192000000 10082812112 10589884416 1080606192 11.653 96 10 20 1 1156867824 74329248 1932384
07:24:04 91573 8192000000 10080314320 10585276416 1095600688 11.796 97 9 18 0 1135496528 38277264 1618576
07:26:38 41757 8192000000 9227824960 9544171520 360709472 4.217 32 4 6 1 401691024 40099696 881856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-01-2021 07:29:51 to 06-01-2021 07:30:06.

Exceptions occured during test was in run

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

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

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          236267
MON$OLDEST_ACTIVE               236268
MON$OLDEST_SNAPSHOT             236268
MON$NEXT_TRANSACTION            236268
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                       2096704
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             798
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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Wed Jan  6 07:27:26 2021
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
--- end of diff output ---

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

06.01.21 07:30:10. 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-06 07:30:13 No severe PSQL-related problems occured
06.01.21 07:30:14. End of report.