Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2022 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16787136 kB
MemAvailable 31526840 kB
Buffers 0 kB
Cached 14910288 kB
SwapCached 2364 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.10
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 2022-04-08 22:38:09
DTS_END                         2022-04-08 22:38:09.4050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49752
TRN_ID                          251244
ATT_ID                          664
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: 9173.22 21428 1100786
customer order: creation 181.69 370 21803
customer order: refuse 16.02 348 1923
order to supplier: creation 47.98 1333 5758
order to supplier: removal 3.39 81831 407
invoice (draft): creation 49.07 3268 5888
invoice (draft): removal 50.50 661 6060
invoice accept: apply 44.51 18159 5341
invoice accept: cancel 44.98 778 5398
customer reserve: creation 4045.68 184 485482
customer reserve: removal 4390.78 51 526894
realization accept: apply 87.33 167 10480
realization accept: cancel 75.55 115 9066
payment from customer: creation 62.43 95 7492
payment from customer: removal 5.83 74 699
payment to supplier: creation 51.11 121 6133
payment to supplier: removal 3.41 86 409
service: total inventory turnovers 5.18 953 621
service: total monetary turnovers 6.81 23 817
service: aggregate perf. data 0.93 16760 111
service: refresh index statistics 0.03 303178 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-04-2022 22:39:01 to 08-04-2022 22:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4117
TEST_TIME 2 3400
TEST_TIME 3 4578
TEST_TIME 4 5173
TEST_TIME 5 5107
TEST_TIME 6 5624
TEST_TIME 7 5865
TEST_TIME 8 6311
TEST_TIME 9 6347
TEST_TIME 10 6868
TEST_TIME 11 7064
TEST_TIME 12 7386
TEST_TIME 13 7475
TEST_TIME 14 7794
TEST_TIME 15 7831
TEST_TIME 16 8057
TEST_TIME 17 7940
TEST_TIME 18 8650
TEST_TIME 19 8674
TEST_TIME 20 8850
TEST_TIME 21 9007
TEST_TIME 22 9355
TEST_TIME 23 9687
TEST_TIME 24 10093
TEST_TIME 25 10024
TEST_TIME 26 10198
TEST_TIME 27 10610
TEST_TIME 28 10325
TEST_TIME 29 10233
TEST_TIME 30 10028
TEST_TIME 31 9922
TEST_TIME 32 9856
TEST_TIME 33 9670
TEST_TIME 34 9537
TEST_TIME 35 9492
TEST_TIME 36 9580
TEST_TIME 37 9598
TEST_TIME 38 9389
TEST_TIME 39 9375
TEST_TIME 40 9334
TEST_TIME 41 9200
TEST_TIME 42 9055
TEST_TIME 43 9141
TEST_TIME 44 9101
TEST_TIME 45 9025
TEST_TIME 46 8948
TEST_TIME 47 8966
TEST_TIME 48 8946
TEST_TIME 49 8880
TEST_TIME 50 8974
TEST_TIME 51 8925
TEST_TIME 52 9044
TEST_TIME 53 9131
TEST_TIME 54 9125
TEST_TIME 55 9133
TEST_TIME 56 9123
TEST_TIME 57 9281
TEST_TIME 58 9270
TEST_TIME 59 9240
TEST_TIME 60 9171
TEST_TIME 61 9203
TEST_TIME 62 9164
TEST_TIME 63 9083
TEST_TIME 64 9075
TEST_TIME 65 9105
TEST_TIME 66 9008
TEST_TIME 67 9015
TEST_TIME 68 8995
TEST_TIME 69 8973
TEST_TIME 70 9057
TEST_TIME 71 9067
TEST_TIME 72 9082
TEST_TIME 73 9180
TEST_TIME 74 9179
TEST_TIME 75 9205
TEST_TIME 76 9271
TEST_TIME 77 9324
TEST_TIME 78 9338
TEST_TIME 79 9325
TEST_TIME 80 9322
TEST_TIME 81 9255
TEST_TIME 82 9194
TEST_TIME 83 9204
TEST_TIME 84 9169
TEST_TIME 85 9140
TEST_TIME 86 9093
TEST_TIME 87 9051
TEST_TIME 88 9098
TEST_TIME 89 9121
TEST_TIME 90 9106
TEST_TIME 91 9163
TEST_TIME 92 9185
TEST_TIME 93 9181
TEST_TIME 94 9176
TEST_TIME 95 9225
TEST_TIME 96 9257
TEST_TIME 97 9267
TEST_TIME 98 9326
TEST_TIME 99 9321
TEST_TIME 100 9335
TEST_TIME 101 9340
TEST_TIME 102 9363
TEST_TIME 103 9381
TEST_TIME 104 9303
TEST_TIME 105 9282
TEST_TIME 106 9239
TEST_TIME 107 9253
TEST_TIME 108 9232
TEST_TIME 109 9187
TEST_TIME 110 9154
TEST_TIME 111 9119
TEST_TIME 112 9127
TEST_TIME 113 9186
TEST_TIME 114 9168
TEST_TIME 115 9159
TEST_TIME 116 9212
TEST_TIME 117 9233
TEST_TIME 118 9223
TEST_TIME 119 9250
TEST_TIME 120 9179

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2022 22:39:05 to 08-04-2022 22:39:08.

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 1549938 1549938 0 0.00 0 14444 6 0 0
doc_list_biud 1549936 1549936 0 0.00 0 23401 22 0 0
sp_add_invoice_to_stock 5469 5341 128 2.34 30 205459 18159 0 128
sp_cancel_adding_invoice 5398 5398 0 0.00 6 66530 778 0 0
sp_cancel_client_order 1923 1923 0 0.00 5 10913 348 0 0
sp_cancel_customer_reserve 526894 526894 0 0.00 1 22241 51 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 4019 74 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 4226 87 0 0
sp_cancel_supplier_invoice 6069 6060 9 0.14 2 23412 661 0 9
sp_cancel_supplier_order 407 407 0 0.00 9 767467 81831 0 0
sp_cancel_write_off 9066 9066 0 0.00 1 10085 116 0 0
sp_client_order 21803 21803 0 0.00 4 21859 371 0 0
sp_customer_reserve 485482 485482 0 0.00 0 35913 184 0 0
sp_fill_shopping_cart 33438 33438 0 0.00 1 16868 42 0 0
sp_fill_shopping_cart_clo_res 485466 485466 0 0.00 0 18537 13 0 0
sp_get_clo_for_invoice 5325 5325 0 0.00 0 26740 304 0 0
sp_kill_cost_storno 15572 15572 0 0.00 0 6488 50 0 0
sp_kill_qstorno_ret_qs2qd 1069321 1069321 0 0.00 0 23394 28 0 0
sp_kill_qty_storno 565551 565551 0 0.00 0 23398 61 0 0
sp_lock_selected_doc 21887 21887 0 0.00 0 11352 6 0 0
sp_make_cost_storno 29430 29430 0 0.00 0 14430 45 0 0
sp_multiply_rows_for_pdistr 29428 29428 0 0.00 0 6663 26 0 0
sp_multiply_rows_for_qdistr 21803 21803 0 0.00 0 21578 164 0 0
sp_pay_from_customer 7492 7492 0 0.00 4 11959 96 0 0
sp_pay_to_supplier 6133 6133 0 0.00 4 14452 122 0 0
sp_payment_common 13625 13625 0 0.00 1 12492 23 0 0
sp_qd_handle_on_cancel_clo 1923 1923 0 0.00 0 10904 309 0 0
sp_qd_handle_on_invoice_upd_sts 10723 10723 0 0.00 0 16331 285 0 0
sp_qd_handle_on_reserve_upd_sts 10478 10478 0 0.00 0 20185 67 0 0
sp_reserve_write_off 10480 10480 0 0.00 6 20258 168 0 0
sp_supplier_invoice 5888 5888 0 0.00 6 70939 3268 0 0
sp_supplier_order 5758 5758 0 0.00 5 24627 1333 0 0
srv_aggregate_perf_data 111 111 0 0.00 2468 231400 16761 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2256 132063 42443 0 0
srv_find_qd_qs_mism 1050390 1050390 0 0.00 0 9378 6 0 0
srv_make_invnt_saldo 621 621 0 0.00 34 68404 953 0 0
srv_make_money_saldo 817 817 0 0.00 1 1341 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 210602 555256 303178 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5343 5343 0 0.00 0 8594 143 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 3013 100 0 0
t$perf-norm:sp_cancel_client_order 1923 1923 0 0.00 0 2297 12 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 1190 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 1447 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1336 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 1186 13 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 24976 1376 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 1773 6 0 0
t$perf-norm:sp_client_order 21803 21803 0 0.00 0 9000 15 0 0
t$perf-norm:sp_customer_reserve 1086 1086 0 0.00 0 3138 8 0 0
t$perf-norm:sp_pay_from_customer 7492 7492 0 0.00 0 3836 9 0 0
t$perf-norm:sp_pay_to_supplier 6133 6133 0 0.00 0 6524 11 0 0
t$perf-norm:sp_reserve_write_off 10480 10480 0 0.00 0 6274 13 0 0
t$perf-norm:sp_supplier_invoice 5888 5888 0 0.00 0 7126 16 0 0
t$perf-norm:sp_supplier_order 5758 5758 0 0.00 0 3855 11 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 198 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 700 11 0 0
t$perf-norm:srv_make_invnt_saldo 621 621 0 0.00 0 1013 16 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 2875 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10906 10650 256 2.34 0 5544 17 0 256
v_all_customers 39418 39418 0 0.00 0 30 1 0 0
v_all_suppliers 31942 31942 0 0.00 0 120 1 0 0
v_all_wares 43606 43606 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 214 5 0 0
v_cancel_client_order 3846 3846 0 0.00 0 3146 7 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 3994 8 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 1115 6 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 4141 22 0 0
v_cancel_supplier_invoice 908 890 18 1.98 0 5800 26 0 18
v_cancel_supplier_order 814 814 0 0.00 0 4220 13 0 0
v_cancel_write_off 2140 2140 0 0.00 0 6970 16 0 0
v_max_id_clo_ord 5758 5758 0 0.00 0 3603 3 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 1250 9 0 0
v_max_id_ord_sup 5877 5877 0 0.00 0 6159 3 0 0
v_max_non_paid_invoice 1797 1797 0 0.00 0 256 2 0 0
v_max_non_paid_realizn 4144 4144 0 0.00 0 548 3 0 0
v_min_id_clo_ord 5758 5758 0 0.00 0 16736 17 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 4725 14 0 0
v_min_id_ord_sup 5877 5877 0 0.00 0 3275 5 0 0
v_min_non_paid_invoice 6133 6133 0 0.00 0 12489 15 0 0
v_min_non_paid_realizn 7491 7491 0 0.00 0 11937 18 0 0
v_our_firm 10882 10882 0 0.00 0 12 1 0 0
v_reserve_write_off 20956 20956 0 0.00 0 6891 7 0 0
x_lock_depdocs_on_canc_invoice 11013 11013 0 0.00 0 17295 108 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 3601 255 0 0
x_make_qstorno_1000_1200 5758 5758 0 0.00 1 24618 1242 0 0
x_make_qstorno_1200_2000 5888 5888 0 0.00 2 70901 3119 0 0
x_make_qstorno_2100_3300 485482 485482 0 0.00 0 35910 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2022 22:39:09 to 08-04-2022 22:39:13.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:13 11 8192000000 8802774400 8815546368 8213408 0.100 3 1 2 0 8351376 63440 74528
20:09:14 29307 8192000000 9912824320 10222858240 991039120 10.792 100 87 174 0 1019911232 6249760 22622352
20:10:49 16112 8192000000 9945191920 10264444928 1027782528 11.147 100 87 174 0 1058753968 8145792 22825648
20:12:19 16759 8192000000 9998144432 10335883264 1083423152 11.680 100 83 166 0 1113769456 9579568 20766736
20:13:42 11925 8192000000 10055667680 10407874560 1139994400 12.215 100 81 162 0 1169266096 9983776 19287920
20:14:56 10291 8192000000 10106298976 10468573184 1193058704 12.712 100 81 162 1 1224533536 13043088 18431744
20:16:09 4593 8192000000 10144745248 10520891392 1236014816 13.110 100 76 152 1 1265905232 12907584 16982832
20:17:15 4176 8192000000 10169153296 10547245056 1262095600 13.349 100 84 164 1 1293335728 13307520 17932608
20:18:20 3177 8192000000 10199040544 10585939968 1292248096 13.625 100 87 173 2 1325085344 12870352 19966896
20:19:25 36517 8192000000 10209009328 10609278976 1305057760 13.741 100 67 133 0 1330808560 10288464 15462336
20:21:04 4890 8192000000 10213562656 10620792832 1316762640 13.847 100 45 88 1 1335089232 8748224 9578368
20:22:11 8282 8192000000 10204070000 10621825024 1322757856 13.902 100 16 32 0 1332246080 5975376 3512848
20:23:21 7041 8192000000 10214997056 10631729152 1326373344 13.934 100 28 57 0 1339620928 6835440 6412144
20:24:28 25003 8192000000 10227222512 10643202048 1329080368 13.959 100 45 91 1 1345406496 5091392 11234736
20:25:55 7424 8192000000 10234565008 10654490624 1340873760 14.065 100 33 66 0 1353928688 5567056 7487872
20:27:06 6072 8192000000 10228604848 10644582400 1322179216 13.896 99 25 50 0 1334039696 6114432 5746048
20:28:13 9990 8192000000 10177240000 10590019584 1258094208 13.313 97 26 52 3 1270152256 5114624 6943424
20:29:24 22530 8192000000 10174487200 10590011392 1253086720 13.267 97 37 73 2 1270941952 9480288 8374944
20:30:50 6678 8192000000 10157093456 10554851328 1236778976 13.117 98 20 39 1 1247099776 5620960 4699840
20:31:59 6834 8192000000 10156152912 10554986496 1258469248 13.316 100 22 44 1 1269122480 5195648 5457584
20:33:09 5611 8192000000 10170436896 10579259392 1276955728 13.485 100 27 52 1 1293507200 10448416 6103056
20:34:18 17574 8192000000 10165393280 10580934656 1281462896 13.526 100 20 39 1 1291738384 5212080 5063408
20:35:38 8796 8192000000 10200040576 10612301824 1301394464 13.708 100 23 46 1 1316563056 9732496 5436096
20:36:49 9111 8192000000 10202652096 10619695104 1313113024 13.814 100 22 43 1 1322984736 4820560 5051152
20:37:58 17116 8192000000 10229766128 10644258816 1328876656 13.957 100 28 57 1 1341305248 5102256 7326336
20:39:17 58255 8192000000 10257768944 10673471488 1349041024 14.139 100 44 88 2 1366290544 7647648 9601872
20:41:19 12031 8192000000 10275296368 10691919872 1367116288 14.301 100 62 123 1 1392014400 10670800 14227312
20:42:45 6289 8192000000 10294623664 10710863872 1379804416 14.415 100 65 130 0 1406813040 12384608 14624016
20:43:53 6254 8192000000 10289000032 10706644992 1378185808 14.400 100 61 121 0 1404464320 12039280 14239232
20:45:08 29273 8192000000 10310406064 10733772800 1381190032 14.427 99 61 122 0 1414252768 18372912 14689824
20:46:39 6221 8192000000 10313680288 10720727040 1375205536 14.374 99 80 157 1 1409551840 16743184 17603120
20:47:48 7300 8192000000 10298456320 10714099712 1375609424 14.377 100 67 134 1 1409616800 17480704 16526672
20:49:00 3477 8192000000 10302279488 10714107904 1370155328 14.328 100 72 145 1 1408851248 21086960 17608960
20:50:07 9451 8192000000 10293870080 10711916544 1367853728 14.308 100 62 123 0 1407575824 25015120 14706976
20:51:28 4090 8192000000 10310478400 10730246144 1372191392 14.347 99 55 110 0 1410678080 24092432 14394256
20:52:36 2844 8192000000 10294889712 10714017792 1357853728 14.218 99 54 106 1 1394286816 23421856 13011232
20:53:50 2667 8192000000 10316190000 10719694848 1372468608 14.349 100 80 159 1 1417103376 24750800 19883968
20:54:56 6094 8192000000 10317944272 10733776896 1375564096 14.377 99 66 132 1 1417385504 25460688 16360720
20:56:14 2255 8192000000 10335605632 10741895168 1376568368 14.386 99 88 174 2 1432602768 34770000 21264400
20:57:19 5129 8192000000 10306912064 10726408192 1369337024 14.321 99 41 79 2 1404561952 24856400 10368528
20:58:27 8384 8192000000 10300124640 10728579072 1372322848 14.348 100 30 60 1 1411428112 31067584 8037680
20:59:38 8111 8192000000 10307646944 10726211584 1367349376 14.303 100 53 104 2 1413348000 32874432 13124192
21:00:52 13906 8192000000 10290924752 10723160064 1358458320 14.224 100 21 42 1 1399079360 34382064 6238976
21:02:12 18119 8192000000 10277905728 10709602304 1330506816 13.972 98 34 68 1 1371401104 31315568 9578720
21:03:33 41853 8192000000 10280378848 10705510400 1337391264 14.034 99 36 72 0 1366972592 20509136 9072192
21:05:17 25422 8192000000 10265463808 10713055232 1356330896 14.204 100 13 26 1 1379599312 19887648 3380768
21:06:43 47308 8192000000 10260686192 10702225408 1355692512 14.199 100 18 38 2 1368344176 8473536 4178128
21:08:31 37961 8192000000 10284581696 10723913728 1371125888 14.337 100 25 50 2 1384746688 8168768 5452032
21:10:14 52884 8192000000 10295551296 10743762944 1387547680 14.484 100 14 27 0 1401708112 11378896 2781536
21:12:08 41272 8192000000 10299575504 10751799296 1399669536 14.592 100 13 26 1 1417167648 14371984 3126128
21:14:26 32737 8192000000 10290361584 10733580288 1382047072 14.435 99 20 40 1 1401027328 14093776 4886480
21:17:26 37114 8192000000 10286524624 10740862976 1382244144 14.437 99 22 44 1 1393744336 6849776 4650416
21:19:07 45647 8192000000 10292836336 10744881152 1369930256 14.326 98 14 28 0 1383514400 10819296 2764848
21:20:55 67635 8192000000 10304088480 10762776576 1384081136 14.453 99 24 48 0 1402088368 12321168 5686064
21:23:04 40248 8192000000 10288958608 10738896896 1358336672 14.222 97 14 28 2 1369219136 7564624 3317840
21:24:45 59293 8192000000 10290058288 10763186176 1374192640 14.365 98 13 26 0 1394386112 17393328 2800144
21:26:44 51032 8192000000 10298945968 10748997632 1358331392 14.222 97 17 34 1 1372235584 10525536 3378656
21:28:45 59186 8192000000 10308074592 10760876032 1394818144 14.549 100 12 24 0 1412389456 14887792 2683520
21:30:45 58527 8192000000 10296937040 10763878400 1391350800 14.518 99 19 38 0 1410283312 13979664 4952848
21:32:45 60837 8192000000 10287863840 10715611136 1305079168 13.741 94 31 61 3 1320003760 7935520 6989072
21:34:59 55806 8192000000 10277604720 10734333952 1343103568 14.085 97 22 44 0 1366760896 18234224 5423104
21:37:00 39691 8192000000 10282757920 10727751680 1355113968 14.193 98 36 72 0 1373167200 9592736 8460496
21:38:49 86327 8192000000 10225541872 10654035968 1305310336 13.744 95 36 72 0 1332346448 19554480 7481632
21:41:16 22998 8192000000 10259667024 10687975424 1328030000 13.949 98 50 98 1 1348689520 9922496 10737024
21:42:39 50314 8192000000 10238736672 10687123456 1310172016 13.788 97 10 20 0 1324528960 12068928 2288016
21:44:30 18546 8192000000 10252973856 10691723264 1344396320 14.097 100 23 44 0 1359911072 10320160 5194592
21:45:51 77676 8192000000 10252618688 10699595776 1302207952 13.715 96 15 30 0 1316094304 10555920 3330432
21:48:14 81489 8192000000 10256476240 10702368768 1310465408 13.790 97 20 41 1 1328282832 12856656 4960768
21:50:41 73279 8192000000 10254572432 10703949824 1296933168 13.667 96 15 30 1 1313504848 12932144 3639536
21:52:57 74822 8192000000 10268307248 10719592448 1309357184 13.780 96 26 52 0 1329506208 13771872 6377152
21:55:12 65208 8192000000 10283687872 10733690880 1361522400 14.251 99 5 10 0 1374328928 11694512 1112016
21:57:25 34510 8192000000 10296564688 10733404160 1371024880 14.336 100 27 54 1 1387456320 10769056 5662384
21:59:00 114285 8192000000 10275083808 10727845888 1367406160 14.304 99 16 32 0 1379915408 8552048 3957200
22:02:09 64539 8192000000 10271922704 10736431104 1378173024 14.400 100 8 16 0 1389940448 10181968 1585456
22:04:15 34335 8192000000 10291114848 10753183744 1395523680 14.555 100 7 14 2 1406853136 9681088 1648368
22:05:57 55868 8192000000 10304323328 10778238976 1396135536 14.561 100 12 24 0 1411687904 12837200 2715168
22:08:07 88872 8192000000 10314512208 10774282240 1402062000 14.613 99 14 28 0 1417034432 11907504 3064928
22:10:36 67836 8192000000 10309179536 10760445952 1365501568 14.287 97 16 32 1 1380465728 11159968 3804192
22:12:45 70090 8192000000 10303472480 10761388032 1360290416 14.240 97 20 40 0 1380498000 15671696 4535888
22:14:56 46053 8192000000 10314983520 10759774208 1394202720 14.543 100 20 40 1 1410643248 11897552 4542976
22:16:43 53170 8192000000 10300819008 10758520832 1363878480 14.272 97 24 48 0 1378670944 9380048 5412416
22:18:44 132063 8192000000 10294349520 10747510784 1271612640 13.436 91 17 31 2 1287582896 12870848 3099408
22:21:57 93958 8192000000 10283237744 10724167680 1338009584 14.039 97 23 46 0 1352912880 9762640 5140656
22:24:33 8415 8192000000 10282873824 10725896192 1370528800 14.332 99 43 86 0 1389887056 9968848 9389408
22:25:42 52563 8192000000 10246112352 10710822912 1337696096 14.037 98 5 10 0 1347174080 8317136 1160848
22:27:43 52228 8192000000 10259696176 10708078592 1359410896 14.232 100 12 24 1 1373783632 11472480 2900256
22:29:37 71976 8192000000 10273581232 10731843584 1346362576 14.115 98 15 30 0 1367501248 17347568 3791104
22:31:49 65496 8192000000 10256000864 10698596352 1270065184 13.422 93 21 41 1 1289659696 14955568 4638944
22:34:01 64012 8192000000 10258518496 10702651392 1303785600 13.730 96 24 47 1 1323899872 14433424 5680848
22:36:15 14607 8192000000 10272748288 10702946304 1334370336 14.007 98 49 98 0 1358651568 13648272 10632960
22:37:30 26297 8192000000 10246545536 10682064896 1309089072 13.778 97 25 50 0 1325036368 10549808 5397488

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-04-2022 22:39:15 to 08-04-2022 22:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2022 22:39:31 to 08-04-2022 22:39:33.

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          248597
MON$OLDEST_ACTIVE               251478
MON$OLDEST_SNAPSHOT             251478
MON$NEXT_TRANSACTION            251478
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                       2013504
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:68: 
:69: mscs-test-161.budzdorov.ru    Fri Apr  8 22:38:12 2022
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Fri Apr  8 22:38:12 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Fri Apr  8 22:38:15 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Fri Apr  8 22:38:15 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
--- end of diff output ---

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

08.04.22 22:39:34. 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
2022-04-08 22:39:36 No severe PSQL-related problems occured
08.04.22 22:39:38. End of report.