Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.04.2022 19: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 16480540 kB
MemAvailable 31511504 kB
Buffers 0 kB
Cached 15209108 kB
SwapCached 2688 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-11 19:38:11
DTS_END                         2022-04-11 19:38:11.2000
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/37102
TRN_ID                          247153
ATT_ID                          694
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: 9261.92 24885 1111430
customer order: creation 181.06 350 21727
customer order: refuse 15.56 364 1867
order to supplier: creation 48.50 1351 5820
order to supplier: removal 3.26 101005 391
invoice (draft): creation 47.67 3532 5720
invoice (draft): removal 50.25 830 6030
invoice accept: apply 44.38 18540 5326
invoice accept: cancel 45.23 840 5428
customer reserve: creation 4102.48 183 492298
customer reserve: removal 4415.43 58 529851
realization accept: apply 87.23 198 10467
realization accept: cancel 81.67 117 9800
payment from customer: creation 63.48 131 7618
payment from customer: removal 6.01 112 721
payment to supplier: creation 52.79 157 6335
payment to supplier: removal 3.45 127 414
service: total inventory turnovers 5.13 1148 616
service: total monetary turnovers 7.34 28 881
service: aggregate perf. data 0.97 17875 116
service: refresh index statistics 0.03 350752 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-04-2022 19:39:09 to 11-04-2022 19:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7365
TEST_TIME 2 5177
TEST_TIME 3 5354
TEST_TIME 4 6499
TEST_TIME 5 6436
TEST_TIME 6 5978
TEST_TIME 7 5887
TEST_TIME 8 6018
TEST_TIME 9 6754
TEST_TIME 10 7080
TEST_TIME 11 7490
TEST_TIME 12 7828
TEST_TIME 13 7880
TEST_TIME 14 7925
TEST_TIME 15 7989
TEST_TIME 16 8533
TEST_TIME 17 8806
TEST_TIME 18 8752
TEST_TIME 19 8900
TEST_TIME 20 8744
TEST_TIME 21 8990
TEST_TIME 22 9162
TEST_TIME 23 9157
TEST_TIME 24 9282
TEST_TIME 25 9666
TEST_TIME 26 9478
TEST_TIME 27 9882
TEST_TIME 28 9943
TEST_TIME 29 10140
TEST_TIME 30 11256
TEST_TIME 31 11010
TEST_TIME 32 10837
TEST_TIME 33 10695
TEST_TIME 34 10681
TEST_TIME 35 10499
TEST_TIME 36 10573
TEST_TIME 37 10525
TEST_TIME 38 10387
TEST_TIME 39 10197
TEST_TIME 40 10286
TEST_TIME 41 10299
TEST_TIME 42 10212
TEST_TIME 43 10078
TEST_TIME 44 10046
TEST_TIME 45 9994
TEST_TIME 46 9941
TEST_TIME 47 9868
TEST_TIME 48 10005
TEST_TIME 49 10009
TEST_TIME 50 9955
TEST_TIME 51 9927
TEST_TIME 52 9814
TEST_TIME 53 9731
TEST_TIME 54 9595
TEST_TIME 55 9804
TEST_TIME 56 9651
TEST_TIME 57 9615
TEST_TIME 58 9558
TEST_TIME 59 9580
TEST_TIME 60 9591
TEST_TIME 61 9595
TEST_TIME 62 9535
TEST_TIME 63 9485
TEST_TIME 64 9493
TEST_TIME 65 9464
TEST_TIME 66 9475
TEST_TIME 67 9475
TEST_TIME 68 9508
TEST_TIME 69 9495
TEST_TIME 70 9509
TEST_TIME 71 9517
TEST_TIME 72 9644
TEST_TIME 73 9622
TEST_TIME 74 9651
TEST_TIME 75 9735
TEST_TIME 76 9660
TEST_TIME 77 9718
TEST_TIME 78 9686
TEST_TIME 79 9681
TEST_TIME 80 9616
TEST_TIME 81 9592
TEST_TIME 82 9516
TEST_TIME 83 9505
TEST_TIME 84 9453
TEST_TIME 85 9454
TEST_TIME 86 9415
TEST_TIME 87 9358
TEST_TIME 88 9304
TEST_TIME 89 9375
TEST_TIME 90 9358
TEST_TIME 91 9359
TEST_TIME 92 9351
TEST_TIME 93 9336
TEST_TIME 94 9404
TEST_TIME 95 9415
TEST_TIME 96 9406
TEST_TIME 97 9425
TEST_TIME 98 9472
TEST_TIME 99 9452
TEST_TIME 100 9461
TEST_TIME 101 9550
TEST_TIME 102 9592
TEST_TIME 103 9594
TEST_TIME 104 9609
TEST_TIME 105 9536
TEST_TIME 106 9468
TEST_TIME 107 9435
TEST_TIME 108 9447
TEST_TIME 109 9447
TEST_TIME 110 9397
TEST_TIME 111 9386
TEST_TIME 112 9360
TEST_TIME 113 9317
TEST_TIME 114 9311
TEST_TIME 115 9289
TEST_TIME 116 9279
TEST_TIME 117 9316
TEST_TIME 118 9293
TEST_TIME 119 9332
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-04-2022 19:39:12 to 11-04-2022 19:39:16.

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 1564358 1564358 0 0.00 0 17607 7 0 0
doc_list_biud 1564358 1564358 0 0.00 0 52367 26 0 0
sp_add_invoice_to_stock 5468 5326 142 2.59 34 196653 18540 0 142
sp_cancel_adding_invoice 5428 5428 0 0.00 5 47052 841 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 12713 364 0 0
sp_cancel_customer_reserve 529851 529851 0 0.00 1 52368 58 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 2 8109 113 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 13309 127 0 0
sp_cancel_supplier_invoice 6036 6030 6 0.09 1 28492 830 0 6
sp_cancel_supplier_order 391 391 0 0.00 8 1019212 101005 0 0
sp_cancel_write_off 9800 9800 0 0.00 1 19508 118 0 0
sp_client_order 21727 21727 0 0.00 3 24924 350 0 0
sp_customer_reserve 492298 492298 0 0.00 0 96956 183 0 0
sp_fill_shopping_cart 33266 33266 0 0.00 1 21104 44 0 0
sp_fill_shopping_cart_clo_res 492294 492294 0 0.00 0 12533 12 0 0
sp_get_clo_for_invoice 5321 5321 0 0.00 0 22868 296 0 0
sp_kill_cost_storno 16363 16363 0 0.00 0 6747 58 0 0
sp_kill_qstorno_ret_qs2qd 1075923 1075923 0 0.00 0 52363 32 0 0
sp_kill_qty_storno 569155 569155 0 0.00 0 52366 70 0 0
sp_lock_selected_doc 21856 21856 0 0.00 0 10113 6 0 0
sp_make_cost_storno 29741 29741 0 0.00 0 12756 60 0 0
sp_multiply_rows_for_pdistr 29741 29741 0 0.00 0 8160 33 0 0
sp_multiply_rows_for_qdistr 21727 21727 0 0.00 0 24746 133 0 0
sp_pay_from_customer 7618 7618 0 0.00 4 19275 132 0 0
sp_pay_to_supplier 6335 6335 0 0.00 4 18342 157 0 0
sp_payment_common 13953 13953 0 0.00 2 19269 36 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 12703 334 0 0
sp_qd_handle_on_invoice_upd_sts 10749 10749 0 0.00 0 22748 348 0 0
sp_qd_handle_on_reserve_upd_sts 10467 10467 0 0.00 0 27525 63 0 0
sp_reserve_write_off 10467 10467 0 0.00 7 27566 199 0 0
sp_supplier_invoice 5720 5720 0 0.00 6 103510 3533 0 0
sp_supplier_order 5820 5820 0 0.00 4 30760 1352 0 0
srv_aggregate_perf_data 116 116 0 0.00 2570 260347 17876 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1945 151272 46361 0 0
srv_find_qd_qs_mism 1059214 1059214 0 0.00 0 17605 6 0 0
srv_make_invnt_saldo 616 616 0 0.00 27 74810 1149 0 0
srv_make_money_saldo 881 881 0 0.00 0 1309 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 250833 507901 350753 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5327 5327 0 0.00 0 16108 175 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 0 3229 110 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 3331 13 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 2097 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 4557 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 832 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 464 9 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 26465 1655 0 0
t$perf-norm:sp_cancel_write_off 1076 1076 0 0.00 0 3725 9 0 0
t$perf-norm:sp_client_order 21727 21727 0 0.00 0 22763 18 0 0
t$perf-norm:sp_customer_reserve 1063 1063 0 0.00 0 725 5 0 0
t$perf-norm:sp_pay_from_customer 7618 7618 0 0.00 0 12286 16 0 0
t$perf-norm:sp_pay_to_supplier 6335 6335 0 0.00 0 3545 7 0 0
t$perf-norm:sp_reserve_write_off 10467 10467 0 0.00 0 25370 13 0 0
t$perf-norm:sp_supplier_invoice 5720 5720 0 0.00 0 4969 20 0 0
t$perf-norm:sp_supplier_order 5820 5820 0 0.00 0 8210 22 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 623 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 141 3 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 883 23 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 5373 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 10922 10638 284 2.60 0 6430 19 0 284
v_all_customers 39848 39848 0 0.00 0 51 1 0 0
v_all_suppliers 31936 31936 0 0.00 0 75 1 0 0
v_all_wares 43454 43454 0 0.00 0 95 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 1102 9 0 0
v_cancel_client_order 3734 3734 0 0.00 0 3443 7 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 5759 14 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 3486 10 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3791 8 0 0
v_cancel_supplier_invoice 874 862 12 1.37 0 969 14 0 12
v_cancel_supplier_order 782 782 0 0.00 0 2003 6 0 0
v_cancel_write_off 2152 2152 0 0.00 0 9306 22 0 0
v_max_id_clo_ord 5819 5819 0 0.00 0 130 1 0 0
v_max_id_clo_res 1063 1063 0 0.00 0 4729 21 0 0
v_max_id_ord_sup 5720 5720 0 0.00 0 3066 3 0 0
v_max_non_paid_invoice 1906 1906 0 0.00 0 3092 6 0 0
v_max_non_paid_realizn 4080 4080 0 0.00 0 3436 6 0 0
v_min_id_clo_ord 5819 5819 0 0.00 0 5761 12 0 0
v_min_id_clo_res 1063 1063 0 0.00 0 7214 23 0 0
v_min_id_ord_sup 5720 5720 0 0.00 0 7627 10 0 0
v_min_non_paid_invoice 6335 6335 0 0.00 0 10381 28 0 0
v_min_non_paid_realizn 7618 7618 0 0.00 0 19259 26 0 0
v_our_firm 10682 10682 0 0.00 0 17 1 0 0
v_reserve_write_off 20934 20934 0 0.00 0 3988 7 0 0
x_lock_depdocs_on_canc_invoice 11027 11027 0 0.00 0 18602 125 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 11409 369 0 0
x_make_qstorno_1000_1200 5820 5820 0 0.00 1 30753 1256 0 0
x_make_qstorno_1200_2000 5720 5720 0 0.00 2 103499 3381 0 0
x_make_qstorno_2100_3300 492298 492298 0 0.00 0 96955 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-04-2022 19:39:17 to 11-04-2022 19:39:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:14 35 8192000000 8810170368 8825376768 10450704 0.127 5 1 2 0 10552960 59472 42784
17:09:15 22089 8192000000 9885153056 10189844480 968204880 10.569 100 85 171 0 996432528 5764912 22462736
17:10:41 16907 8192000000 9930051904 10245537792 1008014288 10.956 100 87 174 0 1038770032 7401376 23354368
17:12:01 22173 8192000000 9972299728 10302504960 1053758576 11.397 100 84 168 1 1083463920 8930080 20775264
17:13:33 12460 8192000000 10029143360 10374270976 1115101040 11.981 100 85 170 0 1146386528 11167888 20117600
17:14:47 10723 8192000000 10076485856 10434342912 1161368496 12.416 100 79 158 1 1191394336 11585584 18440256
17:16:00 7353 8192000000 10130683968 10500173824 1215391200 12.919 100 74 147 1 1244589840 12589520 16609120
17:17:11 3395 8192000000 10159417376 10530926592 1247494352 13.215 100 91 182 2 1280966576 13272368 20199856
17:18:16 3217 8192000000 10187704432 10564931584 1268657968 13.409 100 88 177 1 1301821056 13568048 19595040
17:19:22 1465 8192000000 10197991680 10579480576 1284573136 13.555 100 92 184 0 1318851680 13854560 20423984
17:20:24 3317 8192000000 10201775520 10596560896 1296154368 13.660 100 80 160 2 1322650672 7453472 19042832
17:21:29 6236 8192000000 10211722560 10607689728 1305994496 13.750 100 55 109 1 1324462864 5177920 13290448
17:22:39 6513 8192000000 10203702192 10614104064 1313657824 13.819 100 28 56 0 1325739872 5988672 6093376
17:23:49 7573 8192000000 10209429808 10626375680 1325593824 13.927 100 29 56 2 1338395136 6263536 6537776
17:24:57 14863 8192000000 10224891760 10639589376 1333974336 14.003 100 25 48 1 1345210688 5350048 5886304
17:26:13 11768 8192000000 10222677328 10642989056 1333245472 13.996 100 33 63 1 1345879712 4999568 7634672
17:27:29 7605 8192000000 10233869584 10650787840 1327070400 13.941 99 24 45 1 1339223040 6605664 5546976
17:28:38 8128 8192000000 10222520640 10634633216 1315502912 13.836 99 24 47 2 1326420448 5723344 5194192
17:29:51 69706 8192000000 10133856896 10535153664 1171036512 12.507 90 38 77 1 1185353744 4435744 9881488
17:32:02 4362 8192000000 10154303712 10545782784 1238474912 13.132 98 50 100 3 1259006784 9280448 11251424
17:33:07 5842 8192000000 10154381152 10555760640 1249656368 13.235 99 22 44 2 1260511392 5538928 5316096
17:34:14 9819 8192000000 10179357472 10583728128 1282226960 13.533 100 22 44 0 1293165616 5178720 5759936
17:35:28 22176 8192000000 10178933968 10594353152 1294045744 13.641 100 27 54 0 1306294576 6490144 5758688
17:36:53 9398 8192000000 10199176288 10613469184 1307187696 13.761 100 20 40 1 1317588880 5131360 5269824
17:38:04 18896 8192000000 10221788304 10634510336 1321166816 13.887 100 21 41 1 1330952192 5134176 4651200
17:39:29 15946 8192000000 10259130032 10667008000 1345651760 14.108 100 54 108 1 1367815312 9744096 12419456
17:40:48 65896 8192000000 10261581920 10687393792 1357946848 14.219 100 43 85 1 1380283536 12847536 9489152
17:42:58 33970 8192000000 10274138976 10704515072 1370501248 14.332 100 29 58 0 1389725328 12540896 6683184
17:44:36 13506 8192000000 10296871920 10719322112 1382812112 14.442 100 47 92 0 1409204336 15706368 10685856
17:45:52 7349 8192000000 10319789184 10740781056 1391665680 14.521 100 59 117 2 1427584640 22887280 13031680
17:47:03 3322 8192000000 10321900016 10744332288 1398057920 14.578 100 50 100 0 1428452496 18561888 11832688
17:48:11 3582 8192000000 10349661680 10764021760 1410135808 14.685 100 78 156 1 1452468912 24034544 18298560
17:49:19 8811 8192000000 10331045520 10757316608 1401541520 14.609 100 54 108 3 1440413472 25527808 13344144
17:50:36 1943 8192000000 10358929824 10773454848 1414974656 14.728 100 85 169 2 1461058240 25716448 20367136
17:51:44 8558 8192000000 10355255072 10781802496 1421726400 14.788 100 59 115 3 1462909216 26641456 14541360
17:53:00 1966 8192000000 10312543328 10715955200 1356676192 14.208 98 81 155 3 1411134176 34298352 20159632
17:54:06 2582 8192000000 10350402560 10766503936 1385294128 14.464 99 79 158 1 1450152464 45478544 19379792
17:55:14 3967 8192000000 10328492160 10748649472 1387746560 14.486 100 65 130 1 1438041792 33263264 17031968
17:56:22 2630 8192000000 10358607744 10770448384 1398492608 14.582 100 84 168 0 1459064672 40217920 20354144
17:57:33 3041 8192000000 10306181952 10721505280 1326432352 13.935 96 51 100 2 1377289168 38203200 12653616
17:58:45 5362 8192000000 10329765200 10740965376 1368606608 14.315 100 67 135 2 1433788704 47773952 17408144
18:00:05 2841 8192000000 10349790944 10752860160 1378133888 14.400 100 84 170 2 1444068944 44513472 21421584
18:01:12 12892 8192000000 10335779008 10759577600 1383086016 14.444 100 45 90 2 1440899008 45560944 12252048
18:02:43 18165 8192000000 10333948048 10764144640 1362875120 14.263 98 39 78 3 1422949312 49598480 10475712
18:04:03 10818 8192000000 10339472144 10771873792 1392135328 14.525 100 37 75 1 1445748080 43596576 10016176
18:05:20 8888 8192000000 10343897824 10771001344 1376981408 14.390 100 40 79 1 1441770160 53683280 11105472
18:06:32 24020 8192000000 10301573184 10739064832 1348811872 14.137 99 20 40 1 1399005184 44138880 6054432
18:08:15 39849 8192000000 10278328880 10723278848 1358831792 14.227 99 13 26 2 1392369920 29796400 3741728
18:09:55 61001 8192000000 10301322064 10740461568 1381001392 14.426 100 41 82 0 1400338000 10254832 9081776
18:11:59 26418 8192000000 10283257888 10730209280 1368309136 14.312 99 43 86 3 1393443648 15631968 9502544
18:13:27 56585 8192000000 10276553936 10735939584 1363047024 14.265 99 14 28 1 1378736432 12270768 3418640
18:15:24 56828 8192000000 10295476592 10748461056 1387505472 14.484 100 10 20 0 1400570624 11017104 2048048
18:17:43 67292 8192000000 10289513440 10749349888 1389537040 14.502 100 24 47 1 1408396864 13280800 5579024
18:19:56 65998 8192000000 10304285536 10752872448 1384003440 14.452 99 17 34 0 1395880176 8205088 3671648
18:22:02 69833 8192000000 10308472368 10763673600 1373192784 14.356 98 20 39 0 1386719312 9187456 4339072
18:24:14 53752 8192000000 10325280672 10770718720 1387503264 14.484 99 18 36 0 1402603904 10750656 4349984
18:26:08 137089 8192000000 10298777936 10753941504 1356405952 14.205 97 4 8 0 1370567824 13629168 532704
18:29:26 69205 8192000000 10303465472 10755534848 1375445840 14.376 98 15 30 2 1393340272 14394768 3499664
18:31:36 120312 8192000000 10302388144 10756546560 1356181584 14.203 97 26 52 0 1386566976 24166496 6218896
18:34:38 23285 8192000000 10309425808 10745520128 1380458768 14.421 100 48 97 1 1400885264 8986768 11439728
18:36:03 59430 8192000000 10291260640 10735214592 1351795632 14.164 97 39 78 2 1375721120 14900592 9024896
18:38:05 26999 8192000000 10286849904 10741657600 1367568608 14.305 99 19 37 1 1386456016 14616096 4271312
18:39:36 128682 8192000000 10286669584 10739666944 1345394944 14.106 96 23 45 2 1361389776 11362240 4632592
18:43:10 53775 8192000000 10261543808 10709811200 1285893920 13.567 94 5 10 1 1294739488 8108624 736944
18:45:04 42278 8192000000 10270409504 10717704192 1335871600 14.020 98 16 33 0 1347061744 7544416 3645728
18:46:47 16753 8192000000 10245831392 10691629056 1298092144 13.678 96 24 49 0 1313666784 9875952 5698688
18:48:04 45177 8192000000 10283721568 10733236224 1364906880 14.281 100 24 49 0 1389880112 19010880 5962352
18:50:02 84291 8192000000 10269125648 10706661376 1293783424 13.639 95 14 28 0 1312763200 15750240 3229536
18:52:27 42298 8192000000 10284205424 10738343936 1352289888 14.168 99 30 60 0 1374702112 15607824 6804400
18:54:15 14187 8192000000 10288639872 10718056448 1345110512 14.103 98 52 104 1 1365459472 8497984 11850976
18:55:32 56444 8192000000 10280079488 10725081088 1345985392 14.111 99 22 44 2 1364732576 13434880 5312304
18:57:43 151205 8192000000 10278857408 10730659840 1368915376 14.317 100 18 36 0 1384843696 11497136 4431184
19:01:15 48028 8192000000 10274356096 10729000960 1362942416 14.264 99 20 40 0 1375781264 7926496 4912352
19:03:13 44736 8192000000 10275937136 10740690944 1377714384 14.396 100 15 31 2 1394404256 12757008 3932864
19:05:29 85684 8192000000 10286990720 10748157952 1366205600 14.293 99 14 28 0 1389701824 19654320 3841904
19:07:56 27643 8192000000 10275053408 10740862976 1363941536 14.273 99 21 41 0 1383538544 15142656 4454352
19:09:29 98884 8192000000 10292173264 10745401344 1324786352 13.920 95 11 22 0 1335669488 8434048 2449088
19:12:09 53192 8192000000 10289486384 10743648256 1368266480 14.312 98 13 26 0 1380419904 9494912 2658512
19:14:03 38796 8192000000 10290825216 10738253824 1347898512 14.129 97 27 54 0 1362604800 8629152 6077136
19:15:51 72565 8192000000 10297254736 10755248128 1374439232 14.367 99 20 40 0 1394746112 15518128 4788752
19:18:03 70599 8192000000 10303171568 10773061632 1374133136 14.364 99 11 22 0 1390230480 13688336 2409008
19:20:20 151272 8192000000 10280668624 10747912192 1270713408 13.428 92 10 20 0 1304177808 31783872 1680528
19:23:52 94603 8192000000 10289916272 10736857088 1354933792 14.192 99 26 52 0 1371524944 10981952 5609200
19:26:43 79603 8192000000 10253039584 10726928384 1305472560 13.745 95 7 14 0 1322841888 15912480 1456848
19:29:02 67372 8192000000 10243025040 10692009984 1282628912 13.537 94 24 48 0 1296049472 7984560 5436000
19:31:10 50285 8192000000 10244076784 10699354112 1334644304 14.009 99 13 26 0 1344054880 6383360 3027216
19:33:04 79624 8192000000 10234935936 10696491008 1284104592 13.550 95 8 17 1 1295401200 9697136 1599472
19:35:24 52802 8192000000 10243366496 10702680064 1322106448 13.896 98 21 42 1 1341531456 14985872 4439136
19:37:17 49728 8192000000 10235605280 10678132736 1254278848 13.278 93 19 38 0 1267478928 8808704 4391376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-04-2022 19:39:23 to 11-04-2022 19:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-04-2022 19:39:38 to 11-04-2022 19:39:40.

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          246641
MON$OLDEST_ACTIVE               247285
MON$OLDEST_SNAPSHOT             247285
MON$NEXT_TRANSACTION            247285
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                       2047328
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 ---
:207: 
:208: mscs-test-161.budzdorov.ru    Mon Apr 11 19:38:13 2022
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Mon Apr 11 19:38:18 2022
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Mon Apr 11 19:38:18 2022
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Mon Apr 11 19:38:18 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Mon Apr 11 19:38:18 2022
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:226: 
--- end of diff output ---

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

11.04.22 19:39:41. 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-11 19:39:44 No severe PSQL-related problems occured
11.04.22 19:39:45. End of report.