Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.11.2021 19:39
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
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 16693020 kB
MemAvailable 31555316 kB
Buffers 0 kB
Cached 15000636 kB
SwapCached 0 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-11-29 19:38:00
DTS_END                         2021-11-29 19:38:00.1040
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/41914
TRN_ID                          236667
ATT_ID                          695
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: 9444.38 21261 1133325
customer order: creation 167.73 385 20127
customer order: refuse 13.84 364 1661
order to supplier: creation 44.78 1262 5374
order to supplier: removal 3.40 112232 408
invoice (draft): creation 44.65 3747 5358
invoice (draft): removal 55.02 850 6602
invoice accept: apply 41.13 18355 4936
invoice accept: cancel 49.78 853 5974
customer reserve: creation 3810.38 179 457246
customer reserve: removal 4923.53 60 590824
realization accept: apply 82.53 206 9903
realization accept: cancel 81.81 113 9817
payment from customer: creation 57.23 140 6868
payment from customer: removal 5.53 73 663
payment to supplier: creation 47.22 144 5666
payment to supplier: removal 3.48 77 418
service: total inventory turnovers 4.60 1194 552
service: total monetary turnovers 6.70 24 804
service: aggregate perf. data 1.00 13802 120
service: refresh index statistics 0.03 271168 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-11-2021 19:39:27 to 29-11-2021 19:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7199
TEST_TIME 2 5865
TEST_TIME 3 4463
TEST_TIME 4 3878
TEST_TIME 5 4690
TEST_TIME 6 5808
TEST_TIME 7 5523
TEST_TIME 8 5325
TEST_TIME 9 5428
TEST_TIME 10 5827
TEST_TIME 11 6125
TEST_TIME 12 6571
TEST_TIME 13 6930
TEST_TIME 14 7005
TEST_TIME 15 7040
TEST_TIME 16 7173
TEST_TIME 17 7349
TEST_TIME 18 7583
TEST_TIME 19 7874
TEST_TIME 20 8095
TEST_TIME 21 8596
TEST_TIME 22 8862
TEST_TIME 23 9147
TEST_TIME 24 9284
TEST_TIME 25 9396
TEST_TIME 26 9481
TEST_TIME 27 9931
TEST_TIME 28 10368
TEST_TIME 29 11133
TEST_TIME 30 11001
TEST_TIME 31 10738
TEST_TIME 32 10623
TEST_TIME 33 10547
TEST_TIME 34 10665
TEST_TIME 35 10494
TEST_TIME 36 10451
TEST_TIME 37 10310
TEST_TIME 38 10133
TEST_TIME 39 10179
TEST_TIME 40 10435
TEST_TIME 41 10284
TEST_TIME 42 10363
TEST_TIME 43 10212
TEST_TIME 44 10019
TEST_TIME 45 9970
TEST_TIME 46 9878
TEST_TIME 47 9794
TEST_TIME 48 9803
TEST_TIME 49 9813
TEST_TIME 50 9680
TEST_TIME 51 9713
TEST_TIME 52 9712
TEST_TIME 53 9678
TEST_TIME 54 9763
TEST_TIME 55 9743
TEST_TIME 56 9884
TEST_TIME 57 9879
TEST_TIME 58 9947
TEST_TIME 59 9938
TEST_TIME 60 9877
TEST_TIME 61 9995
TEST_TIME 62 10005
TEST_TIME 63 9969
TEST_TIME 64 9905
TEST_TIME 65 9795
TEST_TIME 66 9784
TEST_TIME 67 9755
TEST_TIME 68 9780
TEST_TIME 69 9745
TEST_TIME 70 9674
TEST_TIME 71 9611
TEST_TIME 72 9590
TEST_TIME 73 9507
TEST_TIME 74 9547
TEST_TIME 75 9561
TEST_TIME 76 9541
TEST_TIME 77 9532
TEST_TIME 78 9568
TEST_TIME 79 9541
TEST_TIME 80 9595
TEST_TIME 81 9719
TEST_TIME 82 9692
TEST_TIME 83 9777
TEST_TIME 84 9817
TEST_TIME 85 9806
TEST_TIME 86 9835
TEST_TIME 87 9804
TEST_TIME 88 9716
TEST_TIME 89 9650
TEST_TIME 90 9655
TEST_TIME 91 9569
TEST_TIME 92 9657
TEST_TIME 93 9609
TEST_TIME 94 9587
TEST_TIME 95 9653
TEST_TIME 96 9606
TEST_TIME 97 9657
TEST_TIME 98 9664
TEST_TIME 99 9764
TEST_TIME 100 9734
TEST_TIME 101 9744
TEST_TIME 102 9745
TEST_TIME 103 9784
TEST_TIME 104 9796
TEST_TIME 105 9778
TEST_TIME 106 9811
TEST_TIME 107 9826
TEST_TIME 108 9751
TEST_TIME 109 9735
TEST_TIME 110 9684
TEST_TIME 111 9716
TEST_TIME 112 9685
TEST_TIME 113 9664
TEST_TIME 114 9630
TEST_TIME 115 9626
TEST_TIME 116 9556
TEST_TIME 117 9541
TEST_TIME 118 9528
TEST_TIME 119 9531
TEST_TIME 120 9451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-11-2021 19:39:31 to 29-11-2021 19:39:35.

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 1555371 1555371 0 0.00 0 19939 6 0 0
doc_list_biud 1555367 1555367 0 0.00 0 64482 29 0 0
sp_add_invoice_to_stock 5017 4936 81 1.61 38 228817 18356 0 81
sp_cancel_adding_invoice 5974 5974 0 0.00 4 76741 854 0 0
sp_cancel_client_order 1661 1661 0 0.00 4 9501 364 0 0
sp_cancel_customer_reserve 590824 590824 0 0.00 1 30610 61 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 2 6368 74 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 2611 77 0 0
sp_cancel_supplier_invoice 6607 6602 5 0.07 2 13316 851 0 5
sp_cancel_supplier_order 408 408 0 0.00 5 1221165 112232 0 0
sp_cancel_write_off 9817 9817 0 0.00 1 19296 113 0 0
sp_client_order 20127 20127 0 0.00 3 22948 386 0 0
sp_customer_reserve 457246 457246 0 0.00 0 60041 179 0 0
sp_fill_shopping_cart 30840 30840 0 0.00 1 18535 40 0 0
sp_fill_shopping_cart_clo_res 457239 457239 0 0.00 0 18865 16 0 0
sp_get_clo_for_invoice 4929 4929 0 0.00 0 28496 378 0 0
sp_kill_cost_storno 16872 16872 0 0.00 0 10392 58 0 0
sp_kill_qstorno_ret_qs2qd 1198475 1198475 0 0.00 0 30606 33 0 0
sp_kill_qty_storno 630117 630117 0 0.00 0 64479 72 0 0
sp_lock_selected_doc 20542 20542 0 0.00 0 9326 8 0 0
sp_make_cost_storno 27365 27365 0 0.00 0 19604 60 0 0
sp_multiply_rows_for_pdistr 27365 27365 0 0.00 0 19928 31 0 0
sp_multiply_rows_for_qdistr 20127 20127 0 0.00 0 14259 162 0 0
sp_pay_from_customer 6868 6868 0 0.00 4 20495 141 0 0
sp_pay_to_supplier 5666 5666 0 0.00 5 30719 145 0 0
sp_payment_common 12534 12534 0 0.00 1 19624 50 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 9491 322 0 0
sp_qd_handle_on_invoice_upd_sts 10902 10902 0 0.00 0 64449 349 0 0
sp_qd_handle_on_reserve_upd_sts 9903 9903 0 0.00 0 20302 66 0 0
sp_reserve_write_off 9903 9903 0 0.00 6 22047 206 0 0
sp_supplier_invoice 5358 5358 0 0.00 6 92146 3748 0 0
sp_supplier_order 5374 5374 0 0.00 5 35383 1263 0 0
srv_aggregate_perf_data 121 120 1 0.82 2458 116714 13802 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1798 165220 48516 0 0
srv_find_qd_qs_mism 1085946 1085946 0 0.00 0 19351 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 46 133973 1195 0 0
srv_make_money_saldo 804 804 0 0.00 1 928 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 149157 391298 271169 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4937 4937 0 0.00 0 18535 208 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 7875 145 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 5227 13 0 0
t$perf-norm:sp_cancel_customer_reserve 733 733 0 0.00 0 1560 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 6488 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 440 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 15575 54 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 22848 1843 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 9229 19 0 0
t$perf-norm:sp_client_order 20127 20127 0 0.00 0 12790 25 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 10686 32 0 0
t$perf-norm:sp_pay_from_customer 6868 6868 0 0.00 0 13918 14 0 0
t$perf-norm:sp_pay_to_supplier 5666 5666 0 0.00 0 13504 17 0 0
t$perf-norm:sp_reserve_write_off 9903 9903 0 0.00 0 9223 13 0 0
t$perf-norm:sp_supplier_invoice 5358 5358 0 0.00 0 7714 20 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 4464 17 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 690 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1712 30 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 5248 40 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 4898 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10018 9856 162 1.61 0 5344 20 0 162
v_all_customers 36308 36308 0 0.00 0 15 1 0 0
v_all_suppliers 29808 29808 0 0.00 0 16 1 0 0
v_all_wares 40254 40254 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2973 10 0 0
v_cancel_client_order 3322 3322 0 0.00 0 4739 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2486 6 0 0
v_cancel_customer_reserve 1466 1466 0 0.00 0 1922 5 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1949 8 0 0
v_cancel_supplier_invoice 832 822 10 1.20 0 927 13 0 10
v_cancel_supplier_order 816 816 0 0.00 0 1494 7 0 0
v_cancel_write_off 1968 1968 0 0.00 0 6115 20 0 0
v_max_id_clo_ord 5372 5372 0 0.00 0 669 1 0 0
v_max_id_clo_res 987 987 0 0.00 0 5646 15 0 0
v_max_id_ord_sup 5341 5341 0 0.00 0 6385 6 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 6131 7 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 2276 5 0 0
v_min_id_clo_ord 5372 5372 0 0.00 0 13862 11 0 0
v_min_id_clo_res 987 987 0 0.00 0 3644 12 0 0
v_min_id_ord_sup 5341 5341 0 0.00 0 5873 10 0 0
v_min_non_paid_invoice 5666 5666 0 0.00 0 19589 36 0 0
v_min_non_paid_realizn 6868 6868 0 0.00 0 19593 47 0 0
v_our_firm 10146 10146 0 0.00 0 13 1 0 0
v_reserve_write_off 19806 19806 0 0.00 0 5027 7 0 0
x_lock_depdocs_on_canc_invoice 12165 12165 0 0.00 0 15624 115 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 27643 495 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 1 35374 1174 0 0
x_make_qstorno_1200_2000 5358 5358 0 0.00 3 92063 3611 0 0
x_make_qstorno_2100_3300 457246 457246 0 0.00 0 60038 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2021 19:39:36 to 29-11-2021 19:39:40.

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:08:03 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:04 32268 8192000000 9905806720 10216288256 980541968 10.689 100 86 172 0 1009208480 6078464 22588048
17:10:41 23233 8192000000 9955810336 10278854656 1034596288 11.213 100 89 178 0 1065601584 8400416 22604880
17:12:08 15452 8192000000 9996632960 10332499968 1074238016 11.593 100 87 175 1 1105569408 9761264 21570128
17:13:31 8798 8192000000 10056788608 10407170048 1140480672 12.220 100 84 168 0 1171870416 11810912 19578832
17:14:43 8161 8192000000 10088477600 10450489344 1176074128 12.554 100 82 163 2 1207698880 12794608 18830144
17:15:53 4068 8192000000 10133292704 10505031680 1220554896 12.967 100 87 174 0 1254013008 13721904 19736208
17:17:00 2658 8192000000 10169797856 10549628928 1255880656 13.292 100 83 168 0 1287186752 12690272 18615824
17:18:04 1800 8192000000 10214426336 10597085184 1292517792 13.627 100 87 174 1 1326740064 14716128 19506144
17:19:08 10484 8192000000 10226482736 10617020416 1310508912 13.791 100 70 138 1 1337742672 12003536 15230224
17:20:19 3083 8192000000 10201871104 10616102912 1314194672 13.824 100 42 84 1 1330173696 6374608 9604416
17:21:25 9122 8192000000 10221474880 10628108288 1321103024 13.887 100 22 44 1 1332639232 6955296 4580912
17:22:37 3639 8192000000 10218495472 10634637312 1323200656 13.906 100 36 70 2 1343060064 11783584 8075824
17:23:43 48011 8192000000 10230813792 10644267008 1332806368 13.993 100 35 70 0 1346616608 5154432 8655808
17:25:35 3340 8192000000 10232665120 10651877376 1335412224 14.016 100 36 72 0 1352172320 8797824 7962272
17:26:40 32178 8192000000 10239709136 10654814208 1335904720 14.020 100 52 103 3 1354353584 5215904 13232960
17:28:14 6749 8192000000 10216572112 10635919360 1316810128 13.848 99 13 25 1 1325116032 5460192 2845712
17:29:22 5745 8192000000 10175213808 10583638016 1272744000 13.447 99 30 60 1 1285152240 5325440 7082800
17:30:30 23548 8192000000 10147171264 10541281280 1196703184 12.746 94 63 126 0 1218914192 5962192 16248816
17:31:55 3746 8192000000 10132611584 10529566720 1239255504 13.139 100 33 65 0 1254164496 6192864 8716128
17:33:01 10162 8192000000 10162312592 10561994752 1265933248 13.384 100 29 57 1 1278265872 6030416 6302208
17:34:12 9911 8192000000 10186301920 10595303424 1292914432 13.631 100 23 46 2 1304084592 5374400 5795760
17:35:24 26349 8192000000 10174582224 10587996160 1285976080 13.568 100 19 38 0 1295554208 5347088 4231040
17:36:54 6597 8192000000 10192487648 10606350336 1298987264 13.686 100 26 52 1 1311092544 6129088 5976192
17:38:03 5185 8192000000 10228994048 10632945664 1316466720 13.845 100 52 102 0 1338253648 9102048 12684880
17:39:16 34397 8192000000 10251237808 10661158912 1342939488 14.084 100 45 90 2 1362806240 9881488 9985264
17:40:58 123140 8192000000 10281218528 10704818176 1365204000 14.284 100 41 82 0 1387364192 12558048 9602144
17:44:14 5048 8192000000 10287060304 10712526848 1378566368 14.404 100 48 94 1 1404177536 14021008 11590160
17:45:23 8826 8192000000 10303575200 10722848768 1387404848 14.483 100 69 139 1 1418488432 15158160 15925424
17:46:34 4732 8192000000 10318448384 10735845376 1395271136 14.553 100 59 118 1 1427894000 18978352 13644512
17:47:42 19520 8192000000 10316314880 10743521280 1395273568 14.553 100 51 102 1 1429371648 21011552 13086528
17:49:05 2136 8192000000 10350018800 10768101376 1419924240 14.772 100 75 150 1 1460062912 21995712 18142960
17:50:11 3586 8192000000 10345779440 10759999488 1404806896 14.638 100 76 152 0 1452289232 28827312 18655024
17:51:19 1798 8192000000 10330263936 10739351552 1385350368 14.464 100 87 174 1 1438206048 30805408 22050272
17:52:25 5588 8192000000 10335763808 10745110528 1389455232 14.501 100 79 158 4 1438558192 28610112 20492848
17:53:38 5544 8192000000 10321898288 10735476736 1381119440 14.427 100 68 134 2 1428974928 30604224 17251264
17:54:50 3272 8192000000 10355304288 10758729728 1389622256 14.502 100 82 162 1 1451283936 40792736 20868944
17:55:58 2488 8192000000 10360149296 10766786560 1398931632 14.585 100 83 166 2 1466407136 44403584 23071920
17:57:07 2935 8192000000 10361257696 10761842688 1386522272 14.475 100 85 171 1 1460103840 50703664 22877904
17:58:17 4082 8192000000 10361814256 10771013632 1379981968 14.416 99 85 170 0 1457702640 55277552 22443120
17:59:26 3880 8192000000 10343179008 10760658944 1378892928 14.407 100 55 110 1 1445033232 51390784 14749520
18:00:32 8474 8192000000 10338612864 10759938048 1381182800 14.427 100 48 96 1 1441137856 46818528 13136528
18:01:51 17392 8192000000 10337124736 10764144640 1389586432 14.502 100 34 68 1 1441946272 42385392 9974448
18:03:26 30248 8192000000 10325660304 10766094336 1369075216 14.319 99 24 48 0 1428118704 51868576 7174912
18:05:14 41716 8192000000 10310680192 10760638464 1351950112 14.165 99 15 30 2 1406360320 49635696 4774512
18:06:58 13057 8192000000 10299356992 10741207040 1356466656 14.206 99 15 30 1 1396240592 36022576 3751360
18:08:21 108131 8192000000 10275128304 10699034624 1327183552 13.942 97 36 70 3 1343385648 8181440 8020656
18:11:12 67394 8192000000 10265138208 10713247744 1317803008 13.857 96 27 54 1 1333435184 10038672 5593504
18:13:22 18892 8192000000 10274167312 10717749248 1358200080 14.221 99 22 44 0 1374707184 11483296 5023808
18:14:42 18215 8192000000 10295673600 10740629504 1382218080 14.436 100 15 30 0 1402705024 17223952 3262992
18:16:01 28058 8192000000 10328495600 10768531456 1397783392 14.575 100 38 76 0 1425329840 18577600 8968848
18:17:33 25726 8192000000 10314891424 10775629824 1394993216 14.550 100 12 24 0 1423231008 25286064 2951728
18:19:23 41978 8192000000 10294912144 10752684032 1392176288 14.525 100 11 22 0 1408724928 14162496 2386144
18:21:18 74506 8192000000 10286008288 10742673408 1364055312 14.274 98 19 36 2 1376018400 6702064 5261024
18:23:51 80213 8192000000 10299258000 10762231808 1400137392 14.596 100 10 20 0 1413604704 11079184 2388128
18:26:58 45388 8192000000 10293953648 10762436608 1388960704 14.497 100 11 22 0 1406970624 15292144 2717776
18:29:35 59457 8192000000 10292449936 10776375296 1385113744 14.462 99 11 22 0 1402642560 14539040 2989776
18:31:35 65487 8192000000 10313851040 10783297536 1398881600 14.585 99 15 30 2 1414315056 12001728 3431728
18:33:41 47014 8192000000 10306545424 10773729280 1372894864 14.353 97 11 22 1 1386725280 10892528 2937888
18:35:42 71011 8192000000 10318745520 10785173504 1394794592 14.549 99 22 44 0 1414961936 15096576 5070768
18:37:53 41961 8192000000 10317418352 10766544896 1373014704 14.354 97 26 52 1 1395326256 16661904 5649648
18:39:43 96232 8192000000 10281029072 10735558656 1276140272 13.478 92 14 28 1 1296105776 17298208 2667296
18:42:27 106802 8192000000 10295914048 10738565120 1353476640 14.179 98 47 94 0 1374796992 10478464 10841888
18:45:14 72355 8192000000 10238449216 10696605696 1293140160 13.633 94 36 72 0 1317387936 15585936 8661840
18:47:27 36885 8192000000 10244162704 10696409088 1314240848 13.825 97 29 58 1 1335013104 14376192 6396064
18:49:04 84527 8192000000 10242810320 10691878912 1310193632 13.788 97 12 25 0 1326319760 12825296 3300832
18:51:38 46512 8192000000 10241207760 10702151680 1329339552 13.961 99 14 28 1 1343370496 10516048 3514896
18:53:40 97457 8192000000 10262945280 10708058112 1302375744 13.717 96 12 24 1 1317221984 12219488 2626752
18:56:24 82655 8192000000 10262824784 10701549568 1283194400 13.542 95 21 42 1 1303536192 15495312 4846480
18:58:48 121544 8192000000 10262350560 10712096768 1303152496 13.724 96 7 14 0 1316202032 11429616 1619920
19:01:50 165220 8192000000 10278852688 10721939456 1320533888 13.882 96 15 29 1 1338363792 14814016 3015888
19:05:37 94772 8192000000 10286656880 10740600832 1373964192 14.363 100 18 36 0 1387092288 9039920 4088176
19:08:46 72507 8192000000 10297232064 10764623872 1390471040 14.510 100 10 18 2 1412968896 20601456 1896400
19:10:59 21555 8192000000 10290151104 10770124800 1391394272 14.518 100 20 38 1 1408073904 12051984 4627648
19:12:59 63295 8192000000 10304256864 10759630848 1391770704 14.522 99 13 26 1 1403985216 9453072 2761440
19:15:03 103725 8192000000 10307292144 10768035840 1363053344 14.265 97 17 34 0 1378423520 11463360 3906816
19:17:48 52564 8192000000 10324373952 10770092032 1390892416 14.514 99 19 38 1 1407138160 11550000 4695744
19:19:49 29903 8192000000 10336789744 10779430912 1400233344 14.597 99 43 86 1 1420897040 10806160 9857536
19:21:20 77570 8192000000 10313684864 10775277568 1385682672 14.467 99 17 34 0 1406479872 16900336 3896864
19:23:40 84750 8192000000 10330247552 10794491904 1388762816 14.495 98 28 56 1 1414931856 19864640 6304400
19:26:08 120960 8192000000 10306882240 10765307904 1349086864 14.139 95 28 56 1 1365672192 10180720 6404608
19:29:09 31043 8192000000 10302712224 10781630464 1397395168 14.572 100 9 17 1 1417573168 18518208 1659792
19:30:55 70418 8192000000 10289362816 10748289024 1356914992 14.210 97 11 22 0 1367041760 7616272 2510496
19:33:07 63679 8192000000 10277498256 10743517184 1354867280 14.191 97 11 22 0 1366812240 8691536 3253424
19:35:11 20835 8192000000 10284676272 10744627200 1375087232 14.373 99 16 30 2 1388921968 10131088 3703648
19:36:34 49659 8192000000 10280394640 10741268480 1348712144 14.136 97 17 34 3 1365729072 12754656 4262272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-11-2021 19:39:41 to 29-11-2021 19:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 81 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 29-11-2021 19:39:57 to 29-11-2021 19:39:59.

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          235377
MON$OLDEST_ACTIVE               237137
MON$OLDEST_SNAPSHOT             237137
MON$NEXT_TRANSACTION            237137
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                       2039200
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:539: 
:540: mscs-test-161.budzdorov.ru    Mon Nov 29 19:38:05 2021
:541:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:542: 
:543: 
:544: mscs-test-161.budzdorov.ru    Mon Nov 29 19:38:09 2021
:545:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:546: 
:547: 
:548: mscs-test-161.budzdorov.ru    Mon Nov 29 19:38:09 2021
:549:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:550: 
--- end of diff output ---

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

29.11.21 19:40:00. 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-11-29 19:40:03 No severe PSQL-related problems occured
29.11.21 19:40:04. End of report.