Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2021 16: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 15748376 kB
MemAvailable 30831948 kB
Buffers 0 kB
Cached 15918544 kB
SwapCached 4792 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-27 16:37:52
DTS_END                         2021-10-27 16:37:52.6350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46196
TRN_ID                          244270
ATT_ID                          732
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: 9749.84 21158 1169981
customer order: creation 177.88 342 21346
customer order: refuse 15.46 356 1855
order to supplier: creation 45.64 1211 5477
order to supplier: removal 3.60 105425 432
invoice (draft): creation 46.93 3188 5632
invoice (draft): removal 54.58 784 6550
invoice accept: apply 43.46 18746 5215
invoice accept: cancel 49.66 813 5959
customer reserve: creation 4118.49 181 494219
customer reserve: removal 4885.06 61 586207
realization accept: apply 86.77 173 10412
realization accept: cancel 89.18 115 10701
payment from customer: creation 61.08 106 7329
payment from customer: removal 5.84 89 701
payment to supplier: creation 50.63 113 6075
payment to supplier: removal 3.15 94 378
service: total inventory turnovers 4.61 1346 553
service: total monetary turnovers 7.00 24 840
service: aggregate perf. data 0.80 26809 96
service: refresh index statistics 0.03 263182 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-10-2021 16:38:31 to 27-10-2021 16:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1384
TEST_TIME 2 3133
TEST_TIME 3 3813
TEST_TIME 4 4455
TEST_TIME 5 4356
TEST_TIME 6 4472
TEST_TIME 7 4855
TEST_TIME 8 4912
TEST_TIME 9 5436
TEST_TIME 10 5978
TEST_TIME 11 6289
TEST_TIME 12 6430
TEST_TIME 13 6406
TEST_TIME 14 6628
TEST_TIME 15 6687
TEST_TIME 16 6927
TEST_TIME 17 6959
TEST_TIME 18 7152
TEST_TIME 19 7246
TEST_TIME 20 7935
TEST_TIME 21 8078
TEST_TIME 22 8260
TEST_TIME 23 8690
TEST_TIME 24 8905
TEST_TIME 25 9173
TEST_TIME 26 9307
TEST_TIME 27 9405
TEST_TIME 28 9473
TEST_TIME 29 10986
TEST_TIME 30 11017
TEST_TIME 31 10903
TEST_TIME 32 10912
TEST_TIME 33 11024
TEST_TIME 34 11027
TEST_TIME 35 10926
TEST_TIME 36 10915
TEST_TIME 37 10940
TEST_TIME 38 10811
TEST_TIME 39 10728
TEST_TIME 40 10656
TEST_TIME 41 10501
TEST_TIME 42 10420
TEST_TIME 43 10399
TEST_TIME 44 10462
TEST_TIME 45 10356
TEST_TIME 46 10216
TEST_TIME 47 10098
TEST_TIME 48 10032
TEST_TIME 49 10014
TEST_TIME 50 10029
TEST_TIME 51 10138
TEST_TIME 52 10025
TEST_TIME 53 10051
TEST_TIME 54 9947
TEST_TIME 55 9920
TEST_TIME 56 9798
TEST_TIME 57 9805
TEST_TIME 58 9875
TEST_TIME 59 9786
TEST_TIME 60 9713
TEST_TIME 61 9732
TEST_TIME 62 9682
TEST_TIME 63 9604
TEST_TIME 64 9746
TEST_TIME 65 9775
TEST_TIME 66 9747
TEST_TIME 67 9793
TEST_TIME 68 9813
TEST_TIME 69 9804
TEST_TIME 70 9853
TEST_TIME 71 9877
TEST_TIME 72 9877
TEST_TIME 73 9952
TEST_TIME 74 9875
TEST_TIME 75 9819
TEST_TIME 76 9765
TEST_TIME 77 9849
TEST_TIME 78 9789
TEST_TIME 79 9730
TEST_TIME 80 9687
TEST_TIME 81 9658
TEST_TIME 82 9589
TEST_TIME 83 9532
TEST_TIME 84 9578
TEST_TIME 85 9557
TEST_TIME 86 9568
TEST_TIME 87 9605
TEST_TIME 88 9622
TEST_TIME 89 9614
TEST_TIME 90 9677
TEST_TIME 91 9702
TEST_TIME 92 9669
TEST_TIME 93 9805
TEST_TIME 94 9771
TEST_TIME 95 9764
TEST_TIME 96 9776
TEST_TIME 97 9851
TEST_TIME 98 9822
TEST_TIME 99 9753
TEST_TIME 100 9739
TEST_TIME 101 9681
TEST_TIME 102 9681
TEST_TIME 103 9666
TEST_TIME 104 9651
TEST_TIME 105 9619
TEST_TIME 106 9623
TEST_TIME 107 9592
TEST_TIME 108 9577
TEST_TIME 109 9561
TEST_TIME 110 9568
TEST_TIME 111 9592
TEST_TIME 112 9634
TEST_TIME 113 9628
TEST_TIME 114 9621
TEST_TIME 115 9641
TEST_TIME 116 9656
TEST_TIME 117 9679
TEST_TIME 118 9664
TEST_TIME 119 9756
TEST_TIME 120 9751

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-10-2021 16:38:35 to 27-10-2021 16:38:38.

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 1624495 1624495 0 0.00 0 17696 6 0 0
doc_list_biud 1624495 1624495 0 0.00 0 51484 28 0 0
sp_add_invoice_to_stock 5301 5215 86 1.62 21 179038 18747 0 86
sp_cancel_adding_invoice 5959 5959 0 0.00 5 70713 814 0 0
sp_cancel_client_order 1855 1855 0 0.00 4 16510 356 0 0
sp_cancel_customer_reserve 586207 586207 0 0.00 1 51485 61 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 9511 90 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 5825 94 0 0
sp_cancel_supplier_invoice 6555 6550 5 0.07 2 12224 785 0 5
sp_cancel_supplier_order 432 432 0 0.00 7 1220960 105425 0 0
sp_cancel_write_off 10701 10701 0 0.00 1 15958 116 0 0
sp_client_order 21346 21346 0 0.00 3 17726 343 0 0
sp_customer_reserve 494219 494219 0 0.00 0 86073 181 0 0
sp_fill_shopping_cart 32434 32434 0 0.00 1 26689 34 0 0
sp_fill_shopping_cart_clo_res 494217 494217 0 0.00 0 17854 13 0 0
sp_get_clo_for_invoice 5213 5213 0 0.00 0 17755 294 0 0
sp_kill_cost_storno 17739 17739 0 0.00 0 8359 56 0 0
sp_kill_qstorno_ret_qs2qd 1190097 1190097 0 0.00 0 51481 33 0 0
sp_kill_qty_storno 627327 627327 0 0.00 0 51484 71 0 0
sp_lock_selected_doc 21547 21547 0 0.00 0 5236 3 0 0
sp_make_cost_storno 29027 29027 0 0.00 0 9801 46 0 0
sp_multiply_rows_for_pdistr 29027 29027 0 0.00 0 9697 31 0 0
sp_multiply_rows_for_qdistr 21346 21346 0 0.00 0 11494 159 0 0
sp_pay_from_customer 7329 7329 0 0.00 4 9534 106 0 0
sp_pay_to_supplier 6075 6075 0 0.00 5 9718 114 0 0
sp_payment_common 13404 13404 0 0.00 1 8245 23 0 0
sp_qd_handle_on_cancel_clo 1855 1855 0 0.00 0 16490 325 0 0
sp_qd_handle_on_invoice_upd_sts 11170 11170 0 0.00 0 12435 334 0 0
sp_qd_handle_on_reserve_upd_sts 10412 10412 0 0.00 0 12776 56 0 0
sp_reserve_write_off 10412 10412 0 0.00 6 12825 173 0 0
sp_supplier_invoice 5632 5632 0 0.00 7 102331 3189 0 0
sp_supplier_order 5477 5477 0 0.00 5 33876 1212 0 0
srv_aggregate_perf_data 96 96 0 0.00 2806 637527 26810 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2206 129211 45641 0 0
srv_find_qd_qs_mism 1118680 1118680 0 0.00 0 17696 6 0 0
srv_make_invnt_saldo 553 553 0 0.00 9 143655 1347 0 0
srv_make_money_saldo 840 840 0 0.00 1 276 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 175229 475539 263182 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 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 5215 5215 0 0.00 0 11119 172 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 9696 153 0 0
t$perf-norm:sp_cancel_client_order 1855 1855 0 0.00 0 6344 17 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 822 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 1451 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 499 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 973 19 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 24159 1642 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 4328 12 0 0
t$perf-norm:sp_client_order 21346 21346 0 0.00 0 6142 19 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 3965 10 0 0
t$perf-norm:sp_pay_from_customer 7329 7329 0 0.00 0 7103 13 0 0
t$perf-norm:sp_pay_to_supplier 6075 6075 0 0.00 0 12114 13 0 0
t$perf-norm:sp_reserve_write_off 10412 10412 0 0.00 0 11610 12 0 0
t$perf-norm:sp_supplier_invoice 5632 5632 0 0.00 0 6272 21 0 0
t$perf-norm:sp_supplier_order 5477 5477 0 0.00 0 4403 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 266 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 52 1 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 4057 31 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 1182 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10586 10414 172 1.62 0 9497 21 0 172
v_all_customers 39146 39146 0 0.00 0 28 1 0 0
v_all_suppliers 31078 31078 0 0.00 0 22 1 0 0
v_all_wares 42692 42692 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2602 11 0 0
v_cancel_client_order 3710 3710 0 0.00 0 5790 7 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 9500 15 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2332 7 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 1097 6 0 0
v_cancel_supplier_invoice 848 838 10 1.17 0 9420 28 0 10
v_cancel_supplier_order 864 864 0 0.00 0 4007 9 0 0
v_cancel_write_off 2048 2048 0 0.00 0 3103 15 0 0
v_max_id_clo_ord 5477 5477 0 0.00 0 800 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 4461 21 0 0
v_max_id_ord_sup 5611 5611 0 0.00 0 4768 3 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 4206 6 0 0
v_max_non_paid_realizn 3812 3812 0 0.00 0 905 4 0 0
v_min_id_clo_ord 5477 5477 0 0.00 0 3637 7 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 1105 5 0 0
v_min_id_ord_sup 5611 5611 0 0.00 0 2433 4 0 0
v_min_non_paid_invoice 6075 6075 0 0.00 0 6553 13 0 0
v_min_non_paid_realizn 7329 7329 0 0.00 0 8243 16 0 0
v_our_firm 10580 10580 0 0.00 0 55 1 0 0
v_reserve_write_off 20822 20822 0 0.00 0 9694 8 0 0
x_lock_depdocs_on_canc_invoice 12090 12090 0 0.00 0 53249 129 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 8214 357 0 0
x_make_qstorno_1000_1200 5477 5477 0 0.00 2 33865 1130 0 0
x_make_qstorno_1200_2000 5632 5632 0 0.00 2 101679 3065 0 0
x_make_qstorno_2100_3300 494219 494219 0 0.00 0 86071 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2021 16:38:40 to 27-10-2021 16:38:44.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:07:56 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:57 32410 8192000000 9907543824 10222886912 987963840 10.762 100 89 176 1 1016899936 6143328 22792768
14:10:32 9285 8192000000 9955725840 10279628800 1038202096 11.247 100 90 180 0 1069292064 8192288 22897680
14:11:48 23400 8192000000 9985573824 10325176320 1069998768 11.552 100 75 150 0 1096908592 8701728 18208096
14:13:14 6446 8192000000 10044634592 10393546752 1131320368 12.134 100 76 152 0 1159590784 10247696 18022720
14:14:25 10330 8192000000 10085333376 10445365248 1170513024 12.502 100 77 154 0 1200031696 11892304 17626368
14:15:39 8162 8192000000 10122279488 10494885888 1213558800 12.902 100 71 142 0 1241221696 11591296 16071600
14:16:50 4127 8192000000 10159804336 10535657472 1245285232 13.195 100 70 139 1 1273608624 13506752 14816640
14:17:56 2012 8192000000 10189894624 10575491072 1283617824 13.546 100 78 156 1 1313724512 12437696 17668992
14:19:00 8879 8192000000 10198269872 10596536320 1295262144 13.652 100 64 128 0 1322058656 12780976 14015536
14:20:11 5593 8192000000 10202928608 10606342144 1308385952 13.771 100 48 95 1 1326559856 7985104 10188800
14:21:20 8684 8192000000 10194807216 10611875840 1316763760 13.847 100 25 48 2 1332740560 10152384 5824416
14:22:31 6836 8192000000 10205733712 10622025728 1320374224 13.880 100 22 44 1 1332066368 6562032 5130112
14:23:39 33698 8192000000 10209999344 10635313152 1324201728 13.915 100 35 70 1 1338170048 5875856 8092464
14:25:15 7048 8192000000 10224183056 10646286336 1333078496 13.995 100 21 42 0 1344579168 6455888 5044784
14:26:23 7272 8192000000 10221756464 10641645568 1324274288 13.915 99 24 44 3 1335929552 6714080 4941184
14:27:31 54786 8192000000 10212994768 10626772992 1287699952 13.583 97 47 92 1 1304973440 6308240 10965248
14:29:34 5591 8192000000 10166592960 10572374016 1254927760 13.283 98 30 59 0 1268087792 6628736 6531296
14:30:40 5709 8192000000 10128869360 10529861632 1209451296 12.864 97 23 46 0 1220722784 6454912 4816576
14:31:47 11553 8192000000 10154566320 10556416000 1250727296 13.245 99 17 34 1 1259947152 5037472 4182384
14:33:00 11843 8192000000 10154686576 10566508544 1262464928 13.353 99 19 38 0 1272491920 5648752 4378240
14:34:12 7908 8192000000 10179263840 10588110848 1285275376 13.561 100 27 54 2 1297379392 5897200 6206816
14:35:20 5246 8192000000 10197136464 10606452736 1301272688 13.707 100 23 46 0 1312913984 6584448 5056848
14:36:26 11069 8192000000 10195319568 10614075392 1309675184 13.783 100 24 48 2 1321839200 5788352 6375664
14:37:37 63032 8192000000 10228075648 10642120704 1320309792 13.880 99 40 80 0 1335389392 6148416 8931184
14:40:06 21789 8192000000 10262226304 10681774080 1358959856 14.228 100 45 89 2 1378692928 9383408 10349664
14:41:34 16484 8192000000 10284005744 10709364736 1379850272 14.415 100 43 86 2 1401663344 12055040 9758032
14:42:53 6240 8192000000 10303274480 10724954112 1393571104 14.538 100 49 97 1 1418388048 13115136 11701808
14:44:03 10173 8192000000 10314213456 10737291264 1401939888 14.612 100 54 107 1 1431296560 16788816 12567856
14:45:17 9475 8192000000 10319664176 10745106432 1404118320 14.632 100 48 96 0 1431804256 15770336 11915600
14:46:30 10197 8192000000 10334868992 10758164480 1411313920 14.696 100 62 120 1 1447033248 21546800 14172528
14:47:46 3949 8192000000 10349153280 10765783040 1414244752 14.722 100 75 150 0 1455479808 23398336 17836720
14:48:55 4909 8192000000 10331989824 10744786944 1388267920 14.490 98 80 159 0 1430273952 24073888 17932144
14:50:04 11246 8192000000 10346954400 10765533184 1390305904 14.509 98 66 132 2 1432432784 25916304 16210576
14:51:18 7586 8192000000 10356382608 10767577088 1406083200 14.649 100 80 160 1 1459919200 33509568 20326432
14:52:30 3615 8192000000 10354119952 10758672384 1397644688 14.574 100 80 158 2 1455084176 38403344 19036144
14:53:39 4358 8192000000 10362708032 10774556672 1406325904 14.651 100 73 146 0 1467156400 42072416 18758080
14:54:50 5517 8192000000 10363708928 10774425600 1400638880 14.601 100 72 139 1 1468380800 49834176 17907744
14:56:00 3267 8192000000 10356676384 10757595136 1372595552 14.350 99 75 150 1 1450205200 56735728 20873920
14:57:11 2470 8192000000 10379742384 10784894976 1393804560 14.540 100 82 165 0 1481391136 66050496 21536080
14:58:19 2204 8192000000 10371979056 10775826432 1390518016 14.510 100 89 178 0 1473119424 60053056 22548352
14:59:24 10023 8192000000 10375626400 10801528832 1404687904 14.637 100 49 98 0 1483782336 65652992 13441440
15:00:44 7094 8192000000 10365509792 10791985152 1396536544 14.564 100 53 107 0 1474538656 63292032 14710080
15:01:58 24092 8192000000 10347159136 10793672704 1396470688 14.564 100 30 59 1 1468068432 63058176 8539568
15:03:25 14663 8192000000 10339479872 10778492928 1373749552 14.361 99 24 49 1 1434669680 54145856 6774272
15:04:40 31036 8192000000 10316197056 10755260416 1364954624 14.282 100 21 42 2 1433208112 61534320 6719168
15:06:13 9567 8192000000 10308573648 10745036800 1349754288 14.145 100 32 63 1 1418010624 59382944 8873392
15:07:27 72942 8192000000 10295432976 10733797376 1360081168 14.238 99 9 18 0 1379136560 17514048 1541344
15:09:42 88555 8192000000 10299222496 10760306688 1340056896 14.058 97 12 24 0 1367831712 24821488 2953328
15:12:11 77661 8192000000 10295959936 10746068992 1360216400 14.239 98 4 8 0 1371417280 10598448 602432
15:14:48 55448 8192000000 10304271616 10743951360 1365292544 14.285 98 27 52 1 1382810704 11042640 6475520
15:16:46 129211 8192000000 10299229808 10750783488 1384003328 14.452 99 21 42 0 1404084784 14836928 5244528
15:20:08 65654 8192000000 10316033216 10761080832 1388943840 14.496 99 19 36 1 1400812688 8182720 3686128
15:22:14 51843 8192000000 10290729584 10744963072 1361537008 14.251 97 12 24 0 1373564288 9618672 2408608
15:24:10 29664 8192000000 10284060160 10726592512 1334426304 14.007 96 16 32 1 1345455472 7285776 3743392
15:25:41 29026 8192000000 10295225168 10746896384 1376778720 14.388 99 28 56 0 1399358000 15949440 6629840
15:27:25 40438 8192000000 10285777264 10752315392 1359929856 14.237 98 29 58 2 1386603040 19694064 6979120
15:29:18 28651 8192000000 10289279488 10748805120 1381154000 14.427 100 12 24 0 1402542768 18354816 3033952
15:30:51 79624 8192000000 10308885152 10761154560 1382910640 14.443 99 16 32 0 1400438304 13936768 3590896
15:33:11 100316 8192000000 10278035024 10745921536 1355423872 14.196 97 18 36 0 1374713808 14655504 4634432
15:35:53 41841 8192000000 10265398544 10726354944 1353235344 14.177 98 10 20 0 1366175744 10290816 2649584
15:37:47 77348 8192000000 10274577632 10745249792 1332178320 13.987 96 10 20 0 1345555488 11258336 2118832
15:40:09 79058 8192000000 10288629104 10758950912 1337848816 14.038 96 11 22 0 1354843136 14595728 2398592
15:42:40 42559 8192000000 10273035120 10714374144 1322131904 13.896 95 25 50 0 1335083584 7680752 5270928
15:44:34 70110 8192000000 10264341472 10710003712 1253537488 13.271 92 16 32 0 1271952096 14961200 3453408
15:46:44 65235 8192000000 10272325840 10724323328 1350708640 14.154 99 28 56 0 1369391456 12678560 6004256
15:48:52 73491 8192000000 10267643648 10725400576 1331016064 13.976 97 8 16 1 1341897344 9324560 1556720
15:51:06 36663 8192000000 10279628528 10716819456 1349156032 14.140 99 36 73 2 1367682288 10331088 8195168
15:52:47 127449 8192000000 10275094416 10740785152 1365540336 14.287 100 24 48 0 1388267744 17247344 5480064
15:56:36 54489 8192000000 10238512576 10689114112 1302929648 13.722 95 13 26 1 1312986560 7149664 2907248
15:58:31 86988 8192000000 10283348320 10732130304 1367132256 14.301 100 10 20 1 1379560432 10134736 2293440
16:02:24 44183 8192000000 10273190608 10731536384 1336308432 14.024 97 27 52 1 1355192736 12252272 6632032
16:04:15 45873 8192000000 10278604304 10736140288 1354291296 14.186 98 18 37 0 1371561728 12699056 4571376
16:06:01 40601 8192000000 10291726288 10744082432 1364965744 14.282 99 18 36 1 1380549104 11475504 4107856
16:07:42 77836 8192000000 10313998160 10752610304 1383418448 14.447 99 16 32 0 1402203120 15129008 3655664
16:10:00 73829 8192000000 10294931216 10753761280 1367577776 14.305 98 8 16 0 1383182016 13848048 1756192
16:12:14 32854 8192000000 10316351104 10748686336 1371144496 14.337 98 43 86 1 1391037632 10338704 9554432
16:13:49 80905 8192000000 10296881264 10751090688 1372709728 14.351 98 18 36 0 1391153200 14270400 4173072
16:16:11 118199 8192000000 10284194256 10732830720 1340543472 14.062 97 20 40 0 1352972192 7949424 4479296
16:19:17 45338 8192000000 10267945968 10724642816 1350354480 14.151 98 18 37 0 1365604656 11131920 4118256
16:21:06 38480 8192000000 10267508512 10730659840 1351704720 14.163 98 16 31 0 1367266416 11555616 4006080
16:22:45 59494 8192000000 10249105568 10716348416 1338370544 14.043 98 8 16 0 1347571504 7514272 1686688
16:24:58 45832 8192000000 10278541792 10728636416 1368914384 14.317 100 17 34 1 1382399472 9513440 3971648
16:26:54 90133 8192000000 10269955856 10731536384 1344079600 14.094 98 15 30 1 1362924304 15252432 3592272
16:29:27 56151 8192000000 10268447008 10734223360 1357906432 14.219 99 19 38 0 1373592992 11326880 4359680
16:31:23 44524 8192000000 10258093440 10705149952 1296054384 13.659 95 36 72 0 1317663712 13944672 7664656
16:33:14 66600 8192000000 10270532576 10721910784 1352104560 14.166 100 24 48 0 1372842464 14921440 5816464
16:35:22 72856 8192000000 10287695088 10747645952 1337587968 14.036 98 23 45 0 1356323760 13729248 5006544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-10-2021 16:38:45 to 27-10-2021 16:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 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 27-10-2021 16:39:01 to 27-10-2021 16:39:03.

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          243379
MON$OLDEST_ACTIVE               244395
MON$OLDEST_SNAPSHOT             244395
MON$NEXT_TRANSACTION            244395
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                       2068448
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 ---
:338: 
:339: mscs-test-161.budzdorov.ru    Wed Oct 27 16:37:58 2021
:340:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:341: 
:342: 
:343: mscs-test-161.budzdorov.ru    Wed Oct 27 16:38:00 2021
:344:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:345: 
--- end of diff output ---

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

27.10.21 16:39:04. 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-27 16:39:07 No severe PSQL-related problems occured
27.10.21 16:39:08. End of report.