Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2021 22: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 16051584 kB
MemAvailable 30921160 kB
Buffers 0 kB
Cached 15587148 kB
SwapCached 3640 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-21 22:37:49
DTS_END                         2021-08-21 22:37:49.3390
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/45228
TRN_ID                          245236
ATT_ID                          654
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: 9432.47 17044 1131896
customer order: creation 178.93 341 21472
customer order: refuse 15.35 343 1842
order to supplier: creation 47.24 1297 5669
order to supplier: removal 3.37 103670 404
invoice (draft): creation 47.77 3499 5732
invoice (draft): removal 52.97 746 6356
invoice accept: apply 43.87 19303 5264
invoice accept: cancel 47.94 802 5753
customer reserve: creation 3985.73 194 478287
customer reserve: removal 4699.53 58 563944
realization accept: apply 85.70 182 10284
realization accept: cancel 90.12 108 10814
payment from customer: creation 61.04 123 7325
payment from customer: removal 5.86 69 703
payment to supplier: creation 50.27 146 6032
payment to supplier: removal 3.54 60 425
service: total inventory turnovers 4.90 1464 588
service: total monetary turnovers 7.42 27 890
service: aggregate perf. data 0.90 18593 108
service: refresh index statistics 0.03 189853 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2021 22:38:38 to 21-08-2021 22:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2595
TEST_TIME 2 4296
TEST_TIME 3 5262
TEST_TIME 4 5060
TEST_TIME 5 5447
TEST_TIME 6 5260
TEST_TIME 7 5141
TEST_TIME 8 5845
TEST_TIME 9 6005
TEST_TIME 10 6452
TEST_TIME 11 6605
TEST_TIME 12 6913
TEST_TIME 13 6921
TEST_TIME 14 6885
TEST_TIME 15 7067
TEST_TIME 16 7520
TEST_TIME 17 7448
TEST_TIME 18 7663
TEST_TIME 19 7661
TEST_TIME 20 7885
TEST_TIME 21 8033
TEST_TIME 22 8344
TEST_TIME 23 8723
TEST_TIME 24 8770
TEST_TIME 25 9010
TEST_TIME 26 9286
TEST_TIME 27 9462
TEST_TIME 28 9761
TEST_TIME 29 9836
TEST_TIME 30 9827
TEST_TIME 31 10944
TEST_TIME 32 10799
TEST_TIME 33 10786
TEST_TIME 34 10721
TEST_TIME 35 10591
TEST_TIME 36 10630
TEST_TIME 37 10438
TEST_TIME 38 10374
TEST_TIME 39 10178
TEST_TIME 40 10006
TEST_TIME 41 10080
TEST_TIME 42 9940
TEST_TIME 43 9907
TEST_TIME 44 9814
TEST_TIME 45 9756
TEST_TIME 46 9734
TEST_TIME 47 9719
TEST_TIME 48 10077
TEST_TIME 49 10016
TEST_TIME 50 9902
TEST_TIME 51 9848
TEST_TIME 52 9731
TEST_TIME 53 9682
TEST_TIME 54 9723
TEST_TIME 55 9646
TEST_TIME 56 9568
TEST_TIME 57 9524
TEST_TIME 58 9480
TEST_TIME 59 9473
TEST_TIME 60 9514
TEST_TIME 61 9502
TEST_TIME 62 9626
TEST_TIME 63 9593
TEST_TIME 64 9670
TEST_TIME 65 9747
TEST_TIME 66 9719
TEST_TIME 67 9746
TEST_TIME 68 9769
TEST_TIME 69 9685
TEST_TIME 70 9802
TEST_TIME 71 9775
TEST_TIME 72 9806
TEST_TIME 73 9784
TEST_TIME 74 9823
TEST_TIME 75 9723
TEST_TIME 76 9653
TEST_TIME 77 9628
TEST_TIME 78 9578
TEST_TIME 79 9586
TEST_TIME 80 9504
TEST_TIME 81 9477
TEST_TIME 82 9446
TEST_TIME 83 9405
TEST_TIME 84 9415
TEST_TIME 85 9389
TEST_TIME 86 9431
TEST_TIME 87 9393
TEST_TIME 88 9470
TEST_TIME 89 9490
TEST_TIME 90 9479
TEST_TIME 91 9529
TEST_TIME 92 9522
TEST_TIME 93 9558
TEST_TIME 94 9588
TEST_TIME 95 9554
TEST_TIME 96 9615
TEST_TIME 97 9620
TEST_TIME 98 9675
TEST_TIME 99 9606
TEST_TIME 100 9531
TEST_TIME 101 9486
TEST_TIME 102 9491
TEST_TIME 103 9463
TEST_TIME 104 9414
TEST_TIME 105 9393
TEST_TIME 106 9399
TEST_TIME 107 9363
TEST_TIME 108 9377
TEST_TIME 109 9386
TEST_TIME 110 9373
TEST_TIME 111 9372
TEST_TIME 112 9390
TEST_TIME 113 9432
TEST_TIME 114 9447
TEST_TIME 115 9432
TEST_TIME 116 9423
TEST_TIME 117 9406
TEST_TIME 118 9479
TEST_TIME 119 9461
TEST_TIME 120 9434

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-08-2021 22:38:41 to 21-08-2021 22:38:45.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1575856 1575856 0 0.00 0 33957 7 0 0
doc_list_biud 1575856 1575856 0 0.00 0 40738 26 0 0
sp_add_invoice_to_stock 5400 5264 136 2.51 23 190908 19304 0 136
sp_cancel_adding_invoice 5753 5753 0 0.00 5 87311 803 0 0
sp_cancel_client_order 1842 1842 0 0.00 5 13436 343 0 0
sp_cancel_customer_reserve 563944 563944 0 0.00 1 28577 58 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 5977 70 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 2 3912 61 0 0
sp_cancel_supplier_invoice 6362 6356 6 0.09 1 21079 746 0 6
sp_cancel_supplier_order 404 404 0 0.00 8 1044118 103670 0 0
sp_cancel_write_off 10814 10814 0 0.00 1 21460 108 0 0
sp_client_order 21472 21472 0 0.00 3 25360 342 0 0
sp_customer_reserve 478287 478287 0 0.00 0 88106 195 0 0
sp_fill_shopping_cart 32866 32866 0 0.00 1 14839 38 0 0
sp_fill_shopping_cart_clo_res 478283 478283 0 0.00 0 17934 12 0 0
sp_get_clo_for_invoice 5259 5259 0 0.00 0 25782 267 0 0
sp_kill_cost_storno 17695 17695 0 0.00 0 33951 59 0 0
sp_kill_qstorno_ret_qs2qd 1145462 1145462 0 0.00 0 28286 31 0 0
sp_kill_qty_storno 604656 604656 0 0.00 0 40736 68 0 0
sp_lock_selected_doc 21459 21459 0 0.00 0 12002 6 0 0
sp_make_cost_storno 28900 28900 0 0.00 0 33188 61 0 0
sp_multiply_rows_for_pdistr 28900 28900 0 0.00 0 6178 29 0 0
sp_multiply_rows_for_qdistr 21472 21472 0 0.00 0 10553 148 0 0
sp_pay_from_customer 7325 7325 0 0.00 4 14346 123 0 0
sp_pay_to_supplier 6032 6032 0 0.00 5 23442 146 0 0
sp_payment_common 13357 13357 0 0.00 1 23428 35 0 0
sp_qd_handle_on_cancel_clo 1842 1842 0 0.00 0 13426 320 0 0
sp_qd_handle_on_invoice_upd_sts 11012 11012 0 0.00 0 40734 346 0 0
sp_qd_handle_on_reserve_upd_sts 10284 10284 0 0.00 0 22619 60 0 0
sp_reserve_write_off 10284 10284 0 0.00 6 22648 182 0 0
sp_supplier_invoice 5732 5732 0 0.00 6 94266 3499 0 0
sp_supplier_order 5669 5669 0 0.00 5 34984 1298 0 0
srv_aggregate_perf_data 109 108 1 0.91 2551 243404 18594 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 2224 136979 41760 0 0
srv_find_qd_qs_mism 1083017 1083017 0 0.00 0 15840 6 0 0
srv_make_invnt_saldo 588 588 0 0.00 35 106391 1465 0 0
srv_make_money_saldo 890 890 0 0.00 1 627 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 75127 306201 189853 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5264 5264 0 0.00 0 30346 185 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 6242 128 0 0
t$perf-norm:sp_cancel_client_order 1842 1842 0 0.00 0 8691 21 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 974 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 1265 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 629 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1999 20 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 19060 1529 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 2371 7 0 0
t$perf-norm:sp_client_order 21472 21472 0 0.00 0 9320 18 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 6122 20 0 0
t$perf-norm:sp_pay_from_customer 7325 7325 0 0.00 0 9742 12 0 0
t$perf-norm:sp_pay_to_supplier 6032 6032 0 0.00 0 6316 9 0 0
t$perf-norm:sp_reserve_write_off 10284 10284 0 0.00 0 11027 11 0 0
t$perf-norm:sp_supplier_invoice 5732 5732 0 0.00 0 4863 20 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 2457 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1032 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 1517 25 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 5009 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10786 10514 272 2.52 0 12745 19 0 272
v_all_customers 38646 38646 0 0.00 0 87 1 0 0
v_all_suppliers 30956 30956 0 0.00 0 21 1 0 0
v_all_wares 42942 42942 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2249 11 0 0
v_cancel_client_order 3684 3684 0 0.00 0 2334 4 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 1244 6 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 965 4 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 233 3 0 0
v_cancel_supplier_invoice 842 830 12 1.42 0 487 14 0 12
v_cancel_supplier_order 808 808 0 0.00 0 2151 10 0 0
v_cancel_write_off 1988 1988 0 0.00 0 820 12 0 0
v_max_id_clo_ord 5667 5667 0 0.00 0 3243 3 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 1530 10 0 0
v_max_id_ord_sup 5726 5726 0 0.00 0 7894 5 0 0
v_max_non_paid_invoice 1947 1947 0 0.00 0 9386 10 0 0
v_max_non_paid_realizn 4093 4093 0 0.00 0 4046 5 0 0
v_min_id_clo_ord 5667 5667 0 0.00 0 3073 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 3832 10 0 0
v_min_id_ord_sup 5726 5726 0 0.00 0 7162 7 0 0
v_min_non_paid_invoice 6032 6032 0 0.00 0 23423 24 0 0
v_min_non_paid_realizn 7325 7325 0 0.00 0 7088 17 0 0
v_our_firm 10760 10760 0 0.00 0 15 1 0 0
v_reserve_write_off 20568 20568 0 0.00 0 2547 5 0 0
x_lock_depdocs_on_canc_invoice 11694 11694 0 0.00 0 27705 116 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 3288 350 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 2 34936 1212 0 0
x_make_qstorno_1200_2000 5732 5732 0 0.00 3 92167 3367 0 0
x_make_qstorno_2100_3300 478287 478287 0 0.00 0 88104 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2021 22:38:46 to 21-08-2021 22:38:50.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:54 24784 8192000000 10364990496 10686996480 1424844432 14.816 100 90 180 0 1461232464 6352256 30035776
20:10:25 4598 8192000000 10409566528 10736279552 1469614256 15.210 100 90 180 0 1506908576 7915664 29378656
20:11:43 20968 8192000000 10474483104 10813841408 1527656688 15.717 100 78 156 0 1561793152 8776848 25359616
20:13:09 16402 8192000000 10581743872 10939920384 1631017792 16.604 100 81 162 2 1666087840 10718240 24351808
20:14:28 11810 8192000000 10646999840 11017187328 1700481456 17.189 100 77 154 3 1735874160 12414992 22977712
20:15:42 5815 8192000000 10696007712 11072724992 1755581600 17.648 100 78 156 1 1792610480 14475520 22553360
20:16:50 6042 8192000000 10735223152 11120959488 1797854608 17.996 100 79 155 0 1832376736 12938768 21583360
20:17:58 1450 8192000000 10782735472 11169742848 1842861072 18.364 100 91 181 3 1881093456 13169568 25062816
20:19:01 1437 8192000000 10813961424 11202908160 1867821216 18.567 100 87 174 2 1906157296 13444784 24891296
20:20:03 2159 8192000000 10807235312 11210735616 1875583216 18.629 100 70 141 3 1904504784 8678624 20242944
20:21:06 4746 8192000000 10808518800 11223969792 1880931920 18.673 100 37 71 0 1899345952 7954096 10459936
20:22:14 10491 8192000000 10796649760 11231121408 1893454032 18.774 100 19 39 0 1904405168 5867904 5083232
20:23:25 10008 8192000000 10806303824 11244310528 1900135936 18.827 100 16 32 1 1914694272 10348464 4209872
20:24:36 32369 8192000000 10862752032 11262664704 1913588016 18.935 100 73 151 4 1944720064 9577968 21554080
20:26:08 5556 8192000000 10831204336 11264483328 1911456944 18.918 100 25 49 3 1924612400 6087104 7068352
20:27:18 13378 8192000000 10790827920 11224412160 1815554768 18.141 95 28 56 3 1829473008 5674304 8243936
20:28:31 13298 8192000000 10775036448 11202781184 1811250464 18.106 96 20 40 1 1823018640 5784384 5983792
20:29:45 25886 8192000000 10790317232 11182182400 1805980192 18.063 97 83 170 3 1836963664 5284608 25698864
20:31:11 4989 8192000000 10711482592 11124101120 1788471984 17.919 100 38 76 2 1807770528 8737504 10561040
20:32:18 12291 8192000000 10699384096 11120226304 1749625488 17.598 97 24 47 1 1761848496 5186944 7036064
20:33:33 67011 8192000000 10752735952 11174199296 1831774416 18.274 100 33 66 1 1847814160 5543712 10496032
20:35:40 5330 8192000000 10779550160 11204423680 1862853216 18.526 100 29 58 2 1878641344 8065968 7722160
20:36:47 7428 8192000000 10789270736 11223400448 1875068960 18.625 100 16 32 1 1885308656 5393136 4846560
20:38:00 13822 8192000000 10843026384 11259617280 1912326176 18.925 100 34 66 1 1927656208 5875328 9454704
20:39:17 8710 8192000000 10877579552 11296555008 1935918256 19.114 100 51 97 0 1965664464 16871648 12874560
20:40:28 17504 8192000000 10882389216 11301523456 1938439008 19.134 100 45 89 1 1961162096 9491120 13231968
20:41:52 2377 8192000000 10908856352 11328610304 1965272800 19.348 100 65 128 2 1993686512 10422240 17991472
20:43:00 13323 8192000000 10914374304 11339198464 1959884960 19.305 99 63 126 0 1991158672 13183712 18090000
20:44:19 11925 8192000000 10918496224 11347243008 1975884336 19.432 100 55 111 1 2008426960 16416128 16126496
20:45:38 14330 8192000000 10925499680 11356061696 1966555216 19.358 99 47 93 3 1994978416 13353600 15069600
20:46:55 10710 8192000000 10903547296 11343921152 1962653888 19.327 99 39 76 0 1990318160 16468144 11196128
20:48:09 4033 8192000000 10934570944 11355160576 1984453520 19.500 100 86 169 4 2026648064 18034672 24159872
20:49:16 4592 8192000000 10928536800 11359858688 1982641312 19.486 100 57 112 1 2018153904 17918128 17594464
20:50:25 6153 8192000000 10933076576 11353546752 1974608384 19.422 100 75 149 3 2018598032 22647504 21342144
20:51:37 3535 8192000000 10956797312 11374292992 1992106464 19.560 100 73 144 1 2036887040 23472672 21307904
20:52:49 6389 8192000000 10954969456 11378352128 1975199632 19.427 99 71 142 0 2026921376 30556224 21165520
20:54:03 2406 8192000000 10948070144 11365011456 1963673936 19.335 99 77 152 1 2015106176 28061488 23370752
20:55:11 3346 8192000000 10953733344 11374223360 1962847936 19.329 99 71 142 1 2021167024 35101904 23217184
20:56:18 4760 8192000000 10923668048 11347513344 1955509952 19.270 100 59 116 3 2008646496 35070608 18065936
20:57:31 5332 8192000000 10924278880 11349770240 1935310224 19.109 99 51 102 1 1989664512 37808512 16545776
20:58:42 2223 8192000000 10971171424 11375935488 1967705088 19.367 100 88 175 2 2043406528 48101120 27600320
20:59:48 4872 8192000000 10944472672 11368308736 1964959840 19.345 100 65 130 1 2028569312 43177168 20432304
21:00:56 12081 8192000000 10927611680 11366457344 1941231872 19.157 99 40 80 0 2001040992 45774928 14034192
21:02:11 11484 8192000000 10944167344 11383693312 1967379728 19.365 100 42 83 2 2029854336 48474592 14000016
21:03:29 2610 8192000000 10956428352 11381456896 1977930320 19.448 100 74 144 1 2045415120 44708080 22776720
21:04:35 31004 8192000000 10951156432 11405316096 1972028192 19.402 100 30 60 0 2033578096 51150112 10399792
21:06:11 34310 8192000000 10934502656 11387326464 1949526416 19.223 99 25 50 2 2000711936 43281616 7903904
21:07:46 50192 8192000000 10919707200 11394338816 1948271184 19.213 99 10 20 1 2000205968 48352960 3581824
21:09:39 88169 8192000000 10901737200 11367718912 1975516752 19.429 100 34 69 2 1993433296 9153728 8762816
21:12:08 29429 8192000000 10933873264 11376803840 1986048400 19.513 100 28 56 2 2003104304 8273504 8782400
21:13:39 66377 8192000000 10916613024 11371606016 1969697600 19.383 99 32 64 0 1995374720 17188160 8488960
21:15:49 136978 8192000000 10926679920 11384090624 1978508816 19.453 99 24 48 2 2000558720 14852624 7197280
21:19:08 58735 8192000000 10945862000 11406467072 1975084640 19.426 98 42 84 0 2002616176 14806752 12724784
21:21:13 57806 8192000000 10940202560 11402153984 1988230688 19.530 99 24 48 3 2008648304 14107408 6310208
21:23:27 23761 8192000000 10966516720 11418484736 2017500592 19.761 100 45 91 1 2048401776 17436896 13464288
21:24:57 57187 8192000000 10942557040 11454971904 2005392208 19.665 100 16 33 1 2047679744 37050960 5236576
21:27:13 21322 8192000000 10925362864 11393802240 1988638288 19.533 99 33 65 0 2004545200 5590928 10315984
21:28:35 86595 8192000000 10924698128 11404136448 1988069536 19.529 99 16 32 1 2005993952 12921024 5003392
21:31:43 52852 8192000000 10900599552 11371667456 1918795520 18.977 96 19 38 1 1933502864 9078032 5629312
21:33:43 55704 8192000000 10918629504 11404431360 1964509792 19.342 98 21 41 3 1986038032 16144288 5383952
21:35:39 55539 8192000000 10892462480 11376250880 1924644512 19.024 97 9 18 2 1947342496 20113088 2584896
21:37:56 63473 8192000000 10904061040 11387961344 1956032928 19.274 99 15 30 1 1975870224 15559728 4277568
21:40:11 23983 8192000000 10869081664 11328950272 1872559088 18.605 95 20 40 2 1885434464 7310912 5564464
21:41:35 72481 8192000000 10902323232 11374092288 1926788544 19.041 98 9 18 0 1955778432 26480080 2509808
21:43:50 30683 8192000000 10917768640 11368968192 1945945264 19.194 98 35 70 1 1965908864 10554720 9408880
21:45:24 75953 8192000000 10912269904 11365425152 1886660528 18.719 95 22 44 0 1905703408 13176880 5866000
21:47:45 59715 8192000000 10871487632 11327025152 1858530176 18.491 95 18 36 0 1874656544 11012560 5113808
21:49:47 75299 8192000000 10890732080 11349049344 1878452544 18.653 96 23 46 0 1897258400 12554960 6250896
21:52:14 114717 8192000000 10861058928 11338534912 1914702480 18.944 98 18 36 1 1934485312 14911184 4871648
21:55:21 47816 8192000000 10866630784 11342360576 1939398320 19.142 99 33 66 0 1958615504 9774400 9442784
21:57:35 35579 8192000000 10861630048 11338600448 1926395008 19.038 99 8 16 0 1946005568 17518416 2092144
21:59:11 71154 8192000000 10875751136 11357331456 1927010768 19.043 98 15 30 0 1949559408 18206752 4341888
22:01:24 57024 8192000000 10891922128 11372179456 1966897632 19.361 100 22 44 3 1988327680 15027024 6403024
22:03:30 36704 8192000000 10883286640 11352461312 1906391440 18.878 97 16 32 1 1919114912 8268112 4455360
22:05:09 64680 8192000000 10907697168 11362357248 1947271568 19.205 99 25 50 1 1966572256 11912736 7387952
22:07:16 59358 8192000000 10921232768 11394318336 1982075984 19.481 100 20 40 1 1998367456 10550112 5741360
22:09:17 43026 8192000000 10939238640 11398295552 1995020256 19.583 100 21 40 1 2013255776 12495760 5739760
22:11:03 54351 8192000000 10924653872 11405942784 1981865504 19.479 99 17 34 0 1996845904 10372144 4608256
22:13:07 54376 8192000000 10939827392 11416489984 1975347424 19.428 98 24 48 1 1997558368 15056528 7154416
22:15:08 56057 8192000000 10921073888 11405758464 1980771568 19.471 99 15 30 0 2004984624 19976816 4236240
22:17:10 113643 8192000000 10905365520 11376140288 1919351936 18.982 96 32 63 1 1938496400 10370256 8774208
22:20:50 61262 8192000000 10897451248 11377471488 1938840480 19.138 98 10 20 1 1948785648 7375056 2570112
22:23:02 62103 8192000000 10876358752 11362574336 1921167264 18.996 97 15 30 0 1938555744 12529776 4858704
22:25:06 56646 8192000000 10862349440 11346976768 1874856240 18.624 96 15 30 1 1895914928 16985376 4073312
22:27:16 56423 8192000000 10872936160 11343585280 1912281840 18.925 98 19 37 1 1928195296 11033488 4879968
22:29:19 44423 8192000000 10858132688 11331481600 1914990640 18.947 99 16 31 1 1930483744 11002416 4490688
22:31:04 75806 8192000000 10863491920 11317997568 1863531152 18.532 96 29 58 0 1883333040 11751840 8050048
22:33:23 15553 8192000000 10868205568 11322707968 1910125536 18.908 99 37 74 1 1928961984 9018400 9818048
22:34:39 76076 8192000000 10855716384 11334459392 1880773296 18.671 97 26 52 1 1906939136 18658240 7507600
22:36:55 66961 8192000000 9816607552 10172715008 914752208 10.044 47 17 32 1 928473984 9319456 4402320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-08-2021 22:38:52 to 21-08-2021 22:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 136 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          244511
MON$OLDEST_ACTIVE               245741
MON$OLDEST_SNAPSHOT             245741
MON$NEXT_TRANSACTION            245741
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                       2048480
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:244: 
:245: mscs-test-161.budzdorov.ru    Sat Aug 21 22:38:04 2021
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:247: 
:248: 
:249: mscs-test-161.budzdorov.ru    Sat Aug 21 22:38:08 2021
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
:252: 
:253: mscs-test-161.budzdorov.ru    Sat Aug 21 22:38:11 2021
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:255: 
:256: 
:257: mscs-test-161.budzdorov.ru    Sat Aug 21 22:38:11 2021
:258:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:259: 
--- end of diff output ---

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

21.08.21 22:39:11. 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-21 22:39:13 No severe PSQL-related problems occured
21.08.21 22:39:14. End of report.