Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2021 19: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 16222004 kB
MemAvailable 31009932 kB
Buffers 0 kB
Cached 15442512 kB
SwapCached 2584 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-08-10 19:37:41
DTS_END                         2021-08-10 19:37:41.9740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38714
TRN_ID                          234185
ATT_ID                          668
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: 9212.79 23677 1105535
customer order: creation 164.04 397 19685
customer order: refuse 14.01 410 1681
order to supplier: creation 42.99 1292 5159
order to supplier: removal 3.40 108992 408
invoice (draft): creation 43.82 3688 5258
invoice (draft): removal 55.17 831 6620
invoice accept: apply 39.51 19491 4741
invoice accept: cancel 49.42 841 5930
customer reserve: creation 3696.88 190 443625
customer reserve: removal 4812.04 60 577445
realization accept: apply 79.56 192 9547
realization accept: cancel 88.08 109 10569
payment from customer: creation 57.07 120 6848
payment from customer: removal 5.61 106 673
payment to supplier: creation 46.18 127 5541
payment to supplier: removal 3.58 76 430
service: total inventory turnovers 4.52 1132 542
service: total monetary turnovers 6.16 28 739
service: aggregate perf. data 0.75 24426 90
service: refresh index statistics 0.03 311028 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-08-2021 19:39:07 to 10-08-2021 19:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2734
TEST_TIME 2 3633
TEST_TIME 3 4232
TEST_TIME 4 4381
TEST_TIME 5 5010
TEST_TIME 6 5042
TEST_TIME 7 4875
TEST_TIME 8 5340
TEST_TIME 9 5537
TEST_TIME 10 5532
TEST_TIME 11 5808
TEST_TIME 12 6122
TEST_TIME 13 6451
TEST_TIME 14 6758
TEST_TIME 15 6763
TEST_TIME 16 6666
TEST_TIME 17 6958
TEST_TIME 18 7078
TEST_TIME 19 7455
TEST_TIME 20 7742
TEST_TIME 21 7845
TEST_TIME 22 7983
TEST_TIME 23 8317
TEST_TIME 24 8575
TEST_TIME 25 9255
TEST_TIME 26 9589
TEST_TIME 27 9995
TEST_TIME 28 10192
TEST_TIME 29 10236
TEST_TIME 30 10524
TEST_TIME 31 10301
TEST_TIME 32 10327
TEST_TIME 33 10254
TEST_TIME 34 10093
TEST_TIME 35 10049
TEST_TIME 36 9919
TEST_TIME 37 9814
TEST_TIME 38 9688
TEST_TIME 39 9927
TEST_TIME 40 9833
TEST_TIME 41 9706
TEST_TIME 42 9616
TEST_TIME 43 9489
TEST_TIME 44 9313
TEST_TIME 45 9158
TEST_TIME 46 9302
TEST_TIME 47 9373
TEST_TIME 48 9384
TEST_TIME 49 9496
TEST_TIME 50 9454
TEST_TIME 51 9356
TEST_TIME 52 9415
TEST_TIME 53 9361
TEST_TIME 54 9300
TEST_TIME 55 9392
TEST_TIME 56 9599
TEST_TIME 57 9539
TEST_TIME 58 9597
TEST_TIME 59 9669
TEST_TIME 60 9646
TEST_TIME 61 9581
TEST_TIME 62 9485
TEST_TIME 63 9464
TEST_TIME 64 9371
TEST_TIME 65 9334
TEST_TIME 66 9289
TEST_TIME 67 9245
TEST_TIME 68 9293
TEST_TIME 69 9236
TEST_TIME 70 9206
TEST_TIME 71 9145
TEST_TIME 72 9061
TEST_TIME 73 9057
TEST_TIME 74 9060
TEST_TIME 75 9039
TEST_TIME 76 9013
TEST_TIME 77 9022
TEST_TIME 78 9085
TEST_TIME 79 9104
TEST_TIME 80 9186
TEST_TIME 81 9114
TEST_TIME 82 9133
TEST_TIME 83 9297
TEST_TIME 84 9315
TEST_TIME 85 9339
TEST_TIME 86 9301
TEST_TIME 87 9322
TEST_TIME 88 9242
TEST_TIME 89 9210
TEST_TIME 90 9189
TEST_TIME 91 9194
TEST_TIME 92 9140
TEST_TIME 93 9079
TEST_TIME 94 9070
TEST_TIME 95 9166
TEST_TIME 96 9197
TEST_TIME 97 9158
TEST_TIME 98 9131
TEST_TIME 99 9218
TEST_TIME 100 9297
TEST_TIME 101 9282
TEST_TIME 102 9276
TEST_TIME 103 9341
TEST_TIME 104 9323
TEST_TIME 105 9348
TEST_TIME 106 9362
TEST_TIME 107 9401
TEST_TIME 108 9381
TEST_TIME 109 9298
TEST_TIME 110 9301
TEST_TIME 111 9276
TEST_TIME 112 9275
TEST_TIME 113 9294
TEST_TIME 114 9286
TEST_TIME 115 9253
TEST_TIME 116 9213
TEST_TIME 117 9188
TEST_TIME 118 9190
TEST_TIME 119 9203
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-08-2021 19:39:10 to 10-08-2021 19:39:14.

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 1516826 1516826 0 0.00 0 22715 6 0 0
doc_list_biud 1516826 1516826 0 0.00 0 32552 30 0 0
sp_add_invoice_to_stock 4800 4741 59 1.22 34 181389 19491 0 59
sp_cancel_adding_invoice 5930 5930 0 0.00 5 72653 841 0 0
sp_cancel_client_order 1681 1681 0 0.00 4 13632 411 0 0
sp_cancel_customer_reserve 577445 577445 0 0.00 1 21081 61 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 5930 106 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5061 76 0 0
sp_cancel_supplier_invoice 6627 6620 7 0.10 2 32560 831 0 7
sp_cancel_supplier_order 408 408 0 0.00 9 1160060 108992 0 0
sp_cancel_write_off 10569 10569 0 0.00 1 15498 110 0 0
sp_client_order 19685 19685 0 0.00 3 34540 398 0 0
sp_customer_reserve 443625 443625 0 0.00 0 75238 191 0 0
sp_fill_shopping_cart 30092 30092 0 0.00 1 12509 36 0 0
sp_fill_shopping_cart_clo_res 443623 443623 0 0.00 0 11508 13 0 0
sp_get_clo_for_invoice 4738 4738 0 0.00 0 28441 293 0 0
sp_kill_cost_storno 17602 17602 0 0.00 0 6034 53 0 0
sp_kill_qstorno_ret_qs2qd 1172487 1172487 0 0.00 0 32549 33 0 0
sp_kill_qty_storno 616938 616938 0 0.00 0 32551 72 0 0
sp_lock_selected_doc 19840 19840 0 0.00 0 7573 5 0 0
sp_make_cost_storno 26674 26674 0 0.00 0 15689 51 0 0
sp_multiply_rows_for_pdistr 26674 26674 0 0.00 0 12161 33 0 0
sp_multiply_rows_for_qdistr 19685 19685 0 0.00 0 15226 177 0 0
sp_pay_from_customer 6848 6848 0 0.00 4 21790 120 0 0
sp_pay_to_supplier 5541 5541 0 0.00 5 11678 127 0 0
sp_payment_common 12389 12389 0 0.00 2 21748 25 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 12485 381 0 0
sp_qd_handle_on_invoice_upd_sts 10668 10668 0 0.00 0 17680 362 0 0
sp_qd_handle_on_reserve_upd_sts 9547 9547 0 0.00 0 20831 68 0 0
sp_reserve_write_off 9547 9547 0 0.00 7 20852 193 0 0
sp_supplier_invoice 5258 5258 0 0.00 6 71606 3688 0 0
sp_supplier_order 5159 5159 0 0.00 5 23228 1292 0 0
srv_aggregate_perf_data 90 90 0 0.00 2601 662969 24426 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2112 146781 40457 0 0
srv_find_qd_qs_mism 1060141 1060141 0 0.00 0 22714 5 0 0
srv_make_invnt_saldo 542 542 0 0.00 49 38284 1132 0 0
srv_make_money_saldo 739 739 0 0.00 1 1289 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 172871 487054 311028 0 0
t$perf-abend:sp_add_invoice_to_stock 59 59 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4741 4741 0 0.00 0 14510 199 0 0
t$perf-norm:sp_cancel_adding_invoice 353 353 0 0.00 0 11899 162 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 4398 13 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 12873 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 13346 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1947 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 3775 32 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 20005 1720 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 10090 17 0 0
t$perf-norm:sp_client_order 19685 19685 0 0.00 0 13695 21 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 3691 11 0 0
t$perf-norm:sp_pay_from_customer 6848 6848 0 0.00 0 5982 9 0 0
t$perf-norm:sp_pay_to_supplier 5541 5541 0 0.00 0 5509 9 0 0
t$perf-norm:sp_reserve_write_off 9547 9547 0 0.00 0 15368 15 0 0
t$perf-norm:sp_supplier_invoice 5258 5258 0 0.00 0 12592 25 0 0
t$perf-norm:sp_supplier_order 5159 5159 0 0.00 0 10190 20 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 1958 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 140 5 0 0
t$perf-norm:srv_make_invnt_saldo 542 542 0 0.00 0 2325 22 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 965 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9592 9474 118 1.23 0 4579 18 0 118
v_all_customers 36038 36038 0 0.00 0 18 1 0 0
v_all_suppliers 28686 28686 0 0.00 0 30 1 0 0
v_all_wares 39370 39370 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 706 706 0 0.00 0 1084 7 0 0
v_cancel_client_order 3362 3362 0 0.00 0 2844 7 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 4440 17 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 1108 5 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 1765 6 0 0
v_cancel_supplier_invoice 790 776 14 1.77 0 2686 19 0 14
v_cancel_supplier_order 816 816 0 0.00 0 5447 14 0 0
v_cancel_write_off 1922 1922 0 0.00 0 5911 21 0 0
v_max_id_clo_ord 5159 5159 0 0.00 0 752 2 0 0
v_max_id_clo_res 982 982 0 0.00 0 1532 10 0 0
v_max_id_ord_sup 5248 5248 0 0.00 0 3326 3 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 325 2 0 0
v_max_non_paid_realizn 3639 3639 0 0.00 0 6961 5 0 0
v_min_id_clo_ord 5159 5159 0 0.00 0 4199 7 0 0
v_min_id_clo_res 982 982 0 0.00 0 775 2 0 0
v_min_id_ord_sup 5248 5248 0 0.00 0 2458 3 0 0
v_min_non_paid_invoice 5541 5541 0 0.00 0 9141 14 0 0
v_min_non_paid_realizn 6848 6848 0 0.00 0 21745 17 0 0
v_our_firm 9750 9750 0 0.00 0 14 1 0 0
v_reserve_write_off 19094 19094 0 0.00 0 4300 7 0 0
x_lock_depdocs_on_canc_invoice 12162 12162 0 0.00 0 18714 118 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 22400 426 0 0
x_make_qstorno_1000_1200 5159 5159 0 0.00 1 23218 1210 0 0
x_make_qstorno_1200_2000 5258 5258 0 0.00 2 66670 3557 0 0
x_make_qstorno_2100_3300 443625 443625 0 0.00 0 75234 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2021 19:39:15 to 10-08-2021 19:39:19.

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
17:07:45 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:46 17254 8192000000 10371946016 10688176128 1430905280 14.869 100 90 180 0 1465700416 5513440 29281696
17:10:10 24535 8192000000 10431935392 10762338304 1484983072 15.345 100 85 170 1 1520813808 8243744 27586992
17:11:41 24164 8192000000 10509972592 10858016768 1562055360 16.014 100 82 163 1 1597266384 9495280 25715744
17:13:09 13812 8192000000 10587269168 10944815104 1644660192 16.719 100 80 160 1 1678358944 10240192 23458560
17:14:29 13567 8192000000 10662495216 11029426176 1719961776 17.352 100 87 174 1 1758804400 14013008 24829616
17:15:46 4947 8192000000 10695227856 11069784064 1764122960 17.718 100 80 160 1 1799609872 12600560 22886352
17:16:53 2736 8192000000 10744224800 11125506048 1806441744 18.067 100 83 166 1 1843005072 13455472 23107856
17:17:57 2230 8192000000 10796036832 11182448640 1853208352 18.448 100 94 188 1 1893102480 14508704 25385424
17:19:01 11365 8192000000 10811112368 11203121152 1872307968 18.603 100 82 165 2 1906859920 11643664 22908288
17:20:14 5511 8192000000 10799073680 11212029952 1877285264 18.643 100 38 75 0 1895522288 8098464 10138560
17:21:20 3450 8192000000 10797455008 11224604672 1888659376 18.735 100 31 62 2 1904626256 6998560 8968320
17:22:25 13020 8192000000 10806537344 11231719424 1894893424 18.785 100 19 37 3 1905467008 5421744 5151840
17:23:40 58803 8192000000 10830714464 11253485568 1905107392 18.867 100 29 58 2 1918378912 5398112 7873408
17:25:41 4141 8192000000 10832079696 11255193600 1895174960 18.787 99 37 73 4 1913043072 5931952 11936160
17:26:51 7287 8192000000 10817387392 11251101696 1890799472 18.752 99 29 58 4 1905664576 6304592 8560512
17:28:02 16937 8192000000 10783160976 11204321280 1833317200 18.286 97 17 32 1 1843564512 6030816 4216496
17:29:21 10583 8192000000 10769899328 11203649536 1848100688 18.407 98 31 62 1 1863751360 6608272 9042400
17:30:33 6702 8192000000 10722238352 11141156864 1768336080 17.753 97 37 75 0 1785896496 6624880 10935536
17:31:41 11174 8192000000 10698415008 11109228544 1720032832 17.352 96 34 68 2 1735536592 5309152 10194608
17:32:53 37751 8192000000 10714674640 11146723328 1815198480 18.138 100 19 36 2 1825928544 5259024 5471040
17:34:31 7013 8192000000 10756152432 11179249664 1840474768 18.345 100 38 74 2 1857785984 5721264 11589952
17:35:40 7231 8192000000 10775808608 11199021056 1855876816 18.470 100 35 71 0 1871522800 5897712 9748272
17:36:51 6710 8192000000 10784452944 11212120064 1867882896 18.567 100 20 40 2 1879377600 5546304 5948400
17:38:01 34750 8192000000 10812084656 11237138432 1891832128 18.761 100 46 93 1 1910813056 5011904 13969024
17:39:45 5786 8192000000 10847234464 11269132288 1916291152 18.957 100 47 94 1 1941690272 11220000 14179120
17:40:55 6367 8192000000 10875377824 11301445632 1936227200 19.117 100 51 101 0 1965185232 14353248 14604784
17:42:06 11145 8192000000 10889558912 11320438784 1950694832 19.232 100 54 106 1 1983931440 17708176 15528432
17:43:22 12399 8192000000 10903823328 11339169792 1969690880 19.383 100 47 94 0 2000838352 17324480 13822992
17:44:38 11959 8192000000 10918154064 11345362944 1973844992 19.416 100 57 114 1 2011271888 20914176 16512720
17:45:55 6748 8192000000 10954357680 11364507648 1986807888 19.519 100 72 143 5 2029229808 21807120 20614800
17:47:16 4067 8192000000 10948895584 11372859392 1992321376 19.562 100 57 111 1 2034060320 24464192 17274752
17:48:24 8884 8192000000 10949478256 11374845952 1972038160 19.402 99 68 137 1 2024646256 31969872 20638224
17:49:37 4335 8192000000 10963525472 11390889984 1997013792 19.599 100 73 145 2 2053263616 33015808 23234016
17:50:44 2285 8192000000 10968539712 11392909312 1998645792 19.612 100 63 126 0 2051833472 33512784 19674896
17:51:52 2497 8192000000 10959636832 11387060224 1989707584 19.541 100 69 138 0 2047133360 36733216 20692560
17:53:04 7071 8192000000 10956376672 11383152640 1961827456 19.321 98 69 138 0 2021369648 38618816 20923376
17:54:28 2112 8192000000 10985914016 11408498688 2000466784 19.626 100 81 161 1 2068481184 42780880 25233520
17:55:33 3727 8192000000 10995313712 11415408640 2007431744 19.681 100 75 151 0 2073506640 43136432 22938464
17:56:42 3094 8192000000 10915752064 11333672960 1930580176 19.072 99 64 128 2 1995017984 44313056 20124752
17:57:52 5389 8192000000 10920379872 11351093248 1949339472 19.221 100 48 95 0 2005059024 40570928 15148624
17:59:00 9868 8192000000 10917363728 11342032896 1923830064 19.018 99 53 106 4 1986328448 44899408 17598976
18:00:18 9344 8192000000 10939320384 11357417472 1941648016 19.160 100 59 114 0 2011896736 51403136 18845584
18:01:31 6664 8192000000 10908973088 11326459904 1920750000 18.993 99 61 120 1 1985008752 45041904 19216848
18:02:41 14792 8192000000 10892417408 11326369792 1913333200 18.933 99 33 66 0 1959783008 35142192 11307616
18:04:03 10372 8192000000 10897738768 11346186240 1931855248 19.082 100 27 52 2 1976951408 36368848 8727312
18:05:34 42288 8192000000 10887490416 11348049920 1923604848 19.016 99 10 20 0 1955134256 28425232 3104176
18:07:30 85338 8192000000 10875988544 11323027456 1927743280 19.049 99 30 61 1 1948845488 12617424 8484784
18:10:00 40582 8192000000 10878784784 11321225216 1931771056 19.081 99 28 53 2 1945830400 7137952 6921392
18:11:43 27514 8192000000 10896601232 11333029888 1939020544 19.139 99 34 68 3 1955232784 6541792 9670448
18:13:13 5853 8192000000 10899508528 11329228800 1954009936 19.258 100 71 142 3 1981109712 6486048 20613728
18:14:20 37442 8192000000 10890888672 11337654272 1918953696 18.978 98 40 80 1 1945565424 14580784 12030944
18:16:05 45194 8192000000 10874180704 11346313216 1937112576 19.124 99 13 26 0 1958764640 17914992 3737072
18:18:25 17839 8192000000 10888031488 11352227840 1964361808 19.341 100 11 22 0 1976708304 9193824 3152672
18:19:44 62317 8192000000 10898892672 11367591936 1967494272 19.366 100 17 34 2 1982558064 9868960 5194832
18:21:46 81834 8192000000 10915535584 11380666368 1965228288 19.348 99 16 32 0 1994642016 24519360 4894368
18:24:11 42303 8192000000 10911156304 11372978176 1980545808 19.469 100 24 46 0 2001790112 13663680 7580624
18:26:29 82698 8192000000 10899068960 11381207040 1963545360 19.334 99 10 19 2 1979788288 13589760 2653168
18:29:25 98222 8192000000 10928865216 11395919872 1954013792 19.258 98 14 28 0 1975957024 17844224 4099008
18:32:06 82331 8192000000 10927388880 11417784320 1971614912 19.398 99 8 16 0 1995186816 21121152 2450752
18:34:28 25752 8192000000 10920356624 11373699072 1980389584 19.468 100 41 81 3 2000286464 8347760 11549120
18:35:56 39861 8192000000 10900599232 11379412992 1947912128 19.210 98 26 52 1 1969328768 14213664 7202976
18:37:53 35707 8192000000 10909175488 11349856256 1915283280 18.949 96 28 56 1 1932206912 9141824 7781808
18:39:28 70982 8192000000 10887851232 11346743296 1928811504 19.057 98 34 68 1 1946994976 8607936 9575536
18:41:43 37154 8192000000 10900826992 11345055744 1935673776 19.112 98 45 90 0 1962583312 14315952 12593584
18:43:28 57394 8192000000 10879591440 11362000896 1953028784 19.251 100 10 19 1 1972994992 17060512 2905696
18:46:45 68085 8192000000 10868906496 11344457728 1901099104 18.835 97 16 32 0 1913285024 8176976 4008944
18:49:00 88745 8192000000 10848883696 11318124544 1917888336 18.970 99 19 38 1 1940174064 17036912 5248816
18:51:37 64267 8192000000 10855154944 11338878976 1908472304 18.894 98 5 10 0 1922263184 12433408 1357472
18:53:48 46992 8192000000 10846390048 11316060160 1856035200 18.471 96 16 32 1 1872613440 11972592 4605648
18:55:45 46757 8192000000 10889296000 11336327168 1889832112 18.744 97 25 50 0 1909648416 12410576 7405728
18:57:34 74181 8192000000 10872485648 11337187328 1914989984 18.947 99 10 20 0 1926847824 9187904 2669936
18:59:52 49423 8192000000 10865925680 11350900736 1916321632 18.957 99 11 22 0 1940981200 20828752 3830816
19:01:43 62405 8192000000 10869498128 11329957888 1847579808 18.402 95 20 40 2 1862105008 9222016 5303184
19:03:52 29472 8192000000 10871161072 11336380416 1916142496 18.956 99 29 58 1 1937854560 13672896 8039168
19:05:24 93172 8192000000 10888072208 11352047616 1952693968 19.248 100 37 74 0 1973954288 10305584 10954736
19:08:05 65438 8192000000 10881938080 11370766336 1965685952 19.351 100 10 20 1 1982778720 14688272 2404496
19:10:11 47187 8192000000 10892943728 11368804352 1968174768 19.371 100 10 20 1 1984789792 13630352 2984672
19:12:00 83028 8192000000 10887858080 11386699776 1953282512 19.253 99 13 26 0 1974625664 17728144 3615008
19:14:28 54254 8192000000 10919272864 11388788736 1965719136 19.351 99 18 36 0 1982323296 11200432 5403728
19:16:29 53476 8192000000 10909915328 11412811776 1985286320 19.507 100 13 25 0 2005085984 15906432 3893232
19:18:25 29854 8192000000 10927032336 11393658880 1993074608 19.568 100 25 50 1 2009240992 9207712 6958672
19:19:55 36422 8192000000 10949039680 11425083392 2005774368 19.668 100 34 68 0 2031477136 16303904 9398864
19:21:47 20722 8192000000 10934660352 11384524800 1965165984 19.347 98 45 90 2 1986906432 9821152 11919296
19:23:26 146781 8192000000 10930890960 11408187392 1965544160 19.350 98 17 34 0 1985093712 14775696 4773856
19:26:58 51992 8192000000 10917276272 11395817472 1960784896 19.312 98 24 48 0 1977212640 9663936 6763808
19:28:58 14935 8192000000 10918620784 11394973696 1966682272 19.359 99 18 36 2 1991003744 19235056 5086416
19:30:48 60896 8192000000 10890368352 11380731904 1933151776 19.092 97 11 22 0 1951101904 13905088 4045040
19:32:50 110113 8192000000 10876618560 11361607680 1893953808 18.778 96 17 33 1 1913939776 14953264 5032704
19:36:39 69254 8192000000 9781831504 10143715328 895760912 9.856 46 8 16 0 907486880 9611104 2114864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-08-2021 19:39:20 to 10-08-2021 19:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 59 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2021 19:39:36 to 10-08-2021 19:39:38.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          234460
MON$OLDEST_ACTIVE               234461
MON$OLDEST_SNAPSHOT             234461
MON$NEXT_TRANSACTION            234461
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-03 16:41:55.3470
MON$PAGES                       2039392
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Aug 10 19:37:53 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Tue Aug 10 19:37:53 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Tue Aug 10 19:37:53 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Tue Aug 10 19:37:53 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Tue Aug 10 19:37:54 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
--- end of diff output ---

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

10.08.21 19:39:39. There are 3 logs in /var/tmp/logs-oltp30 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-08-10 19:39:42 No severe PSQL-related problems occured
10.08.21 19:39:43. End of report.