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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16084612 kB
MemAvailable 30839664 kB
Buffers 0 kB
Cached 15572800 kB
SwapCached 340 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.8
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 2021-09-08 01:37:47
DTS_END                         2021-09-08 01:37:47.6510
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/33682
TRN_ID                          246578
ATT_ID                          663
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: 9572.91 20286 1148749
customer order: creation 180.64 366 21677
customer order: refuse 14.91 363 1789
order to supplier: creation 47.36 1321 5683
order to supplier: removal 3.53 98618 423
invoice (draft): creation 47.10 3442 5652
invoice (draft): removal 54.22 791 6506
invoice accept: apply 43.56 18187 5227
invoice accept: cancel 48.53 820 5824
customer reserve: creation 4022.13 180 482655
customer reserve: removal 4801.13 57 576136
realization accept: apply 87.52 213 10502
realization accept: cancel 87.36 114 10483
payment from customer: creation 62.07 146 7448
payment from customer: removal 6.23 75 747
payment to supplier: creation 50.58 146 6070
payment to supplier: removal 3.78 83 453
service: total inventory turnovers 4.76 986 571
service: total monetary turnovers 6.65 21 798
service: aggregate perf. data 0.84 21348 101
service: refresh index statistics 0.03 258448 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-09-2021 01:38:49 to 08-09-2021 01:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4144
TEST_TIME 2 3639
TEST_TIME 3 3540
TEST_TIME 4 4457
TEST_TIME 5 4216
TEST_TIME 6 4553
TEST_TIME 7 4551
TEST_TIME 8 4692
TEST_TIME 9 4702
TEST_TIME 10 5300
TEST_TIME 11 5394
TEST_TIME 12 5463
TEST_TIME 13 5740
TEST_TIME 14 6139
TEST_TIME 15 6352
TEST_TIME 16 6401
TEST_TIME 17 6531
TEST_TIME 18 6954
TEST_TIME 19 7778
TEST_TIME 20 7754
TEST_TIME 21 8193
TEST_TIME 22 8661
TEST_TIME 23 8814
TEST_TIME 24 9456
TEST_TIME 25 9522
TEST_TIME 26 9839
TEST_TIME 27 10297
TEST_TIME 28 11171
TEST_TIME 29 10865
TEST_TIME 30 11011
TEST_TIME 31 10859
TEST_TIME 32 10942
TEST_TIME 33 10863
TEST_TIME 34 10599
TEST_TIME 35 10477
TEST_TIME 36 10371
TEST_TIME 37 10145
TEST_TIME 38 10052
TEST_TIME 39 10138
TEST_TIME 40 10019
TEST_TIME 41 10121
TEST_TIME 42 10138
TEST_TIME 43 10055
TEST_TIME 44 9978
TEST_TIME 45 9834
TEST_TIME 46 9811
TEST_TIME 47 9841
TEST_TIME 48 9981
TEST_TIME 49 9947
TEST_TIME 50 10092
TEST_TIME 51 10009
TEST_TIME 52 9886
TEST_TIME 53 9948
TEST_TIME 54 10055
TEST_TIME 55 10086
TEST_TIME 56 10252
TEST_TIME 57 10401
TEST_TIME 58 10334
TEST_TIME 59 10277
TEST_TIME 60 10261
TEST_TIME 61 10157
TEST_TIME 62 10140
TEST_TIME 63 10051
TEST_TIME 64 10048
TEST_TIME 65 10041
TEST_TIME 66 9969
TEST_TIME 67 9923
TEST_TIME 68 9869
TEST_TIME 69 9800
TEST_TIME 70 9750
TEST_TIME 71 9789
TEST_TIME 72 9738
TEST_TIME 73 9723
TEST_TIME 74 9807
TEST_TIME 75 9813
TEST_TIME 76 9819
TEST_TIME 77 9808
TEST_TIME 78 9773
TEST_TIME 79 9803
TEST_TIME 80 9845
TEST_TIME 81 9903
TEST_TIME 82 9961
TEST_TIME 83 9939
TEST_TIME 84 10045
TEST_TIME 85 9972
TEST_TIME 86 9878
TEST_TIME 87 9827
TEST_TIME 88 9821
TEST_TIME 89 9779
TEST_TIME 90 9753
TEST_TIME 91 9724
TEST_TIME 92 9654
TEST_TIME 93 9665
TEST_TIME 94 9668
TEST_TIME 95 9727
TEST_TIME 96 9722
TEST_TIME 97 9723
TEST_TIME 98 9756
TEST_TIME 99 9732
TEST_TIME 100 9701
TEST_TIME 101 9695
TEST_TIME 102 9845
TEST_TIME 103 9803
TEST_TIME 104 9844
TEST_TIME 105 9858
TEST_TIME 106 9840
TEST_TIME 107 9862
TEST_TIME 108 9794
TEST_TIME 109 9759
TEST_TIME 110 9702
TEST_TIME 111 9746
TEST_TIME 112 9714
TEST_TIME 113 9689
TEST_TIME 114 9651
TEST_TIME 115 9638
TEST_TIME 116 9609
TEST_TIME 117 9606
TEST_TIME 118 9587
TEST_TIME 119 9605
TEST_TIME 120 9577

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-09-2021 01:38:53 to 08-09-2021 01:38:56.

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 1595230 1595230 0 0.00 0 34155 7 0 0
doc_list_biud 1595229 1595229 0 0.00 0 30508 26 0 0
sp_add_invoice_to_stock 5379 5227 152 2.82 39 188660 18187 0 152
sp_cancel_adding_invoice 5824 5824 0 0.00 5 52469 821 0 0
sp_cancel_client_order 1789 1789 0 0.00 5 9518 363 0 0
sp_cancel_customer_reserve 576136 576136 0 0.00 1 30511 57 0 0
sp_cancel_pay_from_customer 747 747 0 0.00 3 6846 76 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 9185 84 0 0
sp_cancel_supplier_invoice 6516 6506 10 0.15 2 15884 792 0 10
sp_cancel_supplier_order 423 423 0 0.00 7 1003427 98618 0 0
sp_cancel_write_off 10483 10483 0 0.00 1 20131 115 0 0
sp_client_order 21677 21677 0 0.00 3 28367 367 0 0
sp_customer_reserve 482655 482655 0 0.00 0 39932 181 0 0
sp_fill_shopping_cart 33004 33004 0 0.00 1 12172 36 0 0
sp_fill_shopping_cart_clo_res 482646 482646 0 0.00 0 12119 12 0 0
sp_get_clo_for_invoice 5218 5218 0 0.00 0 27874 283 0 0
sp_kill_cost_storno 17507 17507 0 0.00 0 14580 56 0 0
sp_kill_qstorno_ret_qs2qd 1169684 1169684 0 0.00 0 30506 31 0 0
sp_kill_qty_storno 616880 616880 0 0.00 0 30508 68 0 0
sp_lock_selected_doc 21798 21798 0 0.00 0 10288 6 0 0
sp_make_cost_storno 29237 29237 0 0.00 0 34140 69 0 0
sp_multiply_rows_for_pdistr 29237 29237 0 0.00 0 27969 32 0 0
sp_multiply_rows_for_qdistr 21677 21677 0 0.00 0 10378 159 0 0
sp_pay_from_customer 7448 7448 0 0.00 4 29146 147 0 0
sp_pay_to_supplier 6070 6070 0 0.00 5 28533 146 0 0
sp_payment_common 13518 13518 0 0.00 1 28508 50 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 9155 326 0 0
sp_qd_handle_on_invoice_upd_sts 11041 11041 0 0.00 0 21702 318 0 0
sp_qd_handle_on_reserve_upd_sts 10502 10502 0 0.00 0 24744 64 0 0
sp_reserve_write_off 10502 10502 0 0.00 6 34165 214 0 0
sp_supplier_invoice 5652 5652 0 0.00 7 64515 3442 0 0
sp_supplier_order 5683 5683 0 0.00 5 23867 1321 0 0
srv_aggregate_perf_data 101 101 0 0.00 2806 528925 21349 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1736 104409 41758 0 0
srv_find_qd_qs_mism 1098700 1098700 0 0.00 0 20059 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 30 40542 987 0 0
srv_make_money_saldo 798 798 0 0.00 1 265 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 78257 362535 258448 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 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 5227 5227 0 0.00 0 11451 176 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 1 10292 135 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 3644 18 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 702 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 747 747 0 0.00 0 8580 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 2284 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1681 18 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 27439 1509 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 2728 11 0 0
t$perf-norm:sp_client_order 21677 21677 0 0.00 0 7998 19 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 2823 12 0 0
t$perf-norm:sp_pay_from_customer 7448 7448 0 0.00 0 9403 10 0 0
t$perf-norm:sp_pay_to_supplier 6070 6070 0 0.00 0 7108 12 0 0
t$perf-norm:sp_reserve_write_off 10502 10502 0 0.00 0 7095 13 0 0
t$perf-norm:sp_supplier_invoice 5652 5652 0 0.00 0 7008 17 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 6677 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 358 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 444 9 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 1625 22 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 898 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10736 10432 304 2.83 0 7034 19 0 304
v_all_customers 39746 39746 0 0.00 0 28 1 0 0
v_all_suppliers 31724 31724 0 0.00 0 30 1 0 0
v_all_wares 43354 43354 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 2555 9 0 0
v_cancel_client_order 3578 3578 0 0.00 0 2527 5 0 0
v_cancel_customer_prepayment 1494 1494 0 0.00 0 3132 7 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 2579 8 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 9158 13 0 0
v_cancel_supplier_invoice 872 852 20 2.29 0 1085 13 0 20
v_cancel_supplier_order 846 846 0 0.00 0 1274 4 0 0
v_cancel_write_off 2112 2112 0 0.00 0 2405 12 0 0
v_max_id_clo_ord 5683 5683 0 0.00 0 1081 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 3440 21 0 0
v_max_id_ord_sup 5644 5644 0 0.00 0 7549 5 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 142 2 0 0
v_max_non_paid_realizn 3805 3805 0 0.00 0 2883 5 0 0
v_min_id_clo_ord 5683 5683 0 0.00 0 7308 9 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 2357 8 0 0
v_min_id_ord_sup 5644 5644 0 0.00 0 5017 9 0 0
v_min_non_paid_invoice 6070 6070 0 0.00 0 28488 41 0 0
v_min_non_paid_realizn 7448 7448 0 0.00 0 28495 39 0 0
v_our_firm 10894 10894 0 0.00 0 70 1 0 0
v_reserve_write_off 21004 21004 0 0.00 0 4386 6 0 0
x_lock_depdocs_on_canc_invoice 11904 11904 0 0.00 0 20631 104 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 3606 268 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 23856 1239 0 0
x_make_qstorno_1200_2000 5652 5652 0 0.00 3 63852 3319 0 0
x_make_qstorno_2100_3300 482655 482655 0 0.00 0 37663 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-09-2021 01:38:57 to 08-09-2021 01:39:01.

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
23:07:51 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:53 13298 8192000000 10344031552 10659528704 1400977792 14.604 100 82 164 0 1434072832 5700544 27394496
23:10:13 15355 8192000000 10422895664 10752004096 1479881312 15.300 100 91 182 0 1517234048 7783712 29569024
23:11:38 14576 8192000000 10465893152 10800861184 1519612304 15.647 100 85 170 0 1554925920 8721296 26592320
23:12:57 10368 8192000000 10531050512 10881196032 1587909600 16.236 100 79 159 0 1624961824 13227504 23824720
23:14:13 11745 8192000000 10603453808 10973589504 1660796992 16.856 100 61 122 0 1688690624 10159936 17733696
23:15:29 7139 8192000000 10693767904 11070959616 1758342832 17.671 100 81 162 0 1793647456 12873280 22431344
23:16:37 4659 8192000000 10729811696 11112116224 1800053824 18.014 100 88 174 0 1838314960 14687072 23574064
23:17:43 3338 8192000000 10775022864 11162234880 1833182736 18.285 100 84 168 2 1871269392 14289232 23797424
23:18:48 1489 8192000000 10803123440 11191631872 1861427584 18.515 100 87 175 1 1898086320 11930320 24728416
23:19:51 25955 8192000000 10791058528 11205165056 1868418016 18.571 100 43 86 1 1889308880 7791776 13099088
23:21:19 4983 8192000000 10790996752 11219800064 1884623056 18.702 100 30 60 1 1899669456 6566272 8480128
23:22:26 9040 8192000000 10801120208 11234406400 1893717808 18.776 100 14 28 1 1902961968 5593472 3650688
23:23:38 16957 8192000000 10815338848 11245797376 1905089488 18.867 100 26 51 2 1919100208 6127376 7883344
23:24:56 5774 8192000000 10858644560 11263111168 1911255840 18.917 100 54 106 2 1932843040 5483888 16103312
23:26:02 3417 8192000000 10837531472 11268538368 1918780720 18.977 100 43 87 2 1937982864 6810944 12391200
23:27:09 2995 8192000000 10807847776 11241349120 1894927616 18.785 100 30 58 2 1909470704 6397184 8145904
23:28:14 9053 8192000000 10770304672 11198943232 1819156880 18.171 96 20 40 2 1830673120 5682656 5833584
23:29:23 23142 8192000000 10780352176 11179753472 1817497040 18.157 97 61 124 4 1840856544 4874112 18485392
23:30:52 6569 8192000000 10710217216 11120988160 1780118144 17.850 98 34 69 1 1796756096 6529792 10108160
23:32:01 6530 8192000000 10716986064 11136020480 1800671456 18.019 100 26 52 0 1814614928 6601072 7342400
23:33:09 7348 8192000000 10717949968 11135950848 1802855136 18.037 100 21 42 1 1813828992 5469824 5504032
23:34:24 54474 8192000000 10748500912 11178590208 1821593760 18.191 99 35 68 1 1837136672 4818912 10724000
23:36:21 8398 8192000000 10781503200 11206975488 1859424192 18.499 100 21 42 0 1871488576 6436800 5627584
23:37:32 6915 8192000000 10813235136 11237810176 1893039872 18.770 100 34 66 3 1907744928 5995008 8710048
23:38:41 13982 8192000000 10869458624 11278974976 1925280096 19.029 100 60 118 1 1954333696 12478352 16575248
23:40:00 76915 8192000000 10883238608 11314008064 1941675264 19.160 100 41 82 3 1971254352 17786544 11792544
23:42:35 49177 8192000000 10903849248 11339681792 1958400320 19.293 100 37 73 0 1989761888 21091856 10269712
23:44:29 15899 8192000000 10925044784 11365191680 1980435024 19.468 100 46 92 3 2019235504 25490480 13310000
23:45:53 1736 8192000000 10954797952 11375079424 1986693872 19.518 100 70 140 0 2035633152 27541776 21397504
23:47:00 27947 8192000000 10931626432 11365494784 1962103824 19.323 99 36 72 0 2005786864 32030128 11652912
23:48:35 15019 8192000000 10979690608 11413622784 2011768336 19.715 100 62 124 1 2065836336 35073024 18994976
23:49:59 6240 8192000000 10994178720 11430428672 2018909856 19.772 100 60 120 0 2077194192 39759504 18524832
23:51:11 2456 8192000000 11002498144 11428790272 2022470464 19.800 100 79 157 1 2084693088 38991984 23230640
23:52:25 1847 8192000000 10979011488 11400605696 1990389824 19.547 99 80 159 1 2059657408 45254016 24013568
23:53:33 2980 8192000000 10990582864 11412217856 1990105472 19.545 99 79 158 0 2062893520 49214320 23573728
23:54:42 2063 8192000000 10992023120 11411337216 2002534704 19.643 100 78 155 2 2076474656 49583232 24356720
23:55:48 6799 8192000000 10922548528 11345330176 1941040496 19.155 100 69 138 1 2012554336 49344016 22169824
23:56:59 10250 8192000000 10912181584 11344117760 1934452832 19.102 99 46 93 5 1993701248 44144864 15103552
23:58:22 11676 8192000000 10916100288 11347202048 1928611232 19.056 99 47 94 2 1997518560 52698800 16208528
23:59:45 14562 8192000000 10913125904 11354607616 1940085760 19.147 100 35 70 0 2005043312 52919392 12038160
00:01:14 18050 8192000000 10922634720 11371982848 1953306608 19.253 100 25 50 1 2013364704 52096256 7961840
00:03:00 28990 8192000000 10891441696 11356643328 1937353552 19.126 100 19 38 2 1989698080 45996352 6348176
00:04:32 22150 8192000000 10889270368 11367419904 1925058592 19.027 100 15 30 2 1978492576 48143216 5290768
00:06:03 45528 8192000000 10900718496 11332005888 1929228432 19.061 99 27 54 2 1950516464 13590192 7697840
00:07:54 41690 8192000000 10877168944 11326902272 1910791952 18.913 98 7 14 0 1923782144 11705984 1284208
00:09:36 39952 8192000000 10860159504 11309637632 1861214976 18.513 95 12 24 2 1871406848 7272512 2919360
00:11:23 60384 8192000000 10889477840 11333226496 1951220960 19.236 100 39 74 1 1969602352 7251488 11129904
00:13:25 89314 8192000000 10846738960 11308609536 1889007504 18.738 97 12 24 1 1900873616 8248848 3617264
00:16:15 18822 8192000000 10874700512 11330924544 1924424256 19.022 98 30 60 2 1944661136 10723680 9513200
00:17:38 39771 8192000000 10915992464 11353673728 1951730048 19.240 99 51 102 1 1980610528 13488272 15392208
00:19:18 26443 8192000000 10893133936 11364782080 1972493264 19.405 100 7 14 2 1982801248 8273424 2034560
00:20:46 42352 8192000000 10908282432 11378114560 1967259824 19.364 99 9 18 0 1984328928 14494912 2574192
00:22:29 48148 8192000000 10935374224 11409342464 1999329408 19.617 100 14 28 1 2024419152 20348272 4741472
00:24:17 39509 8192000000 10946785488 11413078016 2001405760 19.634 100 23 45 1 2027484736 20145296 5933680
00:26:00 90200 8192000000 10922755312 11409010688 1979893040 19.464 99 11 22 0 2004038576 20613104 3532432
00:28:31 63921 8192000000 10942273504 11407781888 1995443232 19.587 100 16 32 1 2015989136 15349168 5196736
00:31:07 60857 8192000000 10922462224 11387195392 1970030560 19.386 99 22 44 0 2002633216 26132864 6469792
00:33:08 54542 8192000000 10926118304 11408629760 1984322144 19.499 100 10 20 1 2006371120 19467680 2581296
00:35:08 38495 8192000000 10945109968 11384881152 1956914432 19.282 98 27 53 0 1975789648 12007104 6868112
00:37:34 103448 8192000000 10880340432 11349598208 1873877088 18.616 95 31 62 0 1895056016 12854768 8324160
00:40:20 15753 8192000000 10894739520 11339452416 1937050656 19.123 98 40 77 2 1955205968 7527472 10627840
00:41:38 39588 8192000000 10906536736 11383615488 1986481376 19.516 100 28 56 0 2008476880 14860032 7135472
00:43:23 30902 8192000000 10852619184 11318636544 1885162560 18.707 96 27 54 1 1899671360 7009760 7499040
00:44:58 62393 8192000000 10853362048 11333537792 1896295456 18.796 97 10 20 0 1911918720 12811520 2811744
00:47:45 34500 8192000000 10839690880 11301634048 1870652480 18.590 96 35 70 2 1895066048 14865856 9547712
00:49:23 104409 8192000000 10844012528 11310022656 1850509056 18.426 95 18 36 0 1870250736 14023840 5717840
00:52:09 44119 8192000000 10863308432 11333529600 1908194144 18.892 98 27 53 1 1924831104 9349808 7287152
00:53:53 35418 8192000000 10856281120 11326320640 1888449456 18.733 97 20 38 2 1902535360 7763104 6322800
00:55:29 14735 8192000000 10889935840 11335888896 1946533936 19.199 100 59 118 2 1973747296 10500128 16713232
00:56:47 66788 8192000000 10894396016 11367047168 1923080848 19.012 98 26 52 0 1943651056 13574768 6995440
00:58:55 54051 8192000000 10900621504 11373604864 1966805264 19.360 100 13 26 1 1992085104 21671376 3608464
01:00:49 82047 8192000000 10904415040 11387457536 1961921568 19.321 99 13 26 0 1978493872 12829776 3742528
01:03:11 57366 8192000000 10828106448 11281469440 1870096384 18.585 94 37 74 1 1887450816 6424624 10929808
01:05:12 16911 8192000000 10880430304 11341799424 1936156848 19.116 99 44 86 1 1956579024 8345760 12076416
01:06:35 50636 8192000000 10869273024 11348484096 1923201200 19.012 98 7 14 0 1934875888 9878192 1796496
01:08:26 45034 8192000000 10825039504 11297681408 1882326080 18.684 97 11 22 0 1895159840 10070240 2763520
01:10:33 53237 8192000000 10866086240 11341942784 1941405568 19.158 99 14 28 0 1955646352 10204096 4036688
01:12:27 58087 8192000000 10884019840 11363438592 1933119568 19.092 98 22 44 1 1948805040 10169984 5515488
01:14:25 25887 8192000000 10914381488 11379179520 1980175904 19.466 100 39 78 1 2002551088 10630048 11745136
01:15:52 62936 8192000000 10920371872 11386314752 1969954416 19.385 99 22 44 1 1987706320 12020112 5731792
01:17:55 99813 8192000000 10881791360 11367260160 1880731296 18.671 95 24 48 1 1912877552 24781200 7365056
01:20:53 13341 8192000000 10878091728 11329323008 1908524096 18.895 98 36 70 0 1926174928 7244992 10405840
01:22:06 86297 8192000000 10879649584 11362201600 1913863392 18.938 98 14 28 1 1931718032 13793728 4060912
01:24:33 61452 8192000000 10863398384 11338694656 1898055808 18.811 97 24 48 1 1915565712 11170960 6338944
01:26:41 67986 8192000000 10843964064 11315929088 1882916288 18.689 96 19 38 0 1896096656 7470592 5709776
01:28:50 31261 8192000000 10868769056 11345797120 1951037264 19.235 100 24 48 2 1968135152 9624592 7473296
01:30:21 69548 8192000000 10857092384 11337814016 1916925392 18.962 98 12 24 1 1930413856 9608000 3880464
01:32:59 59079 8192000000 10827508192 11304259584 1884880608 18.705 98 16 31 1 1899787664 10597040 4310016
01:35:03 14802 8192000000 10869270960 11316551680 1923384832 19.014 100 50 97 4 1945277936 6437120 15455984
01:36:21 65452 8192000000 10858517392 11331923968 1901694576 18.840 98 13 26 1 1914390384 9031824 3663984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-09-2021 01:39:03 to 08-09-2021 01:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 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 08-09-2021 01:39:19 to 08-09-2021 01:39:21.

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          245159
MON$OLDEST_ACTIVE               246945
MON$OLDEST_SNAPSHOT             246945
MON$NEXT_TRANSACTION            246945
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                       2031840
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33492 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33492 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33492 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33492 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Sep  8 01:37:49 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Wed Sep  8 01:37:49 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Wed Sep  8 01:37:51 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Sep  8 01:37:51 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Sep  8 01:37:53 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

08.09.21 01:39:22. 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
2021-09-08 01:39:24 No severe PSQL-related problems occured
08.09.21 01:39:26. End of report.