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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16056232 kB
MemAvailable 31023416 kB
Buffers 0 kB
Cached 15646884 kB
SwapCached 2552 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.11
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-07-24 16:38:29
DTS_END                         2022-07-24 16:38:29.2310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40270
TRN_ID                          240944
ATT_ID                          620
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: 9535.91 19462 1144309
customer order: creation 173.00 320 20760
customer order: refuse 15.47 343 1856
order to supplier: creation 46.05 1214 5526
order to supplier: removal 3.48 95394 417
invoice (draft): creation 47.14 3526 5657
invoice (draft): removal 53.19 741 6383
invoice accept: apply 43.26 16406 5191
invoice accept: cancel 48.33 771 5800
customer reserve: creation 3974.75 162 476970
customer reserve: removal 4826.04 54 579125
realization accept: apply 84.61 167 10153
realization accept: cancel 88.83 111 10660
payment from customer: creation 59.67 99 7160
payment from customer: removal 5.93 80 711
payment to supplier: creation 50.72 105 6086
payment to supplier: removal 3.60 101 432
service: total inventory turnovers 4.88 1206 586
service: total monetary turnovers 6.20 64 744
service: aggregate perf. data 0.73 24023 88
service: refresh index statistics 0.03 244346 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-07-2022 16:39:36 to 24-07-2022 16:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5362
TEST_TIME 2 4815
TEST_TIME 3 5459
TEST_TIME 4 5289
TEST_TIME 5 5416
TEST_TIME 6 5466
TEST_TIME 7 5428
TEST_TIME 8 5724
TEST_TIME 9 6241
TEST_TIME 10 6414
TEST_TIME 11 6809
TEST_TIME 12 6901
TEST_TIME 13 7323
TEST_TIME 14 7288
TEST_TIME 15 7328
TEST_TIME 16 7447
TEST_TIME 17 8064
TEST_TIME 18 8138
TEST_TIME 19 8130
TEST_TIME 20 8276
TEST_TIME 21 8933
TEST_TIME 22 9225
TEST_TIME 23 9599
TEST_TIME 24 9619
TEST_TIME 25 9889
TEST_TIME 26 10537
TEST_TIME 27 10573
TEST_TIME 28 11079
TEST_TIME 29 11397
TEST_TIME 30 11228
TEST_TIME 31 11070
TEST_TIME 32 10896
TEST_TIME 33 10812
TEST_TIME 34 10510
TEST_TIME 35 10426
TEST_TIME 36 10340
TEST_TIME 37 10301
TEST_TIME 38 10335
TEST_TIME 39 10163
TEST_TIME 40 10230
TEST_TIME 41 10233
TEST_TIME 42 10124
TEST_TIME 43 10040
TEST_TIME 44 10019
TEST_TIME 45 9862
TEST_TIME 46 9829
TEST_TIME 47 9750
TEST_TIME 48 9735
TEST_TIME 49 9711
TEST_TIME 50 9545
TEST_TIME 51 9658
TEST_TIME 52 9674
TEST_TIME 53 9692
TEST_TIME 54 9729
TEST_TIME 55 9738
TEST_TIME 56 9826
TEST_TIME 57 9845
TEST_TIME 58 9911
TEST_TIME 59 9873
TEST_TIME 60 10019
TEST_TIME 61 10037
TEST_TIME 62 10061
TEST_TIME 63 10074
TEST_TIME 64 10099
TEST_TIME 65 10009
TEST_TIME 66 9937
TEST_TIME 67 9852
TEST_TIME 68 9788
TEST_TIME 69 9820
TEST_TIME 70 9756
TEST_TIME 71 9699
TEST_TIME 72 9689
TEST_TIME 73 9665
TEST_TIME 74 9624
TEST_TIME 75 9608
TEST_TIME 76 9674
TEST_TIME 77 9644
TEST_TIME 78 9623
TEST_TIME 79 9575
TEST_TIME 80 9784
TEST_TIME 81 9858
TEST_TIME 82 9826
TEST_TIME 83 9873
TEST_TIME 84 9823
TEST_TIME 85 9874
TEST_TIME 86 9843
TEST_TIME 87 9771
TEST_TIME 88 9696
TEST_TIME 89 9685
TEST_TIME 90 9666
TEST_TIME 91 9622
TEST_TIME 92 9555
TEST_TIME 93 9643
TEST_TIME 94 9592
TEST_TIME 95 9614
TEST_TIME 96 9622
TEST_TIME 97 9583
TEST_TIME 98 9667
TEST_TIME 99 9650
TEST_TIME 100 9642
TEST_TIME 101 9668
TEST_TIME 102 9712
TEST_TIME 103 9768
TEST_TIME 104 9765
TEST_TIME 105 9769
TEST_TIME 106 9774
TEST_TIME 107 9741
TEST_TIME 108 9683
TEST_TIME 109 9646
TEST_TIME 110 9608
TEST_TIME 111 9621
TEST_TIME 112 9586
TEST_TIME 113 9543
TEST_TIME 114 9522
TEST_TIME 115 9533
TEST_TIME 116 9574
TEST_TIME 117 9557
TEST_TIME 118 9548
TEST_TIME 119 9575
TEST_TIME 120 9540

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-07-2022 16:39:39 to 24-07-2022 16:39:43.

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 1585824 1585824 0 0.00 0 14248 5 0 0
doc_list_biud 1585821 1585821 0 0.00 0 36877 25 0 0
sp_add_invoice_to_stock 5321 5191 130 2.44 41 190940 16407 0 130
sp_cancel_adding_invoice 5800 5800 0 0.00 4 50292 772 0 0
sp_cancel_client_order 1856 1856 0 0.00 4 14884 343 0 0
sp_cancel_customer_reserve 579125 579125 0 0.00 0 27089 55 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 4621 81 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 5779 102 0 0
sp_cancel_supplier_invoice 6388 6383 5 0.07 1 31806 741 0 5
sp_cancel_supplier_order 417 417 0 0.00 9 951028 95395 0 0
sp_cancel_write_off 10660 10660 0 0.00 1 13592 111 0 0
sp_client_order 20760 20760 0 0.00 3 19448 320 0 0
sp_customer_reserve 476970 476970 0 0.00 0 108393 163 0 0
sp_fill_shopping_cart 31935 31935 0 0.00 1 34919 41 0 0
sp_fill_shopping_cart_clo_res 476962 476962 0 0.00 0 20431 13 0 0
sp_get_clo_for_invoice 5183 5183 0 0.00 0 26745 265 0 0
sp_kill_cost_storno 17603 17603 0 0.00 0 5773 51 0 0
sp_kill_qstorno_ret_qs2qd 1175710 1175710 0 0.00 0 31798 30 0 0
sp_kill_qty_storno 619574 619574 0 0.00 0 36876 64 0 0
sp_lock_selected_doc 21332 21332 0 0.00 0 7632 6 0 0
sp_make_cost_storno 28580 28580 0 0.00 0 14241 49 0 0
sp_multiply_rows_for_pdistr 28579 28579 0 0.00 0 7490 26 0 0
sp_multiply_rows_for_qdistr 20760 20760 0 0.00 0 14980 147 0 0
sp_pay_from_customer 7160 7160 0 0.00 4 8070 100 0 0
sp_pay_to_supplier 6086 6086 0 0.00 4 6130 106 0 0
sp_payment_common 13246 13246 0 0.00 1 8061 23 0 0
sp_qd_handle_on_cancel_clo 1856 1856 0 0.00 0 14876 312 0 0
sp_qd_handle_on_invoice_upd_sts 10980 10980 0 0.00 0 36873 288 0 0
sp_qd_handle_on_reserve_upd_sts 10153 10153 0 0.00 0 23333 58 0 0
sp_reserve_write_off 10153 10153 0 0.00 6 25305 167 0 0
sp_supplier_invoice 5657 5657 0 0.00 6 109128 3527 0 0
sp_supplier_order 5526 5526 0 0.00 5 34804 1214 0 0
srv_aggregate_perf_data 88 88 0 0.00 2549 366855 24024 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1282 132569 43229 0 0
srv_find_qd_qs_mism 1095511 1095511 0 0.00 0 14215 4 0 0
srv_make_invnt_saldo 586 586 0 0.00 32 66043 1207 0 0
srv_make_money_saldo 744 744 0 0.00 1 22358 65 0 0
srv_recalc_idx_stat 4 4 0 0.00 136317 333020 244346 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5191 5191 0 0.00 0 10846 159 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 9386 116 0 0
t$perf-norm:sp_cancel_client_order 1856 1856 0 0.00 0 1648 10 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 3357 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 3976 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 2888 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 5588 29 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 22831 1468 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 2542 8 0 0
t$perf-norm:sp_client_order 20760 20760 0 0.00 0 10058 17 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1695 6 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 7337 12 0 0
t$perf-norm:sp_pay_to_supplier 6086 6086 0 0.00 0 4190 11 0 0
t$perf-norm:sp_reserve_write_off 10153 10153 0 0.00 0 10511 15 0 0
t$perf-norm:sp_supplier_invoice 5657 5657 0 0.00 0 10166 19 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 6359 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 252 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 7308 36 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 2781 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10620 10360 260 2.44 0 11888 20 0 260
v_all_customers 38074 38074 0 0.00 0 20 0 0 0
v_all_suppliers 31204 31204 0 0.00 0 28 1 0 0
v_all_wares 41520 41520 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 3057 12 0 0
v_cancel_client_order 3712 3712 0 0.00 0 4486 6 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 3573 9 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 3582 5 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2466 6 0 0
v_cancel_supplier_invoice 840 830 10 1.19 0 3602 18 0 10
v_cancel_supplier_order 834 834 0 0.00 0 1604 7 0 0
v_cancel_write_off 1994 1994 0 0.00 0 3124 15 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 156 1 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1381 12 0 0
v_max_id_ord_sup 5653 5653 0 0.00 0 6081 6 0 0
v_max_non_paid_invoice 1658 1658 0 0.00 0 1183 3 0 0
v_max_non_paid_realizn 3761 3761 0 0.00 0 4485 6 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 4350 6 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 3296 9 0 0
v_min_id_ord_sup 5653 5653 0 0.00 0 9177 10 0 0
v_min_non_paid_invoice 6086 6086 0 0.00 0 4233 13 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 8057 16 0 0
v_our_firm 10244 10244 0 0.00 0 63 1 0 0
v_reserve_write_off 20306 20306 0 0.00 0 4752 6 0 0
x_lock_depdocs_on_canc_invoice 11768 11768 0 0.00 0 18099 105 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 5915 307 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 1 34793 1137 0 0
x_make_qstorno_1200_2000 5657 5657 0 0.00 2 101497 3376 0 0
x_make_qstorno_2100_3300 476969 476969 0 0.00 0 108391 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-07-2022 16:39:44 to 24-07-2022 16:39:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:32 3 8192000000 8801710704 8813842432 7736576 0.094 2 2 3 0 7877664 66144 74944
14:09:34 22604 8192000000 9893540272 10202722304 972460128 10.611 100 82 164 0 999738736 5538352 21740256
14:11:02 28139 8192000000 9939451200 10259050496 1019865120 11.071 100 89 178 0 1051017120 7748816 23403184
14:12:36 18952 8192000000 9967383936 10295578624 1048796480 11.349 100 81 162 0 1078249488 8730352 20722656
14:13:58 8263 8192000000 10022430048 10367229952 1109994032 11.932 100 79 156 1 1138873872 9599120 19280720
14:15:09 8298 8192000000 10083006864 10438991872 1167314736 12.472 100 80 160 0 1197450160 12010528 18124896
14:16:28 2835 8192000000 10121246704 10488590336 1215648448 12.921 100 79 159 0 1246219472 13407296 17163728
14:17:34 1351 8192000000 10166918320 10540720128 1254444912 13.279 100 85 167 0 1285585488 13175888 17964688
14:18:37 732 8192000000 10196361392 10579841024 1285339264 13.562 100 86 170 1 1316718928 11985728 19393936
14:19:39 21908 8192000000 10206345488 10603270144 1301303712 13.707 100 54 109 0 1322852864 9634416 11914736
14:21:02 4989 8192000000 10210368608 10616311808 1314873472 13.830 100 58 116 0 1337080816 8634816 13572528
14:22:12 5797 8192000000 10211481056 10620669952 1318448048 13.863 100 37 74 0 1334677232 8166128 8063056
14:23:19 63016 8192000000 10217949360 10632462336 1322726544 13.901 100 33 66 1 1335764256 4988672 8049040
14:25:22 3055 8192000000 10225587168 10640666624 1329305136 13.961 100 33 66 0 1343564432 7166016 7093280
14:26:26 5197 8192000000 10230232432 10644926464 1333662736 14.000 100 25 50 0 1346134608 6797248 5674624
14:27:34 11301 8192000000 10208660064 10632003584 1311494176 13.800 99 5 10 0 1317634784 5210544 930064
14:28:46 26272 8192000000 10215319376 10639970304 1328697968 13.955 100 35 69 0 1343745200 7077520 7969712
14:30:12 6546 8192000000 10162220416 10570727424 1247188160 13.212 97 20 41 1 1257165248 5315120 4661968
14:31:20 8905 8192000000 10156546640 10561912832 1236725872 13.116 97 16 30 1 1245053472 5341888 2985712
14:32:32 62334 8192000000 10133873744 10542338048 1220874352 12.970 97 25 50 1 1232074608 5308928 5891328
14:34:35 2226 8192000000 10167933008 10574643200 1273414848 13.453 100 40 78 0 1289676432 7555696 8705888
14:35:40 6975 8192000000 10184040880 10595606528 1289802096 13.602 100 18 36 0 1299850400 5797344 4250960
14:36:47 11188 8192000000 10196558640 10609827840 1302687200 13.720 100 19 37 1 1311815888 4680848 4447840
14:38:00 30137 8192000000 10213873504 10630553600 1315339536 13.834 100 27 53 1 1325802160 4914752 5547872
14:39:44 8014 8192000000 10254307696 10665680896 1346536672 14.116 100 54 104 1 1368156016 9401216 12218128
14:40:59 6488 8192000000 10278141104 10693877760 1364736240 14.280 100 57 115 0 1395196976 17424704 13036032
14:42:10 9844 8192000000 10293992256 10710441984 1377221712 14.392 100 49 97 0 1406749520 17972464 11555344
14:43:25 20273 8192000000 10306400608 10728017920 1389081520 14.498 100 49 94 1 1420280064 20236320 10962224
14:44:49 25815 8192000000 10314532896 10744352768 1394724224 14.548 100 38 74 0 1429669312 25884880 9060208
14:46:24 12637 8192000000 10324080608 10750287872 1396420752 14.563 100 52 104 1 1437080256 27159952 13499552
14:47:58 6528 8192000000 10330030016 10761158656 1405679344 14.646 100 50 100 0 1451520816 33653056 12188416
14:49:07 3867 8192000000 10355851328 10785120256 1419673136 14.770 100 50 100 1 1464623280 32705120 12245024
14:50:14 4165 8192000000 10350708608 10765508608 1403695568 14.628 99 79 157 1 1454402240 31947392 18759280
14:51:24 4106 8192000000 10368978464 10793807872 1421396288 14.785 100 57 114 1 1473617200 38129968 14090944
14:52:34 2257 8192000000 10354788960 10765070336 1396650720 14.565 99 84 167 0 1450349504 34273744 19425040
14:53:41 2439 8192000000 10363622272 10786844672 1408135200 14.667 100 65 130 1 1467166144 42642144 16388800
14:54:53 1281 8192000000 10355159104 10767990784 1389877568 14.505 100 84 167 2 1459837840 49033536 20926736
14:56:00 1783 8192000000 10335895680 10745683968 1381278768 14.428 100 81 156 2 1439944960 38669216 19996976
14:57:05 17322 8192000000 10299669344 10725269504 1351467008 14.161 99 32 64 1 1399196800 39002512 8727280
14:58:27 8439 8192000000 10303723280 10728009728 1358114352 14.220 100 47 94 3 1418305200 46914048 13276800
14:59:39 11839 8192000000 10317845232 10747293696 1372508096 14.350 100 46 92 0 1428356992 43322560 12526336
15:01:00 12062 8192000000 10325211376 10755936256 1382853568 14.442 100 33 66 0 1431521408 39631872 9035968
15:02:18 24152 8192000000 10316076032 10756083712 1358022400 14.220 98 19 38 0 1404045808 40527376 5496032
15:04:33 32455 8192000000 10273318992 10717532160 1340807616 14.065 98 15 29 2 1376111136 31691952 3611568
15:06:46 73047 8192000000 10244843744 10708230144 1304195776 13.733 97 6 12 0 1328964000 23222128 1546096
15:09:03 75359 8192000000 10267386496 10697551872 1325684768 13.928 97 23 39 2 1336480480 6962112 3833600
15:11:20 107364 8192000000 10281631440 10718527488 1337051168 14.031 97 26 52 1 1355509072 11976112 6481792
15:14:08 64842 8192000000 10275205712 10732322816 1374782592 14.370 100 22 45 0 1394184832 13893376 5508864
15:16:14 19099 8192000000 10292662000 10744422400 1377134416 14.391 100 22 43 0 1399254960 17496352 4624192
15:18:07 132569 8192000000 10273847136 10738987008 1379254944 14.410 99 42 80 2 1399224096 11086272 8882880
15:22:50 33376 8192000000 10277205872 10740748288 1384996192 14.461 100 12 24 0 1394224640 6433296 2795152
15:24:23 85163 8192000000 10266621296 10722664448 1357960736 14.219 97 14 28 1 1379039552 17644112 3434704
15:26:52 28961 8192000000 10294402576 10745872384 1387319200 14.482 100 36 72 1 1406623824 11293600 8011024
15:28:21 54990 8192000000 10294337920 10764345344 1397416560 14.572 100 18 36 0 1419560048 17480000 4663488
15:30:26 97833 8192000000 10284871504 10754981888 1368329776 14.312 98 13 26 0 1387456032 16065520 3060736
15:33:32 18603 8192000000 10300506752 10763059200 1402178080 14.614 100 25 50 0 1418986128 11138816 5669232
15:34:52 55834 8192000000 10305789456 10761080832 1363505456 14.269 97 23 46 0 1381398240 12265136 5627648
15:37:22 25663 8192000000 10296284624 10745585664 1338479104 14.044 96 26 52 1 1356832448 12822976 5530368
15:38:49 82310 8192000000 10233545152 10674745344 1213556000 12.902 88 16 32 0 1231845024 14935584 3353440
15:41:11 107568 8192000000 10285546352 10730143744 1322825328 13.902 96 11 22 0 1341479808 16127312 2527168
15:44:00 83933 8192000000 10265020480 10725662720 1359406880 14.232 99 11 22 0 1372862528 11221904 2233744
15:46:24 18176 8192000000 10288977424 10725797888 1361787264 14.253 99 51 102 1 1383779712 10228096 11764352
15:47:46 49590 8192000000 10254758160 10720325632 1341678528 14.073 98 6 12 0 1351874320 8870096 1325696
15:49:54 52022 8192000000 10261125456 10715398144 1352053008 14.166 99 21 39 1 1366437856 9755936 4628912
15:52:13 40867 8192000000 10235381712 10686341120 1309478560 13.781 97 6 12 0 1322036192 11301408 1256224
15:53:54 25984 8192000000 10248496992 10694701056 1327554960 13.945 98 22 44 0 1341817120 9065216 5196944
15:55:21 39748 8192000000 10275586624 10723106816 1349365104 14.142 99 33 66 0 1368908992 12130944 7412944
15:57:01 52603 8192000000 10306080000 10756222976 1383873920 14.451 100 24 49 0 1408605552 18871328 5860304
15:58:55 130534 8192000000 10291803376 10746179584 1319384368 13.871 96 12 24 0 1340311264 18556976 2369920
16:02:06 35358 8192000000 10278507408 10717298688 1344213712 14.095 98 24 48 0 1362935264 12560368 6161184
16:03:45 63764 8192000000 10275538608 10724048896 1359487680 14.233 99 29 58 0 1379335872 12913456 6934736
16:06:59 14519 8192000000 10283364064 10730717184 1379191408 14.409 100 40 80 1 1396353024 8280496 8881120
16:08:14 68395 8192000000 10266650848 10729172992 1366274560 14.294 99 13 24 0 1383242864 14505424 2462880
16:10:57 81736 8192000000 10266716416 10745159680 1350868432 14.155 98 14 28 0 1366659120 12574240 3216448
16:13:19 62591 8192000000 10280908672 10739728384 1377344016 14.393 100 19 38 0 1390509472 8960128 4205328
16:15:26 30189 8192000000 10285954912 10733662208 1361391232 14.250 98 29 58 0 1380617936 12869280 6357424
16:17:36 35794 8192000000 10285624064 10740330496 1361048240 14.247 98 26 52 1 1378214496 11640704 5525552
16:19:13 86638 8192000000 10298479104 10765881344 1343542896 14.089 97 19 38 1 1362995856 15402976 4049984
16:22:11 45949 8192000000 10299547984 10750271488 1381002992 14.426 99 26 52 0 1398660208 10904320 6752896
16:24:00 31213 8192000000 10297406384 10750005248 1368024064 14.309 98 23 46 0 1383126320 10139008 4963248
16:25:32 23050 8192000000 10308078976 10747510784 1384023200 14.453 100 32 64 0 1398655552 7892256 6740096
16:26:56 112439 8192000000 10283168976 10735767552 1322220160 13.897 95 21 42 0 1339263744 12246720 4796864
16:30:02 50763 8192000000 10270710000 10730872832 1370516720 14.332 100 9 18 0 1390274896 17362832 2395344
16:31:54 39186 8192000000 10270308848 10725543936 1360249696 14.240 99 15 30 0 1374340608 10469168 3621744
16:33:33 71730 8192000000 10272261248 10737664000 1346829296 14.119 99 9 17 0 1363711440 15361472 1520672
16:35:45 62960 8192000000 10275992560 10720092160 1329400016 13.962 97 23 46 0 1344405632 9641008 5364608
16:37:50 48360 8192000000 9202970880 9466400768 355964464 4.164 26 4 8 0 361352080 4508944 878672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-07-2022 16:39:49 to 24-07-2022 16:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-07-2022 16:40:05 to 24-07-2022 16:40:07.

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          240943
MON$OLDEST_ACTIVE               241946
MON$OLDEST_SNAPSHOT             241946
MON$NEXT_TRANSACTION            241946
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                       2025440
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:86: 
:87: mscs-test-161.budzdorov.ru    Sun Jul 24 16:38:41 2022
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Sun Jul 24 16:38:44 2022
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
--- end of diff output ---

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

24.07.22 16:40:08. 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-07-24 16:40:11 No severe PSQL-related problems occured
24.07.22 16:40:12. End of report.