Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.05.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 17219944 kB
MemAvailable 31471948 kB
Buffers 0 kB
Cached 14528116 kB
SwapCached 2624 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-05-16 16:38:20
DTS_END                         2022-05-16 16:38:20.4700
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/58736
TRN_ID                          240176
ATT_ID                          701
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9441.01 28356 1132921
customer order: creation 170.10 359 20412
customer order: refuse 15.19 333 1823
order to supplier: creation 45.98 1272 5518
order to supplier: removal 3.55 105623 426
invoice (draft): creation 45.03 3160 5404
invoice (draft): removal 54.63 809 6555
invoice accept: apply 42.43 18371 5092
invoice accept: cancel 49.33 743 5919
customer reserve: creation 3875.44 183 465053
customer reserve: removal 4841.02 60 580922
realization accept: apply 82.95 222 9954
realization accept: cancel 86.16 119 10339
payment from customer: creation 58.87 153 7064
payment from customer: removal 5.69 129 683
payment to supplier: creation 48.80 152 5856
payment to supplier: removal 3.33 85 399
service: total inventory turnovers 4.74 1130 569
service: total monetary turnovers 6.93 30 832
service: aggregate perf. data 0.81 21660 97
service: refresh index statistics 0.03 412519 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-05-2022 16:39:51 to 16-05-2022 16:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5805
TEST_TIME 2 3934
TEST_TIME 3 3582
TEST_TIME 4 3615
TEST_TIME 5 4050
TEST_TIME 6 3952
TEST_TIME 7 4035
TEST_TIME 8 4645
TEST_TIME 9 4944
TEST_TIME 10 5253
TEST_TIME 11 5317
TEST_TIME 12 5546
TEST_TIME 13 5930
TEST_TIME 14 5973
TEST_TIME 15 6415
TEST_TIME 16 6797
TEST_TIME 17 6812
TEST_TIME 18 6892
TEST_TIME 19 7685
TEST_TIME 20 7739
TEST_TIME 21 8316
TEST_TIME 22 8651
TEST_TIME 23 8920
TEST_TIME 24 9166
TEST_TIME 25 9372
TEST_TIME 26 10508
TEST_TIME 27 11024
TEST_TIME 28 10954
TEST_TIME 29 10676
TEST_TIME 30 10854
TEST_TIME 31 10719
TEST_TIME 32 10432
TEST_TIME 33 10447
TEST_TIME 34 10251
TEST_TIME 35 10138
TEST_TIME 36 9988
TEST_TIME 37 10099
TEST_TIME 38 10046
TEST_TIME 39 10104
TEST_TIME 40 9974
TEST_TIME 41 9908
TEST_TIME 42 9923
TEST_TIME 43 9860
TEST_TIME 44 9705
TEST_TIME 45 9762
TEST_TIME 46 9795
TEST_TIME 47 9666
TEST_TIME 48 9586
TEST_TIME 49 9628
TEST_TIME 50 9619
TEST_TIME 51 9541
TEST_TIME 52 9615
TEST_TIME 53 9619
TEST_TIME 54 9790
TEST_TIME 55 9798
TEST_TIME 56 9817
TEST_TIME 57 9831
TEST_TIME 58 9828
TEST_TIME 59 9808
TEST_TIME 60 9908
TEST_TIME 61 9797
TEST_TIME 62 9708
TEST_TIME 63 9663
TEST_TIME 64 9661
TEST_TIME 65 9581
TEST_TIME 66 9577
TEST_TIME 67 9542
TEST_TIME 68 9506
TEST_TIME 69 9561
TEST_TIME 70 9620
TEST_TIME 71 9596
TEST_TIME 72 9716
TEST_TIME 73 9708
TEST_TIME 74 9742
TEST_TIME 75 9762
TEST_TIME 76 9758
TEST_TIME 77 9834
TEST_TIME 78 9867
TEST_TIME 79 9897
TEST_TIME 80 9832
TEST_TIME 81 9750
TEST_TIME 82 9749
TEST_TIME 83 9741
TEST_TIME 84 9696
TEST_TIME 85 9724
TEST_TIME 86 9636
TEST_TIME 87 9599
TEST_TIME 88 9680
TEST_TIME 89 9598
TEST_TIME 90 9546
TEST_TIME 91 9641
TEST_TIME 92 9591
TEST_TIME 93 9557
TEST_TIME 94 9655
TEST_TIME 95 9699
TEST_TIME 96 9722
TEST_TIME 97 9700
TEST_TIME 98 9725
TEST_TIME 99 9760
TEST_TIME 100 9761
TEST_TIME 101 9782
TEST_TIME 102 9750
TEST_TIME 103 9793
TEST_TIME 104 9785
TEST_TIME 105 9788
TEST_TIME 106 9778
TEST_TIME 107 9691
TEST_TIME 108 9700
TEST_TIME 109 9654
TEST_TIME 110 9645
TEST_TIME 111 9655
TEST_TIME 112 9632
TEST_TIME 113 9602
TEST_TIME 114 9597
TEST_TIME 115 9555
TEST_TIME 116 9528
TEST_TIME 117 9500
TEST_TIME 118 9467
TEST_TIME 119 9468
TEST_TIME 120 9446

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-05-2022 16:39:55 to 16-05-2022 16:39:58.

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 1565234 1565234 0 0.00 0 36383 7 0 0
doc_list_biud 1565233 1565233 0 0.00 0 32745 28 0 0
sp_add_invoice_to_stock 5188 5092 96 1.85 57 177312 18372 0 96
sp_cancel_adding_invoice 5919 5919 0 0.00 5 69253 743 0 0
sp_cancel_client_order 1823 1823 0 0.00 4 10066 333 0 0
sp_cancel_customer_reserve 580922 580922 0 0.00 1 32760 60 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 25138 129 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 5381 85 0 0
sp_cancel_supplier_invoice 6565 6555 10 0.15 2 28493 809 0 10
sp_cancel_supplier_order 426 426 0 0.00 6 1223102 105623 0 0
sp_cancel_write_off 10339 10339 0 0.00 1 20383 119 0 0
sp_client_order 20412 20412 0 0.00 3 24578 360 0 0
sp_customer_reserve 465053 465053 0 0.00 0 84199 183 0 0
sp_fill_shopping_cart 31321 31321 0 0.00 1 10473 36 0 0
sp_fill_shopping_cart_clo_res 465042 465042 0 0.00 0 30349 15 0 0
sp_get_clo_for_invoice 5080 5080 0 0.00 0 25392 302 0 0
sp_kill_cost_storno 17340 17340 0 0.00 0 15808 60 0 0
sp_kill_qstorno_ret_qs2qd 1179164 1179164 0 0.00 0 32724 33 0 0
sp_kill_qty_storno 621018 621018 0 0.00 0 32742 70 0 0
sp_lock_selected_doc 20916 20916 0 0.00 0 8330 8 0 0
sp_make_cost_storno 27954 27954 0 0.00 0 35878 77 0 0
sp_multiply_rows_for_pdistr 27954 27954 0 0.00 0 24100 33 0 0
sp_multiply_rows_for_qdistr 20412 20412 0 0.00 0 24475 164 0 0
sp_pay_from_customer 7064 7064 0 0.00 4 33694 154 0 0
sp_pay_to_supplier 5856 5856 0 0.00 4 31555 152 0 0
sp_payment_common 12920 12920 0 0.00 1 27637 46 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 10052 306 0 0
sp_qd_handle_on_invoice_upd_sts 10999 10999 0 0.00 0 20776 323 0 0
sp_qd_handle_on_reserve_upd_sts 9954 9954 0 0.00 0 19580 57 0 0
sp_reserve_write_off 9954 9954 0 0.00 7 36415 223 0 0
sp_supplier_invoice 5404 5404 0 0.00 6 80500 3161 0 0
sp_supplier_order 5518 5518 0 0.00 5 25119 1273 0 0
srv_aggregate_perf_data 97 97 0 0.00 3476 355824 21661 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1773 100810 44279 0 0
srv_find_qd_qs_mism 1086134 1086134 0 0.00 0 15765 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 25 56224 1131 0 0
srv_make_money_saldo 832 832 0 0.00 1 3016 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 233079 568963 412520 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5092 5092 0 0.00 0 15459 175 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 7876 104 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 5983 19 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 3375 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1319 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 388 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 456 10 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 27133 1731 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 2535 8 0 0
t$perf-norm:sp_client_order 20412 20412 0 0.00 0 10427 17 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 443 3 0 0
t$perf-norm:sp_pay_from_customer 7064 7064 0 0.00 0 8441 9 0 0
t$perf-norm:sp_pay_to_supplier 5856 5856 0 0.00 0 7215 12 0 0
t$perf-norm:sp_reserve_write_off 9954 9954 0 0.00 0 10407 10 0 0
t$perf-norm:sp_supplier_invoice 5404 5404 0 0.00 0 3557 16 0 0
t$perf-norm:sp_supplier_order 5518 5518 0 0.00 0 10293 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 984 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 813 19 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 4128 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 45 15 0 0
v_add_invoice_to_stock 10350 10158 192 1.85 0 4734 19 0 192
v_all_customers 37208 37208 0 0.00 0 31 1 0 0
v_all_suppliers 30318 30318 0 0.00 0 82 1 0 0
v_all_wares 40822 40822 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 818 7 0 0
v_cancel_client_order 3646 3646 0 0.00 0 2591 5 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 9316 16 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 2865 9 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 3275 14 0 0
v_cancel_supplier_invoice 848 828 20 2.35 0 1678 18 0 20
v_cancel_supplier_order 852 852 0 0.00 0 810 5 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2200 15 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 5222 2 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 1672 8 0 0
v_max_id_ord_sup 5393 5393 0 0.00 0 5435 4 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 213 2 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 5029 7 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 3212 8 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 2658 13 0 0
v_min_id_ord_sup 5393 5393 0 0.00 0 4555 4 0 0
v_min_non_paid_invoice 5856 5856 0 0.00 0 13102 33 0 0
v_min_non_paid_realizn 7064 7064 0 0.00 0 27632 42 0 0
v_our_firm 10448 10448 0 0.00 0 14 1 0 0
v_reserve_write_off 19908 19908 0 0.00 0 4494 7 0 0
x_lock_depdocs_on_canc_invoice 12060 12060 0 0.00 0 16087 120 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 12651 416 0 0
x_make_qstorno_1000_1200 5518 5518 0 0.00 2 25106 1195 0 0
x_make_qstorno_1200_2000 5404 5404 0 0.00 2 70921 3032 0 0
x_make_qstorno_2100_3300 465053 465053 0 0.00 0 84199 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-05-2022 16:39:59 to 16-05-2022 16:40:03.

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:24 21 8192000000 8813077712 8829575168 15933216 0.194 4 4 5 0 16159968 87456 139296
14:09:25 20188 8192000000 9890552320 10196795392 974882224 10.634 100 90 180 0 1005290016 6176240 24231552
14:10:48 28894 8192000000 9940852288 10263588864 1020300016 11.075 100 85 170 0 1049913904 8322208 21291680
14:12:24 13096 8192000000 9990660816 10324815872 1070630576 11.558 100 82 164 0 1101182928 10349008 20203344
14:13:44 11017 8192000000 10047089792 10394718208 1124003728 12.065 100 78 156 1 1154002304 11741232 18257344
14:15:00 14017 8192000000 10083776832 10445889536 1167354896 12.472 100 80 158 0 1199415712 13232336 18828480
14:16:17 7243 8192000000 10126912768 10495479808 1210326384 12.872 100 72 143 2 1238544352 12425168 15792800
14:17:27 7007 8192000000 10150092320 10531475456 1241935824 13.164 100 80 158 0 1272991168 13723056 17332288
14:18:37 2629 8192000000 10199759712 10577035264 1276073408 13.477 100 89 178 0 1311178688 16004720 19100560
14:19:42 1602 8192000000 10215756880 10598031360 1297227088 13.670 100 93 187 1 1331638016 13728624 20682304
14:20:45 23166 8192000000 10210354512 10608246784 1306139456 13.751 100 44 89 1 1322399104 5831872 10427776
14:22:09 5719 8192000000 10210590272 10617307136 1314934768 13.831 100 32 65 0 1328532848 6404368 7193712
14:23:17 12568 8192000000 10211162048 10625560576 1320189216 13.878 100 22 44 2 1331492512 6532704 4770592
14:24:32 8642 8192000000 10212151744 10631970816 1327583072 13.945 100 16 32 0 1337431072 6504672 3343328
14:25:46 36062 8192000000 10231473952 10648756224 1333519504 13.999 100 32 62 3 1347120304 6331456 7269344
14:27:22 4723 8192000000 10239126832 10651619328 1338918176 14.048 100 38 76 2 1354085728 6752800 8414752
14:28:29 7327 8192000000 10210261648 10622214144 1302112208 13.714 98 30 60 0 1315803904 7228912 6462784
14:29:39 55166 8192000000 10185182832 10570108928 1219498208 12.957 94 38 74 1 1234010672 4793824 9718640
14:31:35 5253 8192000000 10153597056 10556166144 1261398688 13.343 100 46 92 1 1278600112 6360160 10841264
14:32:41 6524 8192000000 10161032448 10565533696 1265536192 13.381 100 40 81 1 1282318832 6675616 10107024
14:33:56 10268 8192000000 10150479024 10556198912 1253551984 13.271 99 22 45 1 1264345744 5881008 4912752
14:35:12 8225 8192000000 10161458544 10568134656 1267698912 13.401 100 22 44 2 1282527792 9878816 4950064
14:36:21 3504 8192000000 10184443152 10590683136 1287692368 13.583 100 31 62 0 1301623808 6962576 6968864
14:37:26 8751 8192000000 10186192608 10606206976 1301851872 13.712 100 24 48 1 1313804336 6638464 5314000
14:38:42 76009 8192000000 10247182512 10662805504 1340033552 14.058 100 40 78 0 1356822544 6915120 9873872
14:41:05 46403 8192000000 10268375808 10687090688 1357671008 14.216 100 41 81 0 1382068432 14989264 9408160
14:43:03 18176 8192000000 10278912304 10698473472 1367691600 14.306 100 37 74 2 1395476416 18227136 9557680
14:44:29 30546 8192000000 10302617056 10724909056 1381742064 14.432 100 45 90 0 1412781328 20367664 10671600
14:46:03 8398 8192000000 10318616928 10743382016 1394139872 14.543 100 47 94 0 1436129344 30414000 11575472
14:47:38 10760 8192000000 10336221168 10762276864 1402741776 14.619 100 45 90 2 1451230720 36755376 11733568
14:48:52 3887 8192000000 10363077008 10780155904 1417319104 14.749 100 72 144 0 1472077056 36573600 18184352
14:50:00 7467 8192000000 10372616144 10794029056 1426138656 14.827 100 70 140 0 1483708608 39982560 17587392
14:51:10 2472 8192000000 10354638480 10760929280 1388458336 14.492 98 80 161 3 1450502128 42289600 19754192
14:52:17 3419 8192000000 10395106640 10807758848 1430189552 14.863 100 79 157 2 1497819440 48369840 19260048
14:53:28 8900 8192000000 10395407120 10812846080 1427609440 14.840 100 75 149 1 1499189248 53173840 18405968
14:54:52 5844 8192000000 10370645824 10786025472 1399912544 14.594 99 71 142 1 1472228848 53465392 18850912
14:56:07 2883 8192000000 10403385952 10809311232 1407522048 14.662 99 92 181 1 1491391616 61372416 22497152
14:57:17 1773 8192000000 10387704592 10805338112 1414887200 14.727 100 72 144 3 1494840848 61775392 18178256
14:58:24 9319 8192000000 10380545536 10803818496 1413301184 14.713 100 56 111 1 1488396080 60027792 15067104
14:59:38 18004 8192000000 10368645120 10806595584 1388369088 14.491 99 45 90 0 1470534752 69229552 12936112
15:01:05 15442 8192000000 10331817248 10772221952 1388276752 14.490 100 36 72 0 1446620912 48573216 9770944
15:02:21 15759 8192000000 10329310432 10776702976 1386522816 14.475 100 26 52 0 1448843040 54547120 7773104
15:03:39 35886 8192000000 10311349408 10762457088 1347398320 14.124 98 19 38 0 1403446656 50257248 5791088
15:05:19 28985 8192000000 10263686784 10709573632 1334729232 14.010 98 6 12 0 1347495408 10777392 1988784
15:06:50 55454 8192000000 10300547136 10729050112 1347817488 14.128 98 15 29 0 1365990992 14696480 3477024
15:08:57 52218 8192000000 10276699056 10712387584 1344610256 14.099 98 26 52 0 1360873520 10357504 5905760
15:10:50 63536 8192000000 10288553088 10737983488 1377837840 14.397 100 29 56 2 1399471552 14702192 6931520
15:12:59 26562 8192000000 10282326832 10739445760 1367514784 14.305 99 14 26 2 1385757360 15294704 2947872
15:14:27 29319 8192000000 10297925632 10738470912 1369275264 14.321 99 38 76 0 1389056640 11363264 8418112
15:15:57 50384 8192000000 10294889696 10740002816 1371506016 14.341 99 29 58 1 1387378608 8901840 6970752
15:17:51 46721 8192000000 10283704128 10743746560 1351284912 14.159 97 14 28 1 1370195840 15437888 3473040
15:20:23 68735 8192000000 10294056608 10760859648 1383260912 14.446 99 19 39 0 1403237056 14382880 5593264
15:22:45 14646 8192000000 10298931552 10761740288 1398345232 14.580 100 15 30 1 1413957920 12180976 3431712
15:24:02 81795 8192000000 10287259664 10758533120 1389427120 14.501 100 11 22 0 1407626224 15800320 2398784
15:26:24 44112 8192000000 10307651088 10752700416 1375784288 14.379 99 30 60 1 1390176064 7529296 6862480
15:28:09 46507 8192000000 10306150384 10762960896 1382436880 14.438 99 20 39 0 1399542912 12632528 4473504
15:29:57 29084 8192000000 10315511088 10760990720 1375129968 14.373 98 25 50 0 1397982192 17088160 5764064
15:31:26 70346 8192000000 10306227856 10761273344 1377914976 14.398 99 17 34 0 1398876672 16857712 4103984
15:33:37 53944 8192000000 10302140704 10762035200 1387443408 14.483 100 21 41 1 1406812432 15009296 4359728
15:35:33 39015 8192000000 10306700928 10751447040 1380743696 14.423 99 29 58 0 1400812384 13942304 6126384
15:37:23 14819 8192000000 10284211104 10718662656 1348891328 14.138 99 47 94 1 1370148336 11126784 10130224
15:38:38 42077 8192000000 10291243328 10730123264 1359149232 14.230 99 20 38 1 1373407664 9737504 4520928
15:40:22 83708 8192000000 10264576272 10721058816 1358840128 14.227 99 27 54 2 1375625744 10681264 6104352
15:42:52 55455 8192000000 10271163888 10737725440 1364603152 14.279 99 12 24 0 1376506384 9510384 2392848
15:45:10 100809 8192000000 10272602784 10720792576 1262721792 13.355 91 20 40 1 1279057600 12275760 4060048
15:47:53 50874 8192000000 10272482880 10732953600 1341963088 14.075 98 12 25 1 1358986064 14636432 2386544
15:49:46 38036 8192000000 10273761440 10714353664 1337012304 14.030 98 33 66 0 1358352320 13327728 8012288
15:51:24 80846 8192000000 10276270496 10708205568 1320065792 13.877 97 17 34 1 1337986832 14256432 3664608
15:53:57 83318 8192000000 10266326240 10719789056 1289359568 13.598 94 17 34 0 1307426384 14636176 3430640
15:56:26 54013 8192000000 10286148512 10731442176 1335303072 14.015 98 23 46 1 1353272448 12780176 5189200
15:58:23 12627 8192000000 10293734448 10729828352 1373109456 14.355 100 26 53 1 1386221008 6630000 6481552
15:59:53 87073 8192000000 10282026400 10748227584 1366674208 14.297 99 24 48 1 1382579136 10484336 5420592
16:02:20 33245 8192000000 10287302160 10738868224 1377916976 14.398 100 38 74 1 1398804784 12132752 8755056
16:03:56 53720 8192000000 10286851984 10746552320 1380185984 14.418 100 10 20 0 1399586864 16998096 2402784
16:06:10 77705 8192000000 10275689456 10731544576 1343173376 14.086 97 16 32 2 1370921216 23761632 3986208
16:08:31 65341 8192000000 10273830336 10748919808 1371287072 14.339 100 6 12 0 1386419152 13814960 1317120
16:10:47 91965 8192000000 10288609328 10751766528 1322094800 13.896 95 19 38 0 1342706272 16393200 4218272
16:13:23 64406 8192000000 10288276928 10749489152 1363041552 14.265 98 13 26 1 1376375216 10602096 2731568
16:15:32 38071 8192000000 10305708480 10747871232 1370895728 14.335 98 40 80 2 1391035536 11020768 9119040
16:17:14 67624 8192000000 10286328816 10739261440 1321999776 13.895 95 29 58 2 1340765232 12543744 6221712
16:19:24 67264 8192000000 10296989120 10751827968 1359066624 14.229 98 27 54 1 1377744272 12528656 6148992
16:21:31 41412 8192000000 10294797968 10751131648 1360935616 14.246 98 24 48 2 1378975312 12927168 5112528
16:23:12 96291 8192000000 10290548160 10748882944 1333802096 14.001 96 20 40 0 1356377664 17756352 4819216
16:26:40 73990 8192000000 10270013824 10720980992 1304262896 13.734 94 22 44 0 1325413520 16133968 5016656
16:28:54 51124 8192000000 10252497024 10704592896 1316039600 13.841 96 18 36 0 1330036608 9657264 4339744
16:31:03 61628 8192000000 10254686016 10708594688 1307497200 13.763 96 13 26 1 1321409088 10968656 2943232
16:33:16 51152 8192000000 10250258848 10707099648 1346239776 14.114 99 15 30 1 1357850512 8186976 3423760
16:35:12 74462 8192000000 10280999472 10745327616 1365035200 14.283 99 17 34 0 1381714304 12529856 4149248
16:37:46 42261 8192000000 8965875728 9163685888 141058800 1.692 11 4 8 0 149746592 7659424 1028368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-05-2022 16:40:05 to 16-05-2022 16:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          240543
MON$OLDEST_ACTIVE               240544
MON$OLDEST_SNAPSHOT             240544
MON$NEXT_TRANSACTION            240544
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                       2026656
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:398: 
:399: mscs-test-161.budzdorov.ru    Mon May 16 16:38:30 2022
:400:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:401: 
:402: 
:403: mscs-test-161.budzdorov.ru    Mon May 16 16:38:31 2022
:404:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:405: 
:406: 
:407: mscs-test-161.budzdorov.ru    Mon May 16 16:38:31 2022
:408:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:409: 
:410: 
:411: mscs-test-161.budzdorov.ru    Mon May 16 16:38:31 2022
:412:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:413: 
:414: 
:415: mscs-test-161.budzdorov.ru    Mon May 16 16:38:31 2022
:416:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:417: 
--- end of diff output ---

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

16.05.22 16:40:24. 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-05-16 16:40:26 No severe PSQL-related problems occured
16.05.22 16:40:27. End of report.