Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.09.2021 01: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 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 16177400 kB
MemAvailable 30881552 kB
Buffers 0 kB
Cached 15531736 kB
SwapCached 384 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-09-10 01:37:58
DTS_END                         2021-09-10 01:37:58.4310
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/37442
TRN_ID                          240417
ATT_ID                          698
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: 9549.80 19695 1145976
customer order: creation 173.92 342 20870
customer order: refuse 15.13 328 1816
order to supplier: creation 46.41 1262 5569
order to supplier: removal 3.47 108097 416
invoice (draft): creation 46.48 2982 5578
invoice (draft): removal 54.72 881 6566
invoice accept: apply 42.17 16357 5060
invoice accept: cancel 49.13 812 5896
customer reserve: creation 3939.30 159 472716
customer reserve: removal 4880.72 60 585686
realization accept: apply 85.00 177 10200
realization accept: cancel 81.82 108 9818
payment from customer: creation 59.74 114 7169
payment from customer: removal 6.03 72 723
payment to supplier: creation 49.61 116 5953
payment to supplier: removal 3.73 64 448
service: total inventory turnovers 4.73 1076 568
service: total monetary turnovers 6.83 30 819
service: aggregate perf. data 0.84 17997 101
service: refresh index statistics 0.03 242861 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-09-2021 01:39:46 to 10-09-2021 01:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1662
TEST_TIME 2 3082
TEST_TIME 3 3923
TEST_TIME 4 4069
TEST_TIME 5 4848
TEST_TIME 6 4740
TEST_TIME 7 5051
TEST_TIME 8 5208
TEST_TIME 9 5338
TEST_TIME 10 5639
TEST_TIME 11 5979
TEST_TIME 12 6310
TEST_TIME 13 7010
TEST_TIME 14 7198
TEST_TIME 15 7116
TEST_TIME 16 7427
TEST_TIME 17 7459
TEST_TIME 18 8093
TEST_TIME 19 7924
TEST_TIME 20 8141
TEST_TIME 21 8750
TEST_TIME 22 9242
TEST_TIME 23 9370
TEST_TIME 24 9638
TEST_TIME 25 9719
TEST_TIME 26 9653
TEST_TIME 27 10139
TEST_TIME 28 11268
TEST_TIME 29 11329
TEST_TIME 30 11208
TEST_TIME 31 11188
TEST_TIME 32 10951
TEST_TIME 33 10821
TEST_TIME 34 10614
TEST_TIME 35 10447
TEST_TIME 36 10330
TEST_TIME 37 10426
TEST_TIME 38 10467
TEST_TIME 39 10410
TEST_TIME 40 10194
TEST_TIME 41 10161
TEST_TIME 42 10075
TEST_TIME 43 10095
TEST_TIME 44 10085
TEST_TIME 45 9985
TEST_TIME 46 9846
TEST_TIME 47 9784
TEST_TIME 48 9715
TEST_TIME 49 9728
TEST_TIME 50 9905
TEST_TIME 51 9729
TEST_TIME 52 9726
TEST_TIME 53 9812
TEST_TIME 54 9814
TEST_TIME 55 9768
TEST_TIME 56 9738
TEST_TIME 57 9745
TEST_TIME 58 9800
TEST_TIME 59 9783
TEST_TIME 60 9805
TEST_TIME 61 9901
TEST_TIME 62 9875
TEST_TIME 63 9872
TEST_TIME 64 9928
TEST_TIME 65 9976
TEST_TIME 66 9961
TEST_TIME 67 9894
TEST_TIME 68 9961
TEST_TIME 69 9902
TEST_TIME 70 9813
TEST_TIME 71 9867
TEST_TIME 72 9846
TEST_TIME 73 9801
TEST_TIME 74 9796
TEST_TIME 75 9820
TEST_TIME 76 9775
TEST_TIME 77 9731
TEST_TIME 78 9653
TEST_TIME 79 9599
TEST_TIME 80 9563
TEST_TIME 81 9652
TEST_TIME 82 9601
TEST_TIME 83 9556
TEST_TIME 84 9616
TEST_TIME 85 9666
TEST_TIME 86 9635
TEST_TIME 87 9702
TEST_TIME 88 9674
TEST_TIME 89 9630
TEST_TIME 90 9740
TEST_TIME 91 9783
TEST_TIME 92 9781
TEST_TIME 93 9785
TEST_TIME 94 9866
TEST_TIME 95 9830
TEST_TIME 96 9735
TEST_TIME 97 9734
TEST_TIME 98 9721
TEST_TIME 99 9668
TEST_TIME 100 9665
TEST_TIME 101 9618
TEST_TIME 102 9578
TEST_TIME 103 9542
TEST_TIME 104 9517
TEST_TIME 105 9493
TEST_TIME 106 9477
TEST_TIME 107 9462
TEST_TIME 108 9472
TEST_TIME 109 9505
TEST_TIME 110 9533
TEST_TIME 111 9518
TEST_TIME 112 9559
TEST_TIME 113 9543
TEST_TIME 114 9523
TEST_TIME 115 9511
TEST_TIME 116 9572
TEST_TIME 117 9608
TEST_TIME 118 9681
TEST_TIME 119 9625
TEST_TIME 120 9554
TEST_TIME 121 9475

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-09-2021 01:39:49 to 10-09-2021 01:39:53.

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 1582280 1582280 0 0.00 0 21018 6 0 0
doc_list_biud 1582280 1582280 0 0.00 0 103626 28 0 0
sp_add_invoice_to_stock 5271 5060 211 4.00 19 155660 16357 0 211
sp_cancel_adding_invoice 5896 5896 0 0.00 5 81059 813 0 0
sp_cancel_client_order 1816 1816 0 0.00 4 11468 329 0 0
sp_cancel_customer_reserve 585686 585686 0 0.00 1 24600 60 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 4578 72 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 2 2784 65 0 0
sp_cancel_supplier_invoice 6591 6566 25 0.37 2 103632 882 0 25
sp_cancel_supplier_order 416 416 0 0.00 6 914606 108098 0 0
sp_cancel_write_off 9818 9818 0 0.00 1 12781 108 0 0
sp_client_order 20870 20870 0 0.00 3 26511 342 0 0
sp_customer_reserve 472716 472716 0 0.00 0 47730 160 0 0
sp_fill_shopping_cart 32006 32006 0 0.00 1 13288 37 0 0
sp_fill_shopping_cart_clo_res 472701 472701 0 0.00 0 11952 12 0 0
sp_get_clo_for_invoice 5045 5045 0 0.00 0 32638 271 0 0
sp_kill_cost_storno 16885 16885 0 0.00 0 6110 55 0 0
sp_kill_qstorno_ret_qs2qd 1188172 1188172 0 0.00 0 103623 33 0 0
sp_kill_qty_storno 625443 625443 0 0.00 0 103624 71 0 0
sp_lock_selected_doc 21153 21153 0 0.00 0 8657 6 0 0
sp_make_cost_storno 28367 28367 0 0.00 0 20718 57 0 0
sp_multiply_rows_for_pdistr 28367 28367 0 0.00 0 21012 29 0 0
sp_multiply_rows_for_qdistr 20870 20870 0 0.00 0 15274 146 0 0
sp_pay_from_customer 7169 7169 0 0.00 4 21023 114 0 0
sp_pay_to_supplier 5953 5953 0 0.00 5 20741 117 0 0
sp_payment_common 13122 13122 0 0.00 1 16335 31 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 11461 299 0 0
sp_qd_handle_on_invoice_upd_sts 10941 10941 0 0.00 0 20428 317 0 0
sp_qd_handle_on_reserve_upd_sts 10200 10200 0 0.00 0 18723 63 0 0
sp_reserve_write_off 10200 10200 0 0.00 6 18770 178 0 0
sp_supplier_invoice 5578 5578 0 0.00 6 106104 2983 0 0
sp_supplier_order 5569 5569 0 0.00 4 42726 1263 0 0
srv_aggregate_perf_data 101 101 0 0.00 2975 156936 17998 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1772 173557 47846 0 0
srv_find_qd_qs_mism 1096727 1096727 0 0.00 0 20466 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 27 44952 1077 0 0
srv_make_money_saldo 819 819 0 0.00 0 10052 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 91069 447940 242861 0 0
t$perf-abend:sp_add_invoice_to_stock 211 211 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 25 25 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5060 5060 0 0.00 0 20251 151 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 25248 163 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 3058 11 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1617 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 3771 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 7531 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 884 16 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 35333 1707 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 2490 8 0 0
t$perf-norm:sp_client_order 20870 20870 0 0.00 0 14136 16 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 7226 11 0 0
t$perf-norm:sp_pay_from_customer 7169 7169 0 0.00 0 13479 12 0 0
t$perf-norm:sp_pay_to_supplier 5953 5953 0 0.00 0 10749 11 0 0
t$perf-norm:sp_reserve_write_off 10200 10200 0 0.00 0 16849 14 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 9715 17 0 0
t$perf-norm:sp_supplier_order 5569 5569 0 0.00 0 12607 22 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 334 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 999 13 0 0
t$perf-norm:srv_make_money_saldo 819 819 0 0.00 0 2621 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 29 10 0 0
v_add_invoice_to_stock 10510 10088 422 4.01 0 6161 20 0 422
v_all_customers 37964 37964 0 0.00 0 29 1 0 0
v_all_suppliers 31002 31002 0 0.00 0 19 1 0 0
v_all_wares 41732 41732 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 2492 11 0 0
v_cancel_client_order 3632 3632 0 0.00 0 6286 7 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 4573 9 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1430 4 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 2666 9 0 0
v_cancel_supplier_invoice 874 824 50 5.72 0 2686 16 0 50
v_cancel_supplier_order 832 832 0 0.00 0 2172 9 0 0
v_cancel_write_off 2126 2126 0 0.00 0 6052 15 0 0
v_max_id_clo_ord 5567 5567 0 0.00 0 322 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3983 20 0 0
v_max_id_ord_sup 5573 5573 0 0.00 0 3512 4 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 8321 15 0 0
v_max_non_paid_realizn 3852 3852 0 0.00 0 3139 6 0 0
v_min_id_clo_ord 5567 5567 0 0.00 0 11827 8 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 4801 11 0 0
v_min_id_ord_sup 5573 5573 0 0.00 0 6473 7 0 0
v_min_non_paid_invoice 5953 5953 0 0.00 0 8146 15 0 0
v_min_non_paid_realizn 7169 7169 0 0.00 0 11330 20 0 0
v_our_firm 10402 10402 0 0.00 0 16 1 0 0
v_reserve_write_off 20396 20396 0 0.00 0 2957 5 0 0
x_lock_depdocs_on_canc_invoice 12050 12050 0 0.00 0 30143 120 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 3737 298 0 0
x_make_qstorno_1000_1200 5569 5569 0 0.00 1 42670 1188 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 2 106009 2850 0 0
x_make_qstorno_2100_3300 472716 472716 0 0.00 0 47729 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-09-2021 01:39:54 to 10-09-2021 01:39:58.

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
23:08:02 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:09:03 16352 8192000000 10373939072 10695626752 1434908944 14.905 100 88 176 0 1469615440 6036160 28670336
23:10:31 11389 8192000000 10437318384 10768793600 1494845536 15.431 100 87 174 0 1531384656 8418576 28120544
23:11:55 18833 8192000000 10488297728 10831470592 1541921360 15.840 100 83 166 0 1578244320 9943952 26379008
23:13:25 9707 8192000000 10566610128 10929537024 1626718720 16.567 100 79 158 0 1662052240 12118848 23214672
23:14:36 7469 8192000000 10642015968 11013152768 1699321776 17.179 100 74 148 0 1733101824 12594128 21185920
23:15:49 4443 8192000000 10699038208 11081281536 1761938032 17.700 100 76 152 1 1797065776 13196336 21931408
23:16:55 3085 8192000000 10754022976 11139756032 1803097376 18.039 100 82 164 4 1839630688 13545056 22988256
23:18:00 2340 8192000000 10794516976 11177484288 1848456464 18.410 100 94 189 1 1888113952 13802784 25854704
23:19:05 1936 8192000000 10826111296 11218341888 1877800496 18.647 100 90 180 1 1917295568 12801776 26693296
23:20:07 2029 8192000000 10827182096 11220062208 1885176256 18.707 100 89 178 4 1919924016 8929712 25818048
23:21:11 5591 8192000000 10799846560 11223453696 1887039472 18.722 100 35 69 0 1903402800 6471408 9891920
23:22:18 12162 8192000000 10802026768 11235332096 1897427008 18.806 100 33 66 0 1912965072 6066864 9471200
23:23:31 7173 8192000000 10854201664 11256139776 1908255680 18.893 100 71 138 1 1935537024 6463376 20817968
23:24:39 9907 8192000000 10814902128 11251408896 1905460800 18.870 100 22 44 1 1917976192 6161968 6353424
23:25:50 5552 8192000000 10829781536 11257360384 1908869584 18.898 100 35 69 1 1925206944 5847984 10489376
23:26:58 64465 8192000000 10773112800 11205234688 1813808080 18.127 95 39 79 0 1830317504 5211664 11297760
23:29:03 8491 8192000000 10743012096 11164495872 1788363936 17.918 97 22 43 1 1800364240 6024608 5975696
23:30:13 6600 8192000000 10685066576 11104837632 1717138560 17.328 95 18 34 3 1727399984 5627296 4634128
23:31:23 72759 8192000000 10706473776 11130236928 1722439024 17.373 94 40 77 4 1739128816 5550864 11138928
23:33:36 8864 8192000000 10742985104 11158065152 1793170384 17.958 98 26 52 1 1805847456 5453920 7223152
23:34:45 9381 8192000000 10763440608 11190276096 1843289536 18.368 100 18 36 0 1853864960 5158640 5416784
23:35:57 9550 8192000000 10778399760 11201753088 1859918432 18.503 100 27 52 1 1877071168 9574576 7578160
23:37:12 40604 8192000000 10773698320 11209986048 1869976784 18.584 100 30 60 0 1888846688 10494128 8375776
23:39:02 25739 8192000000 10842713552 11263434752 1911592064 18.919 100 50 98 2 1938447600 13246816 13608720
23:40:34 24587 8192000000 10866999776 11291992064 1932347280 19.086 100 34 68 0 1954234096 11676336 10210480
23:42:01 16347 8192000000 10892022464 11317264384 1949681808 19.224 100 41 82 0 1977681968 16195136 11805024
23:43:31 9594 8192000000 10899651104 11329216512 1946081072 19.195 99 57 111 0 1981311024 18693712 16536240
23:44:44 12529 8192000000 10901062288 11343138816 1963670800 19.335 100 29 58 2 1993489808 20904464 8914544
23:46:01 15555 8192000000 10935776592 11370598400 1985300992 19.507 100 49 98 1 2022467520 22747120 14419408
23:47:20 18155 8192000000 10945224640 11377082368 1985445856 19.508 100 58 116 2 2030847488 26974016 18427616
23:48:46 4568 8192000000 10967173888 11396792320 2005939120 19.670 100 77 150 0 2060125312 31700464 22485728
23:50:00 2989 8192000000 10994672336 11415396352 2017493104 19.760 100 70 138 4 2073785088 35106784 21185200
23:51:05 3001 8192000000 10985718288 11400806400 2007610384 19.683 100 76 152 1 2062146400 32330992 22205024
23:52:11 4722 8192000000 10953934992 11394732032 1982888368 19.488 99 38 76 2 2027249456 32255584 12105504
23:53:25 1767 8192000000 10994307744 11418660864 2016350304 19.751 100 77 154 1 2084264816 43249776 24664736
23:54:34 3424 8192000000 10991042368 11409719296 1984928224 19.504 99 82 164 0 2061072192 50793792 25350176
23:55:41 6968 8192000000 10979429888 11414298624 1997538048 19.603 100 49 99 2 2063055888 48928832 16589008
23:56:52 3939 8192000000 10980703536 11408982016 1977689792 19.446 99 60 121 2 2055095728 57316256 20089680
23:58:13 4560 8192000000 10989582224 11420422144 1990118896 19.545 100 63 126 1 2071877232 61148192 20610144
23:59:26 11926 8192000000 10942864144 11389841408 1972448864 19.405 100 35 69 0 2032917232 48400144 12068224
00:00:45 10674 8192000000 10957509856 11399442432 1985748992 19.510 100 36 72 1 2038772512 40801392 12222128
00:01:58 17111 8192000000 10952149904 11390881792 1966368464 19.357 100 30 59 2 2017325600 41092608 9864528
00:03:17 11952 8192000000 10913418176 11357450240 1885360288 18.708 96 37 74 2 1950178784 50881984 13936512
00:04:31 32363 8192000000 10910333040 11379724288 1911397200 18.918 99 24 48 2 1982372928 61415440 9560288
00:06:10 39353 8192000000 10883979024 11323240448 1903808784 18.857 98 26 50 3 1922602800 11453744 7340272
00:07:50 23191 8192000000 10832066896 11296559104 1881590368 18.678 98 10 20 1 1897892528 13432448 2869712
00:09:14 48430 8192000000 10808849680 11266834432 1849575728 18.419 97 4 8 0 1863024160 12594816 853616
00:11:03 66224 8192000000 10854961680 11316350976 1906929792 18.882 99 13 26 1 1921075472 10489392 3656288
00:13:10 91566 8192000000 10872864016 11327631360 1917779456 18.969 99 23 46 0 1941622304 17613200 6229648
00:15:54 109625 8192000000 10873824640 11337695232 1943264512 19.173 100 16 32 0 1959031616 10479232 5287872
00:19:03 43794 8192000000 10883720224 11345178624 1939789808 19.145 99 23 46 0 1956985968 10722320 6473840
00:20:53 41915 8192000000 10912901088 11347652608 1959989648 19.306 100 46 89 2 1984544192 12240576 12313968
00:22:39 60791 8192000000 10909867584 11370496000 1980659504 19.470 100 27 52 3 1998565584 9610240 8295840
00:24:40 30881 8192000000 10922104272 11388362752 1975584736 19.430 99 24 48 1 1996921312 14237616 7098960
00:26:11 90783 8192000000 10908548672 11399417856 1971988368 19.401 99 20 37 1 1996830800 19261744 5580688
00:28:47 83261 8192000000 10909636192 11393744896 1986618064 19.517 100 19 39 1 2006410688 14394832 5397792
00:32:44 20622 8192000000 10907896064 11357880320 1929409712 19.062 97 48 97 2 1950926928 7082240 14434976
00:34:06 55775 8192000000 10921112784 11389108224 1971271936 19.396 99 17 32 0 1985694880 9207728 5215216
00:36:02 43160 8192000000 10889143776 11350417408 1918085200 18.971 97 28 56 0 1935766048 9796960 7883888
00:37:46 105858 8192000000 10890243984 11349782528 1858771344 18.493 94 10 20 0 1881063216 19522768 2769104
00:40:32 54085 8192000000 10907522768 11372396544 1928442208 19.054 98 25 50 1 1956974976 22094192 6438576
00:42:27 69062 8192000000 10886278896 11349581824 1904101184 18.859 97 11 22 0 1918000592 11427728 2471680
00:44:38 43849 8192000000 10891943840 11351068672 1928452784 19.055 99 26 52 2 1950513168 14531536 7528848
00:46:27 173557 8192000000 10877692848 11342782464 1892341136 18.765 97 22 44 1 1910953936 12051616 6561184
00:50:46 47832 8192000000 10881673200 11347128320 1948812752 19.217 100 32 64 0 1969713280 11409504 9491024
00:52:54 25933 8192000000 10881135376 11350847488 1933312160 19.093 99 16 31 0 1947497344 10162064 4023120
00:55:28 83345 8192000000 10881458992 11357130752 1952580112 19.247 100 12 24 0 1968486448 12188880 3717456
00:58:12 48508 8192000000 10854236304 11334967296 1916999248 18.963 99 28 56 0 1943582000 18189280 8393472
01:00:18 91385 8192000000 10860605872 11343712256 1881977232 18.681 97 12 25 0 1911367712 25842720 3547760
01:03:05 69912 8192000000 10882999712 11347714048 1926505600 19.039 99 28 56 0 1948213024 13760896 7946528
01:05:16 40012 8192000000 10883372432 11348135936 1944996624 19.187 100 30 60 0 1970450464 16739344 8714496
01:07:00 56271 8192000000 10901801008 11387023360 1958701152 19.296 100 17 34 0 1977995616 14339984 4954480
01:08:56 37039 8192000000 10905275168 11366227968 1972853840 19.408 100 33 66 1 1993115456 11136704 9124912
01:10:36 130009 8192000000 10913300416 11384885248 1903743232 18.856 96 22 44 0 1929914816 21050240 5121344
01:13:47 108310 8192000000 10919163072 11389722624 1949324032 19.221 98 42 84 1 1976606320 15522656 11759632
01:16:36 8719 8192000000 10908832752 11352936448 1955066864 19.267 99 55 109 1 1980654832 9308464 16279504
01:17:45 79031 8192000000 10882946256 11364073472 1937144944 19.124 98 12 24 1 1948943216 8288912 3509360
01:20:04 88696 8192000000 10889343344 11369062400 1935558736 19.111 98 14 29 1 1947087568 7592176 3936656
01:22:34 70746 8192000000 10882842672 11369824256 1935339344 19.110 98 14 28 0 1948435584 9156992 3939248
01:24:45 82774 8192000000 10880882144 11374202880 1904738016 18.864 96 16 32 0 1923473904 14730528 4005360
01:27:12 48026 8192000000 10907523152 11370512384 1919225952 18.981 97 21 41 0 1936528592 10825664 6476976
01:29:00 40910 8192000000 10874837808 11345068032 1900465392 18.830 97 24 48 0 1916940080 9150560 7324128
01:30:56 16792 8192000000 10866897328 11328311296 1903546336 18.855 98 22 44 0 1920964704 11464576 5953792
01:32:20 98304 8192000000 10860438048 11338301440 1840508016 18.345 95 23 46 0 1867577296 20441152 6628128
01:35:01 155575 8192000000 10864680512 11345883136 1899021168 18.818 98 8 16 2 1922736688 21638848 2076672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-09-2021 01:39:59 to 10-09-2021 01:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 211 v_add_invoice_to_stock 335544517
except 25 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-09-2021 01:40:15 to 10-09-2021 01:40:17.

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          240370
MON$OLDEST_ACTIVE               240873
MON$OLDEST_SNAPSHOT             240873
MON$NEXT_TRANSACTION            240873
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                       2024064
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.33494 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33494 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33494 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Sep 10 01:38:29 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Sep 10 01:38:30 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

10.09.21 01:40:18. 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-09-10 01:40:20 No severe PSQL-related problems occured
10.09.21 01:40:22. End of report.