Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.03.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 16790524 kB
MemAvailable 31600188 kB
Buffers 0 kB
Cached 14903192 kB
SwapCached 2128 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-03-27 16:38:14
DTS_END                         2022-03-27 16:38:14.4600
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/44314
TRN_ID                          246553
ATT_ID                          689
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: 9265.75 20721 1111890
customer order: creation 179.02 348 21482
customer order: refuse 15.33 312 1839
order to supplier: creation 47.12 1239 5654
order to supplier: removal 3.19 98130 383
invoice (draft): creation 46.78 3448 5613
invoice (draft): removal 49.68 813 5961
invoice accept: apply 44.25 16908 5310
invoice accept: cancel 45.44 711 5453
customer reserve: creation 4014.53 171 481744
customer reserve: removal 4526.33 55 543160
realization accept: apply 86.01 161 10321
realization accept: cancel 74.38 95 8926
payment from customer: creation 61.74 103 7409
payment from customer: removal 6.18 76 741
payment to supplier: creation 50.40 111 6048
payment to supplier: removal 3.43 77 411
service: total inventory turnovers 4.64 1026 557
service: total monetary turnovers 6.54 29 785
service: aggregate perf. data 0.74 25409 89
service: refresh index statistics 0.03 265195 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-03-2022 16:39:29 to 27-03-2022 16:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4041
TEST_TIME 2 4074
TEST_TIME 3 4518
TEST_TIME 4 4306
TEST_TIME 5 4151
TEST_TIME 6 4247
TEST_TIME 7 4225
TEST_TIME 8 5006
TEST_TIME 9 5533
TEST_TIME 10 5691
TEST_TIME 11 6243
TEST_TIME 12 6581
TEST_TIME 13 7171
TEST_TIME 14 7130
TEST_TIME 15 7674
TEST_TIME 16 7971
TEST_TIME 17 8314
TEST_TIME 18 8549
TEST_TIME 19 8897
TEST_TIME 20 9207
TEST_TIME 21 9324
TEST_TIME 22 10035
TEST_TIME 23 10440
TEST_TIME 24 10996
TEST_TIME 25 11162
TEST_TIME 26 11083
TEST_TIME 27 10945
TEST_TIME 28 10826
TEST_TIME 29 10793
TEST_TIME 30 10634
TEST_TIME 31 10705
TEST_TIME 32 10579
TEST_TIME 33 10452
TEST_TIME 34 10316
TEST_TIME 35 10227
TEST_TIME 36 10144
TEST_TIME 37 10230
TEST_TIME 38 10289
TEST_TIME 39 10256
TEST_TIME 40 10149
TEST_TIME 41 10159
TEST_TIME 42 10096
TEST_TIME 43 10069
TEST_TIME 44 9990
TEST_TIME 45 9893
TEST_TIME 46 9805
TEST_TIME 47 9715
TEST_TIME 48 9555
TEST_TIME 49 9624
TEST_TIME 50 9610
TEST_TIME 51 9744
TEST_TIME 52 9688
TEST_TIME 53 9700
TEST_TIME 54 9804
TEST_TIME 55 9770
TEST_TIME 56 9834
TEST_TIME 57 9858
TEST_TIME 58 9882
TEST_TIME 59 9912
TEST_TIME 60 9887
TEST_TIME 61 9879
TEST_TIME 62 9976
TEST_TIME 63 9846
TEST_TIME 64 9781
TEST_TIME 65 9697
TEST_TIME 66 9657
TEST_TIME 67 9643
TEST_TIME 68 9661
TEST_TIME 69 9654
TEST_TIME 70 9667
TEST_TIME 71 9664
TEST_TIME 72 9601
TEST_TIME 73 9710
TEST_TIME 74 9728
TEST_TIME 75 9719
TEST_TIME 76 9685
TEST_TIME 77 9755
TEST_TIME 78 9746
TEST_TIME 79 9710
TEST_TIME 80 9714
TEST_TIME 81 9829
TEST_TIME 82 9825
TEST_TIME 83 9740
TEST_TIME 84 9688
TEST_TIME 85 9661
TEST_TIME 86 9685
TEST_TIME 87 9613
TEST_TIME 88 9591
TEST_TIME 89 9575
TEST_TIME 90 9580
TEST_TIME 91 9539
TEST_TIME 92 9466
TEST_TIME 93 9485
TEST_TIME 94 9460
TEST_TIME 95 9452
TEST_TIME 96 9460
TEST_TIME 97 9474
TEST_TIME 98 9485
TEST_TIME 99 9511
TEST_TIME 100 9493
TEST_TIME 101 9521
TEST_TIME 102 9512
TEST_TIME 103 9519
TEST_TIME 104 9544
TEST_TIME 105 9575
TEST_TIME 106 9618
TEST_TIME 107 9605
TEST_TIME 108 9580
TEST_TIME 109 9543
TEST_TIME 110 9504
TEST_TIME 111 9471
TEST_TIME 112 9447
TEST_TIME 113 9400
TEST_TIME 114 9363
TEST_TIME 115 9331
TEST_TIME 116 9318
TEST_TIME 117 9274
TEST_TIME 118 9348
TEST_TIME 119 9328
TEST_TIME 120 9274

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-03-2022 16:39:33 to 27-03-2022 16:39:36.

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 1557216 1557216 0 0.00 0 22149 6 0 0
doc_list_biud 1557216 1557216 0 0.00 0 86403 24 0 0
sp_add_invoice_to_stock 5438 5310 128 2.35 36 157373 16909 0 128
sp_cancel_adding_invoice 5453 5453 0 0.00 5 49031 711 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 11205 313 0 0
sp_cancel_customer_reserve 543160 543160 0 0.00 0 26001 55 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 3 7235 77 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 4181 78 0 0
sp_cancel_supplier_invoice 5969 5961 8 0.13 2 86431 814 0 8
sp_cancel_supplier_order 383 383 0 0.00 5 955960 98130 0 0
sp_cancel_write_off 8926 8926 0 0.00 1 12693 96 0 0
sp_client_order 21482 21482 0 0.00 3 19866 349 0 0
sp_customer_reserve 481744 481744 0 0.00 0 67297 171 0 0
sp_fill_shopping_cart 32741 32741 0 0.00 1 16796 37 0 0
sp_fill_shopping_cart_clo_res 481732 481732 0 0.00 0 18506 13 0 0
sp_get_clo_for_invoice 5294 5294 0 0.00 0 28094 244 0 0
sp_kill_cost_storno 15531 15531 0 0.00 0 8675 49 0 0
sp_kill_qstorno_ret_qs2qd 1101590 1101590 0 0.00 0 86390 30 0 0
sp_kill_qty_storno 581337 581337 0 0.00 0 86399 65 0 0
sp_lock_selected_doc 21687 21687 0 0.00 0 8862 7 0 0
sp_make_cost_storno 29072 29072 0 0.00 0 12954 47 0 0
sp_multiply_rows_for_pdistr 29072 29072 0 0.00 0 7609 25 0 0
sp_multiply_rows_for_qdistr 21482 21482 0 0.00 0 15832 172 0 0
sp_pay_from_customer 7409 7409 0 0.00 4 13085 104 0 0
sp_pay_to_supplier 6048 6048 0 0.00 4 10111 112 0 0
sp_payment_common 13457 13457 0 0.00 1 8261 23 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 11198 279 0 0
sp_qd_handle_on_invoice_upd_sts 10747 10747 0 0.00 0 13143 287 0 0
sp_qd_handle_on_reserve_upd_sts 10321 10321 0 0.00 0 24856 52 0 0
sp_reserve_write_off 10321 10321 0 0.00 6 24886 161 0 0
sp_supplier_invoice 5613 5613 0 0.00 7 96905 3448 0 0
sp_supplier_order 5654 5654 0 0.00 5 37328 1240 0 0
srv_aggregate_perf_data 89 89 0 0.00 2616 351284 25410 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 3633 128649 49642 0 0
srv_find_qd_qs_mism 1061980 1061980 0 0.00 0 22149 6 0 0
srv_make_invnt_saldo 557 557 0 0.00 31 61893 1027 0 0
srv_make_money_saldo 785 785 0 0.00 0 4578 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 87415 394198 265195 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5310 5310 0 0.00 0 20819 149 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 12801 132 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 3196 14 0 0
t$perf-norm:sp_cancel_customer_reserve 778 778 0 0.00 0 3830 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 933 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 7751 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 2541 30 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 36300 1501 0 0
t$perf-norm:sp_cancel_write_off 1099 1099 0 0.00 0 5958 18 0 0
t$perf-norm:sp_client_order 21482 21482 0 0.00 0 23732 16 0 0
t$perf-norm:sp_customer_reserve 1088 1088 0 0.00 0 7817 13 0 0
t$perf-norm:sp_pay_from_customer 7409 7409 0 0.00 0 5549 11 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 23760 16 0 0
t$perf-norm:sp_reserve_write_off 10321 10321 0 0.00 0 12831 14 0 0
t$perf-norm:sp_supplier_invoice 5613 5613 0 0.00 0 5003 15 0 0
t$perf-norm:sp_supplier_order 5654 5654 0 0.00 0 2814 13 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 582 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 6 0 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 3929 34 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 5279 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 38 13 0 0
v_add_invoice_to_stock 10844 10588 256 2.36 0 7588 18 0 256
v_all_customers 39014 39014 0 0.00 0 39 0 0 0
v_all_suppliers 30914 30914 0 0.00 0 103 1 0 0
v_all_wares 42964 42964 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 1504 7 0 0
v_cancel_client_order 3678 3678 0 0.00 0 4439 6 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 4189 8 0 0
v_cancel_customer_reserve 1556 1556 0 0.00 0 3753 7 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 2755 6 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 4671 23 0 16
v_cancel_supplier_order 766 766 0 0.00 0 594 4 0 0
v_cancel_write_off 2198 2198 0 0.00 0 801 11 0 0
v_max_id_clo_ord 5654 5654 0 0.00 0 999 1 0 0
v_max_id_clo_res 1088 1088 0 0.00 0 1762 19 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 6328 9 0 0
v_max_non_paid_invoice 1848 1848 0 0.00 0 4907 5 0 0
v_max_non_paid_realizn 4022 4022 0 0.00 0 3932 6 0 0
v_min_id_clo_ord 5654 5654 0 0.00 0 3320 5 0 0
v_min_id_clo_res 1088 1088 0 0.00 0 8127 13 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 6360 10 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 6852 14 0 0
v_min_non_paid_realizn 7409 7409 0 0.00 0 6948 17 0 0
v_our_firm 10724 10724 0 0.00 0 15 1 0 0
v_reserve_write_off 20642 20642 0 0.00 0 4732 4 0 0
x_lock_depdocs_on_canc_invoice 11018 11018 0 0.00 0 26311 102 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 7167 348 0 0
x_make_qstorno_1000_1200 5654 5654 0 0.00 1 37314 1165 0 0
x_make_qstorno_1200_2000 5613 5613 0 0.00 3 96897 3309 0 0
x_make_qstorno_2100_3300 481744 481744 0 0.00 0 67295 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-03-2022 16:39:37 to 27-03-2022 16:39:41.

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:18 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:19 31388 8192000000 9901180416 10209103872 980012800 10.684 100 86 172 0 1009313872 6313456 22987616
14:10:56 28381 8192000000 9938782320 10260938752 1023095520 11.102 100 93 186 0 1060477136 12940672 24440944
14:12:28 19045 8192000000 9986138672 10322997248 1070660800 11.558 100 79 158 0 1100684960 10596832 19427328
14:14:01 11999 8192000000 10051982000 10400296960 1132569728 12.146 100 87 175 0 1164638544 11690544 20378272
14:15:17 6311 8192000000 10094738352 10452918272 1179557040 12.586 100 78 156 0 1209891536 12760800 17573696
14:16:26 5227 8192000000 10137138832 10507444224 1223806928 12.997 100 81 161 2 1255078944 13953408 17318608
14:17:33 2380 8192000000 10169162480 10544771072 1258421696 13.316 100 87 174 1 1291808496 14147472 19239328
14:18:38 1850 8192000000 10190116448 10574286848 1279117920 13.505 100 78 155 1 1310388496 12884016 18386560
14:19:41 34893 8192000000 10200544224 10596904960 1297719008 13.674 100 61 122 1 1320660576 7720832 15220736
14:21:17 9720 8192000000 10197270720 10608103424 1306658288 13.756 100 32 64 0 1322279568 8292544 7328736
14:22:27 9878 8192000000 10200909216 10612989952 1313054992 13.814 100 32 64 2 1326739376 5913408 7770976
14:23:41 8962 8192000000 10208971056 10629550080 1326202208 13.933 100 16 32 0 1335351904 5743776 3405920
14:24:53 15059 8192000000 10242801744 10643468288 1331114144 13.977 100 65 130 1 1351518528 5069376 15335008
14:26:08 5322 8192000000 10233450624 10647982080 1333482432 13.999 100 28 56 1 1346675888 6884912 6308544
14:27:15 10681 8192000000 10198810000 10612264960 1268145456 13.405 95 16 32 1 1276850768 5276624 3428688
14:28:31 43865 8192000000 10182270960 10585997312 1239297744 13.140 94 45 90 2 1259642144 9509152 10835248
14:30:19 6096 8192000000 10171414432 10569142272 1267306032 13.397 100 44 88 3 1284515024 6452912 10756080
14:31:30 10301 8192000000 10133317232 10534170624 1194797888 12.728 95 22 44 0 1209504912 9552624 5154400
14:32:41 6173 8192000000 10169867584 10572836864 1266069232 13.386 100 34 68 0 1288786912 14782624 7935056
14:33:49 32360 8192000000 10178165104 10585747456 1281266448 13.525 100 31 62 2 1294792528 5939728 7586352
14:35:22 6003 8192000000 10201870192 10607673344 1296769744 13.666 100 42 86 1 1316836816 10758720 9308352
14:36:29 8646 8192000000 10211364816 10618368000 1304427808 13.735 100 32 63 1 1322034176 9728464 7877904
14:37:38 12528 8192000000 10196768624 10619871232 1312765936 13.811 100 20 40 0 1322627712 5292768 4569008
14:38:54 39856 8192000000 10234109488 10645729280 1330349296 13.970 100 47 91 1 1347556544 6603856 10603392
14:40:42 15773 8192000000 10267121712 10683363328 1361690896 14.253 100 50 100 1 1385812224 13028144 11093184
14:42:08 20658 8192000000 10295221008 10716692480 1382131424 14.436 100 38 76 0 1403043888 11983312 8929152
14:43:34 12580 8192000000 10320900608 10737975296 1400719552 14.601 100 54 108 1 1432595984 18891472 12984960
14:45:23 22687 8192000000 10339019952 10756616192 1400564784 14.600 99 54 109 1 1438981168 24407664 14008720
14:46:48 10236 8192000000 10336850720 10764599296 1413774784 14.717 100 47 94 2 1453938608 28319808 11844016
14:48:05 4137 8192000000 10351379296 10772361216 1419394320 14.767 100 60 120 1 1462902112 28628640 14879152
14:49:17 3633 8192000000 10371071584 10786111488 1420172864 14.774 100 83 166 1 1478291680 37046720 21072096
14:50:26 9692 8192000000 10356972704 10778324992 1415841072 14.736 100 66 131 1 1466268544 34804608 15622864
14:51:39 8736 8192000000 10367118176 10785783808 1421798272 14.789 100 67 133 0 1474164128 36174640 16191216
14:52:53 5680 8192000000 10339405008 10749894656 1381189392 14.427 100 77 153 0 1442314400 42415088 18709920
14:54:02 15488 8192000000 10334106432 10754854912 1353638528 14.180 97 43 85 3 1406427552 41123120 11665904
14:55:24 6286 8192000000 10337620432 10761809920 1384769632 14.459 100 51 102 0 1447314288 49160032 13384624
14:56:40 10824 8192000000 10317747664 10739859456 1357092432 14.211 98 43 86 2 1406836768 37939280 11805056
14:57:55 14767 8192000000 10325429472 10749976576 1378738384 14.405 100 45 91 1 1431197664 40970768 11488512
14:59:13 12672 8192000000 10303220496 10732859392 1352772688 14.172 99 38 76 1 1402262448 39114384 10375376
15:00:29 26026 8192000000 10284561952 10722062336 1351021152 14.157 100 26 52 2 1397946144 39619584 7305408
15:02:00 30542 8192000000 10265304016 10709463040 1328549120 13.954 99 20 38 0 1367286048 34055536 4681392
15:03:31 32119 8192000000 10264187120 10688163840 1347633344 14.126 100 40 77 2 1364932784 7708528 9590912
15:05:07 44587 8192000000 10256977344 10691616768 1306582336 13.755 97 17 34 1 1320494160 9884688 4027136
15:07:08 101230 8192000000 10281927296 10717560832 1356957920 14.210 100 34 68 0 1379815840 14422464 8435456
15:09:54 84004 8192000000 10284643152 10722144256 1331571664 13.981 97 28 56 0 1354757728 17352672 5833392
15:12:19 95001 8192000000 10301133600 10737799168 1353705008 14.181 98 22 43 0 1374511696 15122960 5683728
15:14:58 43434 8192000000 10294937184 10744893440 1385324000 14.464 100 34 66 0 1408420992 14578016 8518976
15:16:43 39914 8192000000 10287312016 10739757056 1368509168 14.314 99 16 32 0 1387711888 15481152 3721568
15:18:39 76907 8192000000 10309042096 10771279872 1395690848 14.557 100 21 42 1 1421782304 21207216 4884240
15:20:56 55227 8192000000 10298249856 10760114176 1387336624 14.482 100 12 23 0 1406096672 15553520 3206528
15:22:56 70020 8192000000 10287474960 10752266240 1370084112 14.328 98 20 40 0 1383020800 8324464 4612224
15:25:06 81305 8192000000 10300962080 10770550784 1398260912 14.580 100 13 26 2 1412860032 11853008 2746112
15:27:53 128649 8192000000 10297115680 10772549632 1356177360 14.203 97 10 20 0 1378267168 19702800 2387008
15:31:06 46195 8192000000 10288318560 10743431168 1337984912 14.039 96 20 40 1 1355523328 12550208 4988208
15:32:55 24447 8192000000 10288286128 10739654656 1342107744 14.076 97 29 58 1 1360316000 11565056 6643200
15:34:23 44006 8192000000 10296748176 10739744768 1355979184 14.201 98 23 46 2 1375019120 13822144 5217792
15:36:08 20107 8192000000 10281200576 10723807232 1342460704 14.080 98 37 74 0 1362372256 11919584 7991968
15:37:41 62541 8192000000 10270377408 10708967424 1253257328 13.268 92 25 50 1 1273673360 15149872 5266160
15:39:44 44075 8192000000 10288120256 10726453248 1344083008 14.094 98 31 63 0 1367270656 16234320 6953328
15:41:30 94230 8192000000 10262334224 10716663808 1328681616 13.955 97 11 22 0 1341204448 9813504 2709328
15:45:57 64911 8192000000 10264106224 10722709504 1332218544 13.987 97 8 16 0 1350640288 16694800 1726944
15:48:15 64886 8192000000 10275968800 10730893312 1337755936 14.037 97 19 38 0 1349875968 7447872 4672160
15:50:22 32649 8192000000 10261179168 10706333696 1317724368 13.856 96 30 61 1 1337238816 12434080 7080368
15:51:55 80042 8192000000 10261203056 10718556160 1347792992 14.128 99 16 32 1 1366415584 15170080 3452512
15:54:15 30797 8192000000 10264827600 10700345344 1326640976 13.937 98 25 50 1 1345774944 13609024 5524944
15:55:48 67932 8192000000 10263545424 10703208448 1324003696 13.913 97 21 42 0 1337850560 9012944 4833920
15:58:04 27784 8192000000 10293807024 10722770944 1340374608 14.061 98 39 78 1 1364440016 15540384 8525024
15:59:35 85245 8192000000 10260142672 10714873856 1326485440 13.935 97 16 32 0 1349172128 18284640 4402048
16:03:16 51949 8192000000 10272817632 10728521728 1341343920 14.070 98 17 34 0 1360919152 15628368 3946864
16:05:13 68940 8192000000 10272562800 10739757056 1342658768 14.081 98 8 16 0 1360622512 16291840 1671904
16:07:25 111356 8192000000 10271494912 10737369088 1368389840 14.313 99 15 30 1 1382317472 9944512 3983120
16:10:20 102680 8192000000 10280907504 10753548288 1373475200 14.358 99 8 16 0 1385008896 9976752 1556944
16:13:48 58944 8192000000 10289985616 10748731392 1338148496 14.041 96 13 26 1 1350545744 9867696 2529552
16:15:47 73303 8192000000 10294589552 10748743680 1353022880 14.175 97 18 36 0 1366055712 9123648 3909184
16:18:02 39617 8192000000 10293572368 10747961344 1370625296 14.333 99 20 40 1 1382846800 7847104 4374400
16:19:59 32973 8192000000 10272943008 10715361280 1325687392 13.928 96 26 52 1 1341118144 9701536 5729216
16:21:46 55663 8192000000 10274208656 10719850496 1299167568 13.688 95 24 48 0 1319943968 15499072 5277328
16:23:44 120992 8192000000 10271554784 10710740992 1266898192 13.393 93 17 34 0 1287819728 17265248 3656288
16:26:51 103761 8192000000 10268621152 10723909632 1360451264 14.241 100 15 30 2 1373607216 10090816 3065136
16:29:41 50471 8192000000 10260729952 10723917824 1361805184 14.254 99 6 12 0 1370456832 7569504 1082144
16:31:35 51390 8192000000 10251842480 10716078080 1338009920 14.039 98 5 10 0 1346962368 8002576 949872
16:33:27 67474 8192000000 10264984096 10727981056 1339433344 14.052 98 20 40 0 1355983536 10911984 5638208
16:35:49 76441 8192000000 10247018240 10701955072 1313501328 13.818 97 12 24 0 1336433568 19802768 3129472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-03-2022 16:39:43 to 27-03-2022 16:39:58.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 27-03-2022 16:39:59 to 27-03-2022 16:40:00.

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          246230
MON$OLDEST_ACTIVE               247007
MON$OLDEST_SNAPSHOT             247007
MON$NEXT_TRANSACTION            247007
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                       2024800
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:546: 
:547: mscs-test-161.budzdorov.ru    Sun Mar 27 16:38:20 2022
:548:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:549: 
:550: 
:551: mscs-test-161.budzdorov.ru    Sun Mar 27 16:38:20 2022
:552:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:553: 
:554: 
:555: mscs-test-161.budzdorov.ru    Sun Mar 27 16:38:22 2022
:556:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:557: 
:558: 
:559: mscs-test-161.budzdorov.ru    Sun Mar 27 16:38:22 2022
:560:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:561: 
--- end of diff output ---

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

27.03.22 16:40:02. 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-03-27 16:40:04 No severe PSQL-related problems occured
27.03.22 16:40:05. End of report.