Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.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 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 16494964 kB
MemAvailable 31445716 kB
Buffers 0 kB
Cached 15145356 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.9
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-12-08 16:38:00
DTS_END                         2021-12-08 16:38:00.7140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40038
TRN_ID                          246436
ATT_ID                          701
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: 9325.49 22783 1119059
customer order: creation 177.63 323 21316
customer order: refuse 14.81 344 1777
order to supplier: creation 47.75 1234 5730
order to supplier: removal 3.45 100878 414
invoice (draft): creation 48.78 3222 5854
invoice (draft): removal 53.22 823 6386
invoice accept: apply 42.80 16978 5136
invoice accept: cancel 48.25 850 5790
customer reserve: creation 3870.34 171 464441
customer reserve: removal 4713.95 57 565674
realization accept: apply 87.19 180 10463
realization accept: cancel 81.98 109 9837
payment from customer: creation 62.32 102 7478
payment from customer: removal 5.62 92 674
payment to supplier: creation 51.06 125 6127
payment to supplier: removal 3.67 105 440
service: total inventory turnovers 4.53 1246 543
service: total monetary turnovers 7.27 27 872
service: aggregate perf. data 0.86 19524 103
service: refresh index statistics 0.03 309265 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2021 16:38:59 to 08-12-2021 16:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10734
TEST_TIME 2 8516
TEST_TIME 3 6642
TEST_TIME 4 6628
TEST_TIME 5 6036
TEST_TIME 6 5833
TEST_TIME 7 6062
TEST_TIME 8 5682
TEST_TIME 9 6043
TEST_TIME 10 5980
TEST_TIME 11 6782
TEST_TIME 12 7111
TEST_TIME 13 7167
TEST_TIME 14 7048
TEST_TIME 15 7434
TEST_TIME 16 7421
TEST_TIME 17 7566
TEST_TIME 18 7633
TEST_TIME 19 8163
TEST_TIME 20 8740
TEST_TIME 21 8779
TEST_TIME 22 9327
TEST_TIME 23 9300
TEST_TIME 24 9122
TEST_TIME 25 9870
TEST_TIME 26 10044
TEST_TIME 27 10617
TEST_TIME 28 11073
TEST_TIME 29 10911
TEST_TIME 30 10897
TEST_TIME 31 10851
TEST_TIME 32 10809
TEST_TIME 33 10668
TEST_TIME 34 10616
TEST_TIME 35 10735
TEST_TIME 36 10747
TEST_TIME 37 10774
TEST_TIME 38 10766
TEST_TIME 39 10705
TEST_TIME 40 10624
TEST_TIME 41 10484
TEST_TIME 42 10341
TEST_TIME 43 10309
TEST_TIME 44 10180
TEST_TIME 45 10111
TEST_TIME 46 10072
TEST_TIME 47 10054
TEST_TIME 48 10028
TEST_TIME 49 9987
TEST_TIME 50 9907
TEST_TIME 51 9797
TEST_TIME 52 9712
TEST_TIME 53 9740
TEST_TIME 54 9749
TEST_TIME 55 9720
TEST_TIME 56 9666
TEST_TIME 57 9610
TEST_TIME 58 9523
TEST_TIME 59 9377
TEST_TIME 60 9392
TEST_TIME 61 9367
TEST_TIME 62 9381
TEST_TIME 63 9419
TEST_TIME 64 9594
TEST_TIME 65 9628
TEST_TIME 66 9613
TEST_TIME 67 9615
TEST_TIME 68 9635
TEST_TIME 69 9612
TEST_TIME 70 9622
TEST_TIME 71 9746
TEST_TIME 72 9741
TEST_TIME 73 9746
TEST_TIME 74 9754
TEST_TIME 75 9736
TEST_TIME 76 9624
TEST_TIME 77 9651
TEST_TIME 78 9587
TEST_TIME 79 9524
TEST_TIME 80 9542
TEST_TIME 81 9519
TEST_TIME 82 9456
TEST_TIME 83 9587
TEST_TIME 84 9542
TEST_TIME 85 9501
TEST_TIME 86 9483
TEST_TIME 87 9474
TEST_TIME 88 9448
TEST_TIME 89 9510
TEST_TIME 90 9582
TEST_TIME 91 9605
TEST_TIME 92 9650
TEST_TIME 93 9652
TEST_TIME 94 9649
TEST_TIME 95 9643
TEST_TIME 96 9630
TEST_TIME 97 9644
TEST_TIME 98 9671
TEST_TIME 99 9724
TEST_TIME 100 9630
TEST_TIME 101 9627
TEST_TIME 102 9581
TEST_TIME 103 9541
TEST_TIME 104 9491
TEST_TIME 105 9508
TEST_TIME 106 9489
TEST_TIME 107 9506
TEST_TIME 108 9461
TEST_TIME 109 9455
TEST_TIME 110 9463
TEST_TIME 111 9455
TEST_TIME 112 9445
TEST_TIME 113 9432
TEST_TIME 114 9460
TEST_TIME 115 9453
TEST_TIME 116 9448
TEST_TIME 117 9445
TEST_TIME 118 9459
TEST_TIME 119 9408
TEST_TIME 120 9333

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-12-2021 16:39:02 to 08-12-2021 16:39:06.

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 1550248 1550248 0 0.00 0 13977 6 0 0
doc_list_biud 1550247 1550247 0 0.00 0 68705 27 0 0
sp_add_invoice_to_stock 5247 5136 111 2.11 71 191864 16979 0 111
sp_cancel_adding_invoice 5790 5790 0 0.00 5 65837 850 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 13262 344 0 0
sp_cancel_customer_reserve 565674 565674 0 0.00 1 18308 58 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 8290 92 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 6954 105 0 0
sp_cancel_supplier_invoice 6399 6386 13 0.20 2 68709 824 0 13
sp_cancel_supplier_order 414 414 0 0.00 6 953018 100879 0 0
sp_cancel_write_off 9837 9837 0 0.00 1 12476 109 0 0
sp_client_order 21316 21316 0 0.00 3 18323 324 0 0
sp_customer_reserve 464441 464441 0 0.00 0 78777 171 0 0
sp_fill_shopping_cart 32900 32900 0 0.00 1 11752 39 0 0
sp_fill_shopping_cart_clo_res 464431 464431 0 0.00 0 13309 13 0 0
sp_get_clo_for_invoice 5124 5124 0 0.00 0 21326 308 0 0
sp_kill_cost_storno 16741 16741 0 0.00 0 11650 59 0 0
sp_kill_qstorno_ret_qs2qd 1147985 1147985 0 0.00 0 68701 32 0 0
sp_kill_qty_storno 605464 605464 0 0.00 0 68704 68 0 0
sp_lock_selected_doc 21535 21535 0 0.00 0 9206 6 0 0
sp_make_cost_storno 29192 29192 0 0.00 0 13500 50 0 0
sp_multiply_rows_for_pdistr 29191 29191 0 0.00 0 11009 26 0 0
sp_multiply_rows_for_qdistr 21316 21316 0 0.00 0 18313 146 0 0
sp_pay_from_customer 7478 7478 0 0.00 4 13790 103 0 0
sp_pay_to_supplier 6127 6127 0 0.00 4 18842 125 0 0
sp_payment_common 13605 13605 0 0.00 1 12949 25 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 13250 306 0 0
sp_qd_handle_on_invoice_upd_sts 10913 10913 0 0.00 0 11453 320 0 0
sp_qd_handle_on_reserve_upd_sts 10463 10463 0 0.00 0 16735 60 0 0
sp_reserve_write_off 10463 10463 0 0.00 6 16747 180 0 0
sp_supplier_invoice 5854 5854 0 0.00 6 95388 3223 0 0
sp_supplier_order 5730 5730 0 0.00 5 29970 1235 0 0
srv_aggregate_perf_data 103 103 0 0.00 2987 205304 19525 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1403 147765 46334 0 0
srv_find_qd_qs_mism 1070497 1070497 0 0.00 0 11556 5 0 0
srv_make_invnt_saldo 543 543 0 0.00 47 98858 1246 0 0
srv_make_money_saldo 872 872 0 0.00 1 2684 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 183501 600986 309265 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5138 5138 0 0.00 0 12795 174 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 5168 111 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 3716 17 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 3782 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1768 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 2505 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 1383 14 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 22671 1578 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 2058 9 0 0
t$perf-norm:sp_client_order 21316 21316 0 0.00 0 7201 20 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 3275 8 0 0
t$perf-norm:sp_pay_from_customer 7478 7478 0 0.00 0 5063 11 0 0
t$perf-norm:sp_pay_to_supplier 6127 6127 0 0.00 0 7706 13 0 0
t$perf-norm:sp_reserve_write_off 10463 10463 0 0.00 0 7657 11 0 0
t$perf-norm:sp_supplier_invoice 5854 5854 0 0.00 0 7400 24 0 0
t$perf-norm:sp_supplier_order 5730 5730 0 0.00 0 9036 22 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 258 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 42 2 0 0
t$perf-norm:srv_make_invnt_saldo 543 543 0 0.00 0 2392 26 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 1290 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10468 10246 222 2.12 0 8027 22 0 222
v_all_customers 39070 39070 0 0.00 0 203 1 0 0
v_all_suppliers 31812 31812 0 0.00 0 203 1 0 0
v_all_wares 42632 42632 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 2068 10 0 0
v_cancel_client_order 3554 3554 0 0.00 0 3446 9 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 5835 13 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 967 4 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 3114 7 0 0
v_cancel_supplier_invoice 924 898 26 2.81 0 7657 29 0 26
v_cancel_supplier_order 828 828 0 0.00 0 2035 10 0 0
v_cancel_write_off 2068 2068 0 0.00 0 6653 25 0 0
v_max_id_clo_ord 5730 5730 0 0.00 0 11165 4 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 3438 14 0 0
v_max_id_ord_sup 5854 5854 0 0.00 0 3847 4 0 0
v_max_non_paid_invoice 1805 1805 0 0.00 0 229 2 0 0
v_max_non_paid_realizn 3903 3903 0 0.00 0 4759 5 0 0
v_min_id_clo_ord 5730 5730 0 0.00 0 5157 10 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 1823 5 0 0
v_min_id_ord_sup 5854 5854 0 0.00 0 5546 9 0 0
v_min_non_paid_invoice 6127 6127 0 0.00 0 7354 16 0 0
v_min_non_paid_realizn 7478 7478 0 0.00 0 12946 20 0 0
v_our_firm 10712 10712 0 0.00 0 18 1 0 0
v_reserve_write_off 20926 20926 0 0.00 0 3532 8 0 0
x_lock_depdocs_on_canc_invoice 11727 11727 0 0.00 0 16532 130 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 6029 344 0 0
x_make_qstorno_1000_1200 5730 5730 0 0.00 1 29963 1148 0 0
x_make_qstorno_1200_2000 5854 5854 0 0.00 3 95273 3089 0 0
x_make_qstorno_2100_3300 464441 464441 0 0.00 0 78777 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2021 16:39:07 to 08-12-2021 16:39:11.

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:08:04 13 8192000000 8811445152 8826757120 16033504 0.195 4 4 5 0 16260224 87456 139264
14:09:05 18107 8192000000 9901780768 10215993344 987719456 10.759 100 90 180 0 1017390528 6126400 23544672
14:10:27 22405 8192000000 9956375168 10284826624 1039464352 11.260 100 85 170 0 1068961536 7961184 21536000
14:11:54 15055 8192000000 9991834000 10326990848 1073809424 11.588 100 85 170 0 1104475072 9545424 21120224
14:13:12 10526 8192000000 10045938160 10397073408 1124799344 12.072 100 75 150 0 1157768288 14898976 18069968
14:14:25 8870 8192000000 10082776016 10443341824 1173633536 12.531 100 79 158 0 1203826928 11766448 18426944
14:15:41 5479 8192000000 10137132304 10507083776 1221213680 12.973 100 89 178 0 1254958800 13771504 19973616
14:16:49 2950 8192000000 10170165216 10545217536 1254108496 13.276 100 91 178 0 1287087648 12290096 20689056
14:17:53 1657 8192000000 10198508432 10580348928 1284234992 13.552 100 91 182 1 1318641840 14728832 19678016
14:18:55 1379 8192000000 10218399952 10605211648 1300309200 13.698 100 98 198 1 1335234000 12977728 21947072
14:19:59 15372 8192000000 10202363616 10614960128 1308854432 13.776 100 47 95 0 1327084560 7546432 10683696
14:21:21 7143 8192000000 10207369904 10625937408 1316509088 13.845 100 32 62 0 1331263408 8145616 6608704
14:22:29 8766 8192000000 10203211296 10625695744 1320249552 13.879 100 17 34 1 1329761376 5671200 3840624
14:23:40 10398 8192000000 10220575248 10638536704 1325653408 13.928 100 17 32 0 1339340816 9924672 3762736
14:24:51 37142 8192000000 10216973072 10645348352 1330234400 13.969 100 27 54 0 1342500080 5606720 6658960
14:26:31 4561 8192000000 10225536560 10651086848 1323849168 13.912 99 25 48 0 1335291056 5785728 5656160
14:27:36 9463 8192000000 10213795456 10629586944 1303267040 13.725 99 22 44 1 1313710848 5786640 4657168
14:28:47 6835 8192000000 10157419232 10563014656 1230318848 13.057 96 19 37 1 1240676288 5664304 4693136
14:29:55 41090 8192000000 10163530448 10571902976 1207195984 12.843 94 12 24 1 1214793168 4826672 2770512
14:31:39 4813 8192000000 10132072288 10526068736 1214859088 12.914 98 31 62 0 1228576576 5691408 8026080
14:32:45 11876 8192000000 10163846304 10566004736 1255973744 13.293 99 21 42 2 1265913472 4974288 4965440
14:34:02 61327 8192000000 10176738960 10593587200 1285643600 13.565 100 18 36 0 1299650256 9536864 4469792
14:36:04 9181 8192000000 10189338112 10601259008 1299059120 13.687 100 28 55 6 1312086592 7255168 5772304
14:37:15 9130 8192000000 10193927280 10612514816 1304550848 13.737 100 21 41 1 1315025440 5249616 5224976
14:38:32 9070 8192000000 10237551024 10641768448 1327759920 13.947 100 55 110 2 1348900464 7973904 13166640
14:39:47 57270 8192000000 10260424864 10684874752 1357464848 14.215 100 41 82 2 1376506304 9616416 9425040
14:41:51 10992 8192000000 10284749968 10702364672 1359627744 14.234 99 57 113 1 1388478592 15952656 12898192
14:43:18 29244 8192000000 10297367264 10724397056 1386267264 14.473 100 40 79 0 1413387520 17467056 9653200
14:45:01 15048 8192000000 10308710752 10731495424 1386471616 14.474 100 46 92 1 1418664624 20686240 11506768
14:46:20 20532 8192000000 10336395984 10761347072 1406883072 14.656 100 44 88 0 1441419584 23776944 10759568
14:47:45 10139 8192000000 10335917392 10757152768 1397826960 14.576 99 51 101 2 1439055952 27644976 13584016
14:49:02 3049 8192000000 10343820608 10756440064 1387813280 14.486 99 72 143 0 1443149792 36568192 18768320
14:50:10 7784 8192000000 10342022256 10753269760 1394064480 14.542 100 82 164 2 1446316352 32984592 19267280
14:51:24 11197 8192000000 10338020336 10760462336 1387851152 14.487 99 53 106 2 1434143152 33161216 13130784
14:52:38 6042 8192000000 10345640656 10764308480 1399063888 14.587 99 64 127 2 1452883776 38046608 15773280
14:53:54 6206 8192000000 10349435344 10762461184 1397454336 14.572 100 70 135 1 1449170768 35193312 16523120
14:55:09 2559 8192000000 10355162768 10757918720 1381936704 14.434 99 84 168 0 1448511888 45798944 20776240
14:56:17 1403 8192000000 10363618032 10764427264 1387151536 14.480 100 88 173 3 1458239792 49769888 21318368
14:57:22 6134 8192000000 10348280112 10770669568 1392392080 14.527 100 46 92 1 1454856976 49915008 12549888
14:58:39 4868 8192000000 10346316784 10764718080 1376788512 14.388 100 65 130 0 1450969232 57256224 16924496
14:59:51 7526 8192000000 10320018240 10727616512 1341909056 14.075 98 63 124 5 1406883344 48197504 16776784
15:01:02 28006 8192000000 10304164976 10730266624 1360950352 14.246 99 28 56 3 1406431216 37495120 7985744
15:02:34 12821 8192000000 10318363232 10738499584 1353805488 14.182 99 38 75 0 1409725456 45755680 10164288
15:03:51 43980 8192000000 10312507264 10746753024 1374549824 14.368 100 22 42 2 1418111280 37238576 6322880
15:05:39 47078 8192000000 10281970592 10727428096 1345238384 14.105 99 12 24 0 1384920416 36120976 3561056
15:07:43 51125 8192000000 10271538960 10701721600 1310950368 13.795 96 32 65 1 1327651696 8993136 7708192
15:09:36 82017 8192000000 10279635760 10725715968 1370647056 14.333 100 12 24 0 1381452960 8472784 2333120
15:11:59 72528 8192000000 10295718032 10751574016 1360307392 14.240 98 14 28 1 1376110320 12892304 2910624
15:14:12 44741 8192000000 10303096192 10753306624 1391561856 14.520 100 13 26 1 1410597328 16349728 2685744
15:16:00 114521 8192000000 10304624208 10748776448 1337018128 14.031 96 14 28 1 1358491632 18147648 3325856
15:18:55 54476 8192000000 10301322176 10750918656 1379143632 14.409 99 26 52 0 1397945904 12305200 6497072
15:20:49 127593 8192000000 10310112016 10765475840 1380683968 14.423 99 18 36 1 1402495360 17649504 4161888
15:23:58 23428 8192000000 10314331264 10761883648 1401792816 14.611 100 33 64 3 1417677648 8250640 7634192
15:25:28 37061 8192000000 10319899696 10770206720 1388607840 14.493 99 15 30 0 1399931696 8168592 3155264
15:27:08 63715 8192000000 10288581264 10744463360 1353148656 14.176 97 16 32 0 1368988576 12287456 3552464
15:29:12 78575 8192000000 10308547152 10764939264 1383049424 14.444 99 14 27 0 1396349520 10800624 2499472
15:31:31 50843 8192000000 10289018384 10762727424 1390571792 14.511 100 14 28 1 1407286992 13331104 3384096
15:34:14 57560 8192000000 10287500416 10751422464 1378030960 14.399 99 15 30 2 1391680736 10435248 3214528
15:36:13 45196 8192000000 10247297792 10707316736 1346803072 14.119 99 12 24 0 1360656528 11425072 2428384
15:38:43 44075 8192000000 10266313904 10722615296 1346270624 14.114 98 12 24 0 1354152352 5723136 2158592
15:40:40 56205 8192000000 10245538192 10696900608 1277102096 13.487 94 13 26 0 1301182400 20791424 3288880
15:42:38 58462 8192000000 10271064912 10723692544 1320040592 13.877 97 14 28 1 1343027328 20058048 2928688
15:45:03 46653 8192000000 10268561056 10716741632 1342820400 14.083 99 21 42 2 1363468720 16063552 4584768
15:46:52 25009 8192000000 10283960352 10712596480 1335168288 14.014 98 57 114 3 1358227968 10815632 12244048
15:48:19 40048 8192000000 10286867664 10727813120 1363898256 14.272 100 46 92 0 1395553664 21756080 9899328
15:49:59 77186 8192000000 10278421200 10718867456 1317817616 13.857 96 26 52 0 1333619744 10479728 5322400
15:52:45 28202 8192000000 10285257472 10727878656 1328371952 13.952 97 32 64 1 1347444672 11569264 7503456
15:54:18 131716 8192000000 10265692880 10725289984 1327935008 13.948 97 15 32 0 1343728096 12679680 3113408
15:58:47 75161 8192000000 10264581168 10728022016 1353148752 14.176 99 10 20 0 1374824288 19225760 2449776
16:01:03 70360 8192000000 10270189760 10750611456 1366088432 14.292 100 16 32 2 1398000976 27730832 4181712
16:03:13 54915 8192000000 10295173424 10738540544 1378342144 14.402 100 35 69 3 1396213184 9311568 8559472
16:05:10 78134 8192000000 10285680080 10735292416 1347918576 14.129 97 15 30 0 1367048688 15678944 3451168
16:07:29 42174 8192000000 10292460000 10750504960 1373404768 14.358 99 24 48 0 1392900256 13742336 5753152
16:09:11 83929 8192000000 10292790160 10753236992 1350348560 14.151 97 16 32 0 1365756352 11984416 3423376
16:11:43 65753 8192000000 10310474720 10766598144 1383622416 14.449 99 9 17 0 1394063232 8597248 1843568
16:14:01 43992 8192000000 10295767792 10748010496 1364761088 14.280 98 28 56 1 1379970048 9071488 6137472
16:15:46 66570 8192000000 10309887584 10770997248 1380619376 14.422 99 10 20 0 1403981296 21152832 2209088
16:17:52 147765 8192000000 10283924528 10745204736 1332607904 13.991 96 28 57 5 1351583296 11455584 7519808
16:21:22 41955 8192000000 10294373472 10758111232 1389798768 14.504 100 28 56 1 1408300592 11825280 6676544
16:23:09 72247 8192000000 10278336752 10743595008 1343641632 14.090 97 15 29 0 1362926528 15827488 3457408
16:25:22 43108 8192000000 10266203840 10732769280 1349559904 14.144 98 17 34 0 1368766352 14841248 4365200
16:27:17 57762 8192000000 10252378144 10712645632 1317037472 13.850 96 13 26 1 1332781568 12395680 3348416
16:29:23 41692 8192000000 10253932400 10706284544 1303253936 13.725 95 15 30 1 1314713840 7905632 3554272
16:31:07 26353 8192000000 10254759568 10702708736 1325054528 13.922 98 30 60 0 1342330080 10343360 6932192
16:32:34 39128 8192000000 10268403856 10712043520 1333059696 13.995 98 21 42 1 1353056592 14824368 5172528
16:34:13 89672 8192000000 10262650896 10715344896 1328457856 13.953 98 33 66 0 1353073776 17420304 7195616
16:36:47 26279 8192000000 10275228896 10717757440 1360224400 14.239 100 40 80 1 1378009984 9171936 8613648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2021 16:39:13 to 08-12-2021 16:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-12-2021 16:39:29 to 08-12-2021 16:39:30.

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          245912
MON$OLDEST_ACTIVE               248775
MON$OLDEST_SNAPSHOT             248775
MON$NEXT_TRANSACTION            248775
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                       2051872
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:86: 
:87: mscs-test-161.budzdorov.ru    Wed Dec  8 16:38:02 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Wed Dec  8 16:38:07 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Wed Dec  8 16:38:10 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:97: 
--- end of diff output ---

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

08.12.21 16:39:31. 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-12-08 16:39:34 No severe PSQL-related problems occured
08.12.21 16:39:35. End of report.