Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.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 16044228 kB
MemAvailable 30839020 kB
Buffers 0 kB
Cached 15628768 kB
SwapCached 4900 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-10-26 19:37:56
DTS_END                         2021-10-26 19:37:56.1690
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/60356
TRN_ID                          243834
ATT_ID                          683
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: 9426.39 21563 1131167
customer order: creation 177.56 378 21307
customer order: refuse 14.78 358 1774
order to supplier: creation 46.89 1439 5627
order to supplier: removal 3.58 98017 429
invoice (draft): creation 47.94 3303 5753
invoice (draft): removal 54.84 790 6581
invoice accept: apply 42.00 17179 5040
invoice accept: cancel 49.64 704 5957
customer reserve: creation 3827.01 172 459241
customer reserve: removal 4862.88 56 583546
realization accept: apply 86.69 161 10403
realization accept: cancel 79.03 120 9484
payment from customer: creation 61.60 103 7392
payment from customer: removal 5.68 74 682
payment to supplier: creation 50.01 122 6001
payment to supplier: removal 3.66 71 439
service: total inventory turnovers 4.88 1073 585
service: total monetary turnovers 6.74 25 809
service: aggregate perf. data 0.94 15289 113
service: refresh index statistics 0.03 291834 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2021 19:38:48 to 26-10-2021 19:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1454
TEST_TIME 2 3194
TEST_TIME 3 3453
TEST_TIME 4 3732
TEST_TIME 5 4229
TEST_TIME 6 4525
TEST_TIME 7 4596
TEST_TIME 8 4583
TEST_TIME 9 4959
TEST_TIME 10 5224
TEST_TIME 11 5211
TEST_TIME 12 5513
TEST_TIME 13 5836
TEST_TIME 14 6171
TEST_TIME 15 6676
TEST_TIME 16 6889
TEST_TIME 17 7470
TEST_TIME 18 8127
TEST_TIME 19 8297
TEST_TIME 20 8601
TEST_TIME 21 8785
TEST_TIME 22 9023
TEST_TIME 23 9411
TEST_TIME 24 9700
TEST_TIME 25 9667
TEST_TIME 26 10329
TEST_TIME 27 10872
TEST_TIME 28 10614
TEST_TIME 29 10287
TEST_TIME 30 10149
TEST_TIME 31 10169
TEST_TIME 32 10320
TEST_TIME 33 10062
TEST_TIME 34 10128
TEST_TIME 35 9935
TEST_TIME 36 9926
TEST_TIME 37 10006
TEST_TIME 38 9940
TEST_TIME 39 9997
TEST_TIME 40 9924
TEST_TIME 41 9800
TEST_TIME 42 9719
TEST_TIME 43 9838
TEST_TIME 44 9681
TEST_TIME 45 9868
TEST_TIME 46 9822
TEST_TIME 47 10006
TEST_TIME 48 10031
TEST_TIME 49 9995
TEST_TIME 50 9974
TEST_TIME 51 9913
TEST_TIME 52 10017
TEST_TIME 53 10054
TEST_TIME 54 9977
TEST_TIME 55 10022
TEST_TIME 56 10066
TEST_TIME 57 9900
TEST_TIME 58 9822
TEST_TIME 59 9781
TEST_TIME 60 9783
TEST_TIME 61 9688
TEST_TIME 62 9638
TEST_TIME 63 9582
TEST_TIME 64 9576
TEST_TIME 65 9509
TEST_TIME 66 9510
TEST_TIME 67 9445
TEST_TIME 68 9473
TEST_TIME 69 9460
TEST_TIME 70 9492
TEST_TIME 71 9700
TEST_TIME 72 9713
TEST_TIME 73 9720
TEST_TIME 74 9747
TEST_TIME 75 9755
TEST_TIME 76 9708
TEST_TIME 77 9887
TEST_TIME 78 9865
TEST_TIME 79 9814
TEST_TIME 80 9760
TEST_TIME 81 9705
TEST_TIME 82 9659
TEST_TIME 83 9639
TEST_TIME 84 9691
TEST_TIME 85 9627
TEST_TIME 86 9622
TEST_TIME 87 9562
TEST_TIME 88 9514
TEST_TIME 89 9534
TEST_TIME 90 9506
TEST_TIME 91 9499
TEST_TIME 92 9527
TEST_TIME 93 9527
TEST_TIME 94 9590
TEST_TIME 95 9562
TEST_TIME 96 9550
TEST_TIME 97 9592
TEST_TIME 98 9681
TEST_TIME 99 9679
TEST_TIME 100 9637
TEST_TIME 101 9676
TEST_TIME 102 9693
TEST_TIME 103 9691
TEST_TIME 104 9683
TEST_TIME 105 9619
TEST_TIME 106 9577
TEST_TIME 107 9521
TEST_TIME 108 9551
TEST_TIME 109 9536
TEST_TIME 110 9490
TEST_TIME 111 9466
TEST_TIME 112 9435
TEST_TIME 113 9408
TEST_TIME 114 9400
TEST_TIME 115 9384
TEST_TIME 116 9383
TEST_TIME 117 9402
TEST_TIME 118 9419
TEST_TIME 119 9435
TEST_TIME 120 9436

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2021 19:38:52 to 26-10-2021 19:38:55.

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 1560047 1560047 0 0.00 0 13384 6 0 0
doc_list_biud 1560041 1560041 0 0.00 0 39443 27 0 0
sp_add_invoice_to_stock 5152 5040 112 2.17 48 171351 17180 0 112
sp_cancel_adding_invoice 5957 5957 0 0.00 4 61230 705 0 0
sp_cancel_client_order 1774 1774 0 0.00 4 15212 358 0 0
sp_cancel_customer_reserve 583546 583546 0 0.00 1 39445 57 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 3 4276 74 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 4816 72 0 0
sp_cancel_supplier_invoice 6583 6581 2 0.03 1 23212 790 0 2
sp_cancel_supplier_order 429 429 0 0.00 12 1021568 98017 0 0
sp_cancel_write_off 9484 9484 0 0.00 1 9833 121 0 0
sp_client_order 21307 21307 0 0.00 3 18854 379 0 0
sp_customer_reserve 459241 459241 0 0.00 0 51777 172 0 0
sp_fill_shopping_cart 32679 32679 0 0.00 1 12714 39 0 0
sp_fill_shopping_cart_clo_res 459231 459231 0 0.00 0 15730 13 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 38287 288 0 0
sp_kill_cost_storno 16562 16562 0 0.00 0 5654 55 0 0
sp_kill_qstorno_ret_qs2qd 1183586 1183586 0 0.00 0 39425 31 0 0
sp_kill_qty_storno 623193 623193 0 0.00 0 39443 67 0 0
sp_lock_selected_doc 21259 21259 0 0.00 0 6312 5 0 0
sp_make_cost_storno 28819 28819 0 0.00 0 13383 51 0 0
sp_multiply_rows_for_pdistr 28819 28819 0 0.00 0 6667 26 0 0
sp_multiply_rows_for_qdistr 21307 21307 0 0.00 0 10324 185 0 0
sp_pay_from_customer 7392 7392 0 0.00 4 13482 104 0 0
sp_pay_to_supplier 6001 6001 0 0.00 4 13001 123 0 0
sp_payment_common 13391 13391 0 0.00 1 9976 26 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 15206 336 0 0
sp_qd_handle_on_invoice_upd_sts 10979 10979 0 0.00 0 20868 316 0 0
sp_qd_handle_on_reserve_upd_sts 10400 10400 0 0.00 0 18076 57 0 0
sp_reserve_write_off 10403 10403 0 0.00 7 18092 161 0 0
sp_supplier_invoice 5753 5753 0 0.00 6 84089 3304 0 0
sp_supplier_order 5627 5627 0 0.00 5 39735 1439 0 0
srv_aggregate_perf_data 113 113 0 0.00 3163 166166 15290 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2123 136376 41356 0 0
srv_find_qd_qs_mism 1084367 1084367 0 0.00 0 12998 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 56 57495 1073 0 0
srv_make_money_saldo 809 809 0 0.00 0 1549 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 189837 382255 291834 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5041 5041 0 0.00 0 24086 177 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 8433 88 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 9963 18 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3392 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 452 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 2090 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 878 13 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 25713 1600 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 3533 9 0 0
t$perf-norm:sp_client_order 21307 21307 0 0.00 0 19802 22 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 2763 7 0 0
t$perf-norm:sp_pay_from_customer 7392 7392 0 0.00 0 18642 12 0 0
t$perf-norm:sp_pay_to_supplier 6001 6001 0 0.00 0 10299 11 0 0
t$perf-norm:sp_reserve_write_off 10403 10403 0 0.00 0 13218 11 0 0
t$perf-norm:sp_supplier_invoice 5753 5753 0 0.00 0 9888 20 0 0
t$perf-norm:sp_supplier_order 5627 5627 0 0.00 0 21929 23 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 469 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 6773 104 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 6805 36 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 3703 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10264 10040 224 2.18 0 3176 18 0 224
v_all_customers 38692 38692 0 0.00 0 146 1 0 0
v_all_suppliers 31486 31486 0 0.00 0 15 1 0 0
v_all_wares 42610 42610 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 237 6 0 0
v_cancel_client_order 3548 3548 0 0.00 0 2789 5 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 2493 5 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2010 5 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 2908 9 0 0
v_cancel_supplier_invoice 866 862 4 0.46 0 2238 18 0 4
v_cancel_supplier_order 858 858 0 0.00 0 2189 6 0 0
v_cancel_write_off 2010 2010 0 0.00 0 3363 15 0 0
v_max_id_clo_ord 5623 5623 0 0.00 0 1366 2 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 4856 24 0 0
v_max_id_ord_sup 5751 5751 0 0.00 0 7095 7 0 0
v_max_non_paid_invoice 1631 1631 0 0.00 0 2121 3 0 0
v_max_non_paid_realizn 3881 3881 0 0.00 0 5746 5 0 0
v_min_id_clo_ord 5623 5623 0 0.00 0 3013 5 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 8205 16 0 0
v_min_id_ord_sup 5751 5751 0 0.00 0 7436 4 0 0
v_min_non_paid_invoice 6000 6000 0 0.00 0 5815 19 0 0
v_min_non_paid_realizn 7391 7391 0 0.00 0 9972 19 0 0
v_our_firm 10938 10938 0 0.00 0 16 1 0 0
v_reserve_write_off 20798 20798 0 0.00 0 2701 5 0 0
x_lock_depdocs_on_canc_invoice 12107 12107 0 0.00 0 14420 111 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 5203 310 0 0
x_make_qstorno_1000_1200 5627 5627 0 0.00 2 39724 1357 0 0
x_make_qstorno_1200_2000 5753 5753 0 0.00 2 84080 3169 0 0
x_make_qstorno_2100_3300 459241 459241 0 0.00 0 51776 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2021 19:38:56 to 26-10-2021 19:39:00.

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:59 5 8192000000 8801503792 8813711360 7326784 0.089 3 1 2 0 7427744 58176 42784
17:09:01 19536 8192000000 9898030672 10209058816 980215712 10.686 100 84 168 0 1008402320 6200096 21986512
17:10:36 11258 8192000000 9937161264 10257502208 1022198976 11.093 100 94 188 0 1054683440 8618848 23865616
17:11:53 16122 8192000000 9962403440 10293096448 1050312528 11.364 100 82 163 1 1079611152 9396192 19902432
17:13:16 8038 8192000000 10019171808 10366296064 1108084656 11.914 100 77 154 2 1136910112 10180736 18644720
17:14:28 7599 8192000000 10089834016 10448310272 1179286528 12.584 100 81 162 0 1210049968 12801264 17962176
17:15:38 5815 8192000000 10133965040 10504368128 1219027824 12.953 100 90 177 1 1254174576 14520144 20626608
17:16:47 3203 8192000000 10164707968 10542747648 1253762528 13.273 100 87 171 0 1285983920 13079136 19142256
17:17:52 2966 8192000000 10188194000 10573606912 1276854592 13.484 100 84 170 1 1309238352 13662864 18720896
17:18:57 1801 8192000000 10213730656 10600726528 1299234784 13.688 100 87 172 1 1330518352 12668080 18615488
17:20:00 5719 8192000000 10218980048 10613157888 1307989696 13.768 100 82 162 1 1336420272 9100016 19330560
17:21:06 4523 8192000000 10215429008 10621341696 1319393696 13.871 100 31 61 2 1333136416 5504128 8238592
17:22:11 13301 8192000000 10206792336 10627375104 1324073440 13.914 100 19 38 0 1334719232 6725232 3920560
17:23:27 46965 8192000000 10215770864 10644992000 1334641216 14.009 100 49 96 4 1352611488 6764384 11205888
17:25:20 9031 8192000000 10230742672 10653515776 1341526688 14.071 100 25 51 1 1353562432 5844368 6191376
17:26:31 7612 8192000000 10232715120 10656026624 1343340448 14.088 100 25 50 1 1355328736 6278480 5709808
17:27:41 58608 8192000000 10190292272 10611699712 1267150704 13.396 95 42 83 0 1282882704 5648768 10083232
17:29:40 7159 8192000000 10181668272 10587217920 1262393616 13.352 97 32 64 1 1275378560 5549984 7434960
17:30:48 5019 8192000000 10133709424 10526474240 1188683920 12.671 94 23 46 0 1199094240 4854640 5555680
17:31:56 15353 8192000000 10148207904 10548912128 1228948016 13.044 97 23 46 1 1239373904 4668800 5757088
17:33:12 14743 8192000000 10151289232 10560299008 1250447232 13.242 99 16 32 0 1259027872 4713632 3867008
17:34:29 8307 8192000000 10175783920 10589073408 1285364832 13.562 100 24 48 1 1297371872 6110288 5896752
17:35:38 13134 8192000000 10188722832 10604404736 1297188000 13.670 100 22 44 1 1308064736 5338432 5538304
17:36:54 74435 8192000000 10205390192 10616852480 1307955104 13.768 100 40 79 1 1322343808 6029616 8359088
17:39:22 17460 8192000000 10247070928 10656317440 1338715568 14.046 100 46 91 1 1359728080 10967056 10045456
17:40:45 6120 8192000000 10274258160 10687791104 1366031456 14.291 100 49 97 0 1390895824 13468208 11396160
17:41:53 8131 8192000000 10285684784 10702553088 1372802304 14.352 100 48 96 0 1401001088 16953904 11244880
17:43:16 8779 8192000000 10294460432 10714927104 1376833792 14.388 100 43 86 0 1409559024 22332832 10392400
17:44:28 13938 8192000000 10325996960 10747392000 1400927984 14.603 100 52 104 1 1434561328 21306992 12326352
17:45:46 33153 8192000000 10322940176 10746462208 1397333536 14.571 100 47 94 1 1440409728 30745552 12330640
17:47:31 7624 8192000000 10336188768 10760642560 1411815712 14.700 100 48 94 0 1451031680 26980688 12235280
17:48:49 3776 8192000000 10357798992 10777346048 1414597600 14.725 100 63 126 1 1461606304 31783072 15225632
17:49:57 3958 8192000000 10365982864 10781450240 1416487936 14.742 100 71 142 0 1470555504 36536720 17530848
17:51:06 3575 8192000000 10368608400 10782666752 1411513440 14.697 100 75 149 0 1468032880 38015920 18503520
17:52:19 3379 8192000000 10340417120 10755788800 1387667808 14.485 100 76 152 1 1453528688 46024592 19836288
17:53:26 5187 8192000000 10336587808 10751766528 1379176576 14.409 100 73 146 1 1444444640 46267632 19000432
17:54:37 2119 8192000000 10323733968 10724843520 1347632464 14.126 98 91 182 0 1412773408 42667264 22473680
17:55:41 22581 8192000000 10303410960 10734104576 1365768800 14.289 100 27 54 1 1410655664 37496384 7390480
17:57:07 7687 8192000000 10286687472 10712125440 1340383776 14.061 99 36 72 3 1386633840 35966816 10283248
17:58:21 3472 8192000000 10311604464 10733699072 1362660336 14.261 100 41 80 2 1410313616 36794096 10859184
17:59:31 8892 8192000000 10300965232 10734473216 1366107072 14.292 100 26 52 0 1409298608 35830432 7361104
18:00:42 15275 8192000000 10286413072 10722136064 1352082960 14.166 100 24 48 1 1387916384 29309520 6523904
18:02:05 23433 8192000000 10276373280 10710847488 1350393888 14.151 100 20 40 3 1384795600 28510592 5891120
18:03:35 25001 8192000000 10279843296 10728402944 1355731152 14.199 100 15 30 1 1390330304 29984704 4614448
18:05:05 39867 8192000000 10242943648 10694565888 1343264224 14.087 100 18 36 0 1355336352 8224736 3847392
18:07:27 50352 8192000000 10268210464 10714009600 1326315232 13.934 98 32 62 1 1349023520 15731536 6976752
18:09:23 90665 8192000000 10262048544 10706341888 1335702480 14.019 98 9 18 0 1354736848 17156688 1877680
18:11:59 11089 8192000000 10287779936 10729676800 1377023008 14.390 100 32 64 1 1395378800 10650240 7705552
18:13:12 12723 8192000000 10304295280 10733293568 1370577392 14.332 99 51 102 0 1399643808 17166704 11899712
18:14:27 34489 8192000000 10301578752 10752708608 1384163216 14.454 100 18 36 2 1403790512 15208848 4418448
18:16:11 77782 8192000000 10295896768 10750869504 1377560192 14.395 99 14 28 1 1393097696 12357968 3179536
18:19:04 45122 8192000000 10305964016 10766675968 1394192720 14.543 100 8 16 0 1411854848 15900256 1761872
18:20:52 43488 8192000000 10329043248 10770518016 1386654864 14.476 99 23 47 0 1403868944 12191776 5022304
18:22:44 37864 8192000000 10315429040 10771963904 1405394800 14.643 100 18 37 1 1422753568 13444304 3914464
18:24:24 61375 8192000000 10295296160 10759979008 1359784880 14.235 97 12 24 0 1374938048 12322896 2830272
18:27:37 48365 8192000000 10324149776 10775236608 1388514864 14.493 99 30 61 1 1411980320 16595216 6870240
18:29:26 47860 8192000000 10304979808 10769383424 1389401808 14.501 99 19 38 0 1405999152 12318288 4279056
18:31:14 22163 8192000000 10292741216 10739806208 1340545472 14.062 96 16 32 0 1350725344 6891840 3288032
18:32:46 88123 8192000000 10273728192 10744004608 1345076784 14.103 98 6 12 0 1365119120 18735184 1307152
18:35:49 24866 8192000000 10283970144 10733813760 1367349936 14.303 99 36 72 3 1384529040 9701648 7477456
18:37:15 74221 8192000000 10265528000 10723655680 1321053776 13.886 96 12 24 0 1332181040 8733328 2393936
18:39:34 43879 8192000000 10216919136 10671419392 1299174352 13.688 97 13 26 0 1312420944 10431280 2815312
18:41:19 72046 8192000000 10250497072 10710855680 1324418800 13.917 98 15 30 0 1338191008 10756624 3015584
18:43:34 44812 8192000000 10287793504 10732265472 1333366128 13.998 97 18 36 2 1350051136 12104256 4580752
18:45:24 48553 8192000000 10279704048 10726064128 1330272448 13.970 97 14 28 0 1356183600 22447632 3463520
18:47:14 52014 8192000000 10285338528 10738937856 1360506048 14.242 99 17 34 1 1386140272 21572736 4061488
18:49:09 37575 8192000000 10282658336 10727276544 1328777712 13.956 97 42 84 1 1349048960 10992880 9278368
18:50:59 46426 8192000000 10287501504 10738262016 1373827344 14.361 100 21 42 1 1392828800 14234848 4766608
18:52:47 34127 8192000000 10302966032 10748243968 1381677136 14.432 100 32 64 1 1401168464 12491088 7000240
18:54:21 136360 8192000000 10262129712 10723487744 1283021744 13.541 93 4 8 0 1301198576 17329472 847360
18:57:44 100415 8192000000 10276460048 10728501248 1321126496 13.887 96 26 52 0 1338426944 11512800 5787648
19:00:38 26197 8192000000 10256817344 10720313344 1346046864 14.112 99 24 48 2 1367135824 15755152 5333808
19:02:16 57908 8192000000 10274722608 10727141376 1366790528 14.298 100 12 24 0 1382385728 12998704 2596496
19:04:28 73034 8192000000 10278798752 10741989376 1377446240 14.394 100 7 14 0 1391632000 12684800 1500960
19:06:52 58836 8192000000 10283253968 10746789888 1362043888 14.256 98 17 34 1 1380292576 14142592 4106096
19:09:04 42274 8192000000 10298686256 10760335360 1384878544 14.460 100 15 30 0 1399642592 11522000 3242048
19:10:48 48407 8192000000 10293405168 10765656064 1385481920 14.466 99 11 22 0 1399275232 11322032 2471280
19:12:37 28466 8192000000 10311585696 10761994240 1391867024 14.523 99 32 60 0 1408168448 9157200 7144224
19:14:08 48406 8192000000 10316810976 10769715200 1385326032 14.464 99 19 38 0 1400135104 10393440 4415632
19:16:00 115359 8192000000 10305357808 10757095424 1317287184 13.852 94 9 18 0 1333447120 14161008 1998928
19:19:03 54771 8192000000 10311779856 10775236608 1392186144 14.525 100 19 38 2 1414310384 17882704 4241536
19:21:36 136376 8192000000 10284606640 10745548800 1376053264 14.381 99 32 65 2 1395211984 12212304 6946416
19:25:06 32696 8192000000 10269237648 10728632320 1351115264 14.158 98 20 40 1 1365548816 9323344 5110208
19:26:54 42199 8192000000 10258494704 10722783232 1365268000 14.285 100 6 12 0 1373611008 7094848 1248160
19:28:36 59323 8192000000 10260052384 10715291648 1348683184 14.136 99 12 24 1 1358735664 7396864 2655616
19:30:36 59999 8192000000 10260376768 10726383616 1345360112 14.106 98 9 18 1 1355105504 7831712 1913680
19:32:45 34260 8192000000 10271806336 10728050688 1368408448 14.313 100 14 28 0 1379802208 7969888 3423872
19:34:28 29068 8192000000 10246918896 10691768320 1311161424 13.797 97 28 56 1 1325160000 7340144 6658432
19:36:08 57174 8192000000 10262353984 10711285760 1341357168 14.070 99 23 46 0 1356436512 9739584 5339760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2021 19:39:02 to 26-10-2021 19:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2021 19:39:18 to 26-10-2021 19:39:20.

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          242908
MON$OLDEST_ACTIVE               243975
MON$OLDEST_SNAPSHOT             243975
MON$NEXT_TRANSACTION            243975
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                       2034592
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:303: 
:304: mscs-test-161.budzdorov.ru    Tue Oct 26 19:37:58 2021
:305:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:306: 
:307: 
:308: mscs-test-161.budzdorov.ru    Tue Oct 26 19:38:05 2021
:309:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:310: 
:311: 
:312: mscs-test-161.budzdorov.ru    Tue Oct 26 19:38:06 2021
:313:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:314: 
--- end of diff output ---

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

26.10.21 19:39:21. 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-10-26 19:39:23 No severe PSQL-related problems occured
26.10.21 19:39:25. End of report.