Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.08.2021 10:37
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 16152060 kB
MemAvailable 30912440 kB
Buffers 0 kB
Cached 15521156 kB
SwapCached 336 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-08-29 10:37:05
DTS_END                         2021-08-29 10:37:05.2880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56354
TRN_ID                          236001
ATT_ID                          654
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: 8929.19 23183 1071503
customer order: creation 170.69 414 20483
customer order: refuse 14.65 352 1758
order to supplier: creation 45.70 1470 5484
order to supplier: removal 3.33 111526 399
invoice (draft): creation 46.32 3252 5558
invoice (draft): removal 48.46 959 5815
invoice accept: apply 42.37 19048 5084
invoice accept: cancel 43.56 868 5227
customer reserve: creation 3957.13 186 474855
customer reserve: removal 4261.41 69 511369
realization accept: apply 82.43 195 9891
realization accept: cancel 82.18 120 9862
payment from customer: creation 60.90 139 7308
payment from customer: removal 5.70 121 684
payment to supplier: creation 49.81 140 5977
payment to supplier: removal 3.52 128 422
service: total inventory turnovers 3.98 3751 477
service: total monetary turnovers 6.13 415 735
service: aggregate perf. data 0.93 15772 111
service: refresh index statistics 0.03 304742 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-08-2021 10:37:55 to 29-08-2021 10:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5251
TEST_TIME 2 6022
TEST_TIME 3 5501
TEST_TIME 4 4795
TEST_TIME 5 4178
TEST_TIME 6 4579
TEST_TIME 7 4695
TEST_TIME 8 4869
TEST_TIME 9 4856
TEST_TIME 10 5704
TEST_TIME 11 6187
TEST_TIME 12 6540
TEST_TIME 13 7143
TEST_TIME 14 7522
TEST_TIME 15 7811
TEST_TIME 16 7979
TEST_TIME 17 8134
TEST_TIME 18 8772
TEST_TIME 19 8728
TEST_TIME 20 8708
TEST_TIME 21 9270
TEST_TIME 22 9546
TEST_TIME 23 9732
TEST_TIME 24 10097
TEST_TIME 25 9998
TEST_TIME 26 11418
TEST_TIME 27 11259
TEST_TIME 28 11379
TEST_TIME 29 10990
TEST_TIME 30 10715
TEST_TIME 31 10626
TEST_TIME 32 10435
TEST_TIME 33 10361
TEST_TIME 34 10206
TEST_TIME 35 10103
TEST_TIME 36 9975
TEST_TIME 37 9835
TEST_TIME 38 9657
TEST_TIME 39 9559
TEST_TIME 40 9451
TEST_TIME 41 9277
TEST_TIME 42 9434
TEST_TIME 43 9396
TEST_TIME 44 9336
TEST_TIME 45 9204
TEST_TIME 46 9249
TEST_TIME 47 9131
TEST_TIME 48 9017
TEST_TIME 49 8976
TEST_TIME 50 9205
TEST_TIME 51 9186
TEST_TIME 52 9171
TEST_TIME 53 9159
TEST_TIME 54 9208
TEST_TIME 55 9297
TEST_TIME 56 9336
TEST_TIME 57 9265
TEST_TIME 58 9244
TEST_TIME 59 9447
TEST_TIME 60 9478
TEST_TIME 61 9424
TEST_TIME 62 9318
TEST_TIME 63 9219
TEST_TIME 64 9271
TEST_TIME 65 9278
TEST_TIME 66 9225
TEST_TIME 67 9178
TEST_TIME 68 9150
TEST_TIME 69 9196
TEST_TIME 70 9171
TEST_TIME 71 9141
TEST_TIME 72 9152
TEST_TIME 73 9161
TEST_TIME 74 9286
TEST_TIME 75 9282
TEST_TIME 76 9226
TEST_TIME 77 9275
TEST_TIME 78 9213
TEST_TIME 79 9138
TEST_TIME 80 9125
TEST_TIME 81 9108
TEST_TIME 82 9082
TEST_TIME 83 9051
TEST_TIME 84 8982
TEST_TIME 85 8957
TEST_TIME 86 8932
TEST_TIME 87 9038
TEST_TIME 88 9074
TEST_TIME 89 9074
TEST_TIME 90 9113
TEST_TIME 91 9062
TEST_TIME 92 9114
TEST_TIME 93 9187
TEST_TIME 94 9226
TEST_TIME 95 9202
TEST_TIME 96 9192
TEST_TIME 97 9253
TEST_TIME 98 9311
TEST_TIME 99 9262
TEST_TIME 100 9204
TEST_TIME 101 9190
TEST_TIME 102 9168
TEST_TIME 103 9143
TEST_TIME 104 9123
TEST_TIME 105 9099
TEST_TIME 106 9065
TEST_TIME 107 9012
TEST_TIME 108 9001
TEST_TIME 109 8978
TEST_TIME 110 8949
TEST_TIME 111 8903
TEST_TIME 112 8960
TEST_TIME 113 8945
TEST_TIME 114 8946
TEST_TIME 115 8930
TEST_TIME 116 8962
TEST_TIME 117 8942
TEST_TIME 118 8947
TEST_TIME 119 9003
TEST_TIME 120 8935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-08-2021 10:37:58 to 29-08-2021 10:38:02.

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 1508966 1508966 0 0.00 0 17677 7 0 0
doc_list_biud 1508966 1508966 0 0.00 0 56109 30 0 0
sp_add_invoice_to_stock 5167 5084 83 1.60 44 250778 19048 0 83
sp_cancel_adding_invoice 5227 5227 0 0.00 3 60698 868 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 11842 352 0 0
sp_cancel_customer_reserve 511369 511369 0 0.00 1 33760 69 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 13411 122 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 13445 129 0 0
sp_cancel_supplier_invoice 5823 5815 8 0.13 2 56114 959 0 8
sp_cancel_supplier_order 399 399 0 0.00 12 1194079 111526 0 0
sp_cancel_write_off 9862 9862 0 0.00 1 28580 120 0 0
sp_client_order 20483 20483 0 0.00 4 26224 415 0 0
sp_customer_reserve 474855 474855 0 0.00 0 50365 187 0 0
sp_fill_shopping_cart 31507 31507 0 0.00 1 17893 49 0 0
sp_fill_shopping_cart_clo_res 474845 474845 0 0.00 0 25878 16 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 45189 383 0 0
sp_kill_cost_storno 16195 16195 0 0.00 0 14254 59 0 0
sp_kill_qstorno_ret_qs2qd 1038814 1038814 0 0.00 0 56106 37 0 0
sp_kill_qty_storno 549390 549390 0 0.00 0 56109 80 0 0
sp_lock_selected_doc 20709 20709 0 0.00 0 8598 5 0 0
sp_make_cost_storno 28245 28245 0 0.00 0 11923 57 0 0
sp_multiply_rows_for_pdistr 28245 28245 0 0.00 0 8043 33 0 0
sp_multiply_rows_for_qdistr 20483 20483 0 0.00 0 14152 202 0 0
sp_pay_from_customer 7308 7308 0 0.00 4 18987 139 0 0
sp_pay_to_supplier 5977 5977 0 0.00 5 13451 140 0 0
sp_payment_common 13285 13285 0 0.00 1 9270 22 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 11834 322 0 0
sp_qd_handle_on_invoice_upd_sts 10296 10296 0 0.00 0 30160 365 0 0
sp_qd_handle_on_reserve_upd_sts 9891 9891 0 0.00 0 10658 55 0 0
sp_reserve_write_off 9891 9891 0 0.00 7 13828 196 0 0
sp_supplier_invoice 5558 5558 0 0.00 8 87818 3252 0 0
sp_supplier_order 5484 5484 0 0.00 5 38911 1471 0 0
srv_aggregate_perf_data 111 111 0 0.00 2727 243650 15773 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1823 168827 48009 0 0
srv_find_qd_qs_mism 1021993 1021993 0 0.00 0 17676 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 49 146174 3752 0 0
srv_make_money_saldo 735 735 0 0.00 1 14378 415 0 0
srv_recalc_idx_stat 4 4 0 0.00 140527 400252 304743 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 5084 5084 0 0.00 0 9513 149 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 7396 112 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 1402 10 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 6968 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 564 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 7298 32 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 458 11 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 44640 1659 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 4779 8 0 0
t$perf-norm:sp_client_order 20483 20483 0 0.00 0 6587 16 0 0
t$perf-norm:sp_customer_reserve 1047 1047 0 0.00 0 2549 5 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 3405 7 0 0
t$perf-norm:sp_pay_to_supplier 5977 5977 0 0.00 0 9986 10 0 0
t$perf-norm:sp_reserve_write_off 9891 9891 0 0.00 0 14874 12 0 0
t$perf-norm:sp_supplier_invoice 5558 5558 0 0.00 0 21333 19 0 0
t$perf-norm:sp_supplier_order 5484 5484 0 0.00 0 7001 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 310 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 665 18 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 3864 15 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 15808 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10298 10132 166 1.61 0 15196 23 0 166
v_all_customers 37934 37934 0 0.00 0 15 1 0 0
v_all_suppliers 30708 30708 0 0.00 0 66 1 0 0
v_all_wares 40964 40964 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 13113 23 0 0
v_cancel_client_order 3516 3516 0 0.00 0 7842 7 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 13360 34 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 4160 9 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 13356 22 0 0
v_cancel_supplier_invoice 832 816 16 1.92 0 375 11 0 16
v_cancel_supplier_order 798 798 0 0.00 0 466 3 0 0
v_cancel_write_off 1922 1922 0 0.00 0 6254 21 0 0
v_max_id_clo_ord 5479 5479 0 0.00 0 3022 2 0 0
v_max_id_clo_res 1047 1047 0 0.00 0 1390 7 0 0
v_max_id_ord_sup 5546 5546 0 0.00 0 5642 5 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 673 3 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 7157 6 0 0
v_min_id_clo_ord 5479 5479 0 0.00 0 9695 14 0 0
v_min_id_clo_res 1047 1047 0 0.00 0 1827 6 0 0
v_min_id_ord_sup 5546 5546 0 0.00 0 6640 11 0 0
v_min_non_paid_invoice 5977 5977 0 0.00 0 5927 16 0 0
v_min_non_paid_realizn 7308 7308 0 0.00 0 6291 13 0 0
v_our_firm 10116 10116 0 0.00 0 16 1 0 0
v_reserve_write_off 19781 19781 0 0.00 0 13363 9 0 0
x_lock_depdocs_on_canc_invoice 10634 10634 0 0.00 0 9943 123 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 6956 317 0 0
x_make_qstorno_1000_1200 5484 5484 0 0.00 2 38898 1384 0 0
x_make_qstorno_1200_2000 5558 5558 0 0.00 4 87786 3074 0 0
x_make_qstorno_2100_3300 474855 474855 0 0.00 0 41204 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-08-2021 10:38:03 to 29-08-2021 10:38:07.

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
08:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 29391 8192000000 9717796928 10013827072 795270656 8.848 100 81 162 0 822695040 7520528 19903856
08:09:56 19805 8192000000 9767498112 10079358976 843628864 9.336 100 87 174 0 878665584 14338160 20698560
08:11:20 15999 8192000000 9823481968 10143432704 881271296 9.712 100 92 185 0 929529264 27386400 20871568
08:12:42 11188 8192000000 9875848080 10214866944 923413280 10.130 100 77 154 0 994449744 53894352 17142112
08:13:56 7227 8192000000 9920321216 10265833472 954553728 10.436 100 81 162 1 1030194160 58500256 17140176
08:15:06 4852 8192000000 9966009168 10322550784 995437248 10.834 100 78 154 1 1075182816 64117680 15627888
08:16:13 2253 8192000000 9989719712 10350280704 1017424432 11.047 100 88 175 1 1102243552 67107376 17711744
08:17:17 2499 8192000000 10022461120 10389188608 1042854416 11.292 100 89 173 5 1127584576 66362128 18368032
08:18:22 1199 8192000000 10034370848 10402205696 1060160128 11.458 100 95 188 0 1146408144 66141152 20106864
08:19:25 8337 8192000000 10009952960 10394861568 1066040272 11.514 100 40 79 1 1130712352 56494832 8177248
08:20:34 54845 8192000000 9997874336 10382860288 1078258640 11.631 100 49 96 3 1123842000 34846512 10736848
08:22:30 3144 8192000000 10002890768 10399870976 1087947264 11.723 100 51 99 0 1110154992 10463456 11744272
08:23:37 8983 8192000000 10011396496 10414116864 1088188720 11.725 100 23 46 2 1127573904 35049968 4335216
08:24:46 9277 8192000000 10000285232 10414948352 1094037200 11.781 100 25 47 5 1122935840 23325264 5573376
08:25:56 42690 8192000000 9998849888 10409644032 1060759792 11.464 97 45 91 2 1098161504 27745168 9656544
08:27:39 8052 8192000000 10007742848 10415259648 1064002992 11.495 97 43 87 0 1091918576 18589856 9325728
08:28:52 6435 8192000000 9973853264 10364964864 1046727424 11.329 99 25 50 0 1071499696 19909920 4862352
08:30:02 4951 8192000000 9967607952 10346033152 1030082320 11.169 99 50 101 3 1069737376 28958768 10696288
08:31:08 25826 8192000000 9934737280 10326523904 955881248 10.449 92 43 85 2 978883184 13450256 9551680
08:32:38 4798 8192000000 9940493792 10315177984 1001546592 10.894 98 47 94 2 1040565664 28089568 10929504
08:33:43 6809 8192000000 9970734784 10356064256 1040876272 11.273 99 56 111 0 1068862880 15753104 12233504
08:34:52 68947 8192000000 9978087072 10384519168 1058914992 11.446 100 23 45 1 1075444656 11672192 4857472
08:37:04 29784 8192000000 9998786960 10392477696 1083773808 11.683 100 53 106 1 1114327264 19353504 11199952
08:38:42 14209 8192000000 10067748944 10461679616 1100623536 11.844 100 51 102 0 1173340032 62263760 10452736
08:40:05 32459 8192000000 10102863600 10514014208 1130864704 12.130 100 42 84 2 1209094848 69435904 8794240
08:41:41 72294 8192000000 10166855776 10580295680 1141289232 12.228 100 38 76 2 1276557568 127022896 8245440
08:44:01 28070 8192000000 10182781728 10599084032 1150974928 12.319 100 45 90 0 1299208672 137959552 10274192
08:45:49 11645 8192000000 10234355248 10649280512 1161530560 12.418 100 55 110 4 1341526832 167536912 12459360
08:47:04 7874 8192000000 10276587424 10716745728 1173058016 12.525 100 56 112 0 1405981792 220002272 12921504
08:48:15 4354 8192000000 10305663904 10725376000 1182215376 12.611 100 70 139 1 1414145968 216345840 15584752
08:49:23 1819 8192000000 10328698544 10757722112 1190125872 12.685 100 83 163 4 1433795984 224823296 18846816
08:50:29 2976 8192000000 10332747248 10741706752 1171713072 12.513 100 78 156 1 1428930816 239294560 17923184
08:51:36 5727 8192000000 10343176096 10771320832 1191640128 12.699 100 65 130 1 1439902640 233400160 14862352
08:52:45 3542 8192000000 10371635824 10788716544 1185119632 12.638 99 67 133 0 1453251264 253015744 15115888
08:53:50 2178 8192000000 10366614864 10775797760 1173466768 12.529 100 82 162 0 1457151408 265428448 18256192
08:54:55 8019 8192000000 10396895296 10823733248 1161914176 12.421 100 50 97 0 1486802112 313708272 11179664
08:56:06 14055 8192000000 10358343760 10791968768 1169995360 12.497 100 31 62 0 1451938784 273942576 8000848
08:57:25 2538 8192000000 10416857760 10818801664 1154266992 12.350 100 92 185 2 1510471184 334048800 22155392
08:58:31 12933 8192000000 10377335312 10808508416 1148573328 12.296 100 44 87 1 1483345840 323274192 11498320
09:00:03 8111 8192000000 10315139920 10736148480 1097724848 11.816 97 36 72 0 1393384048 286481904 9177296
09:01:12 36444 8192000000 10320153808 10849796096 1124057904 12.065 99 15 30 0 1440638928 312324016 4257008
09:03:09 9316 8192000000 10220603440 10769494016 1114952416 11.979 99 27 54 1 1354841824 233029760 6859648
09:04:51 35513 8192000000 10090009120 10535960576 1112385424 11.955 100 34 68 1 1210989024 90717040 7886560
09:06:33 74020 8192000000 10064475376 10503716864 1114819200 11.978 100 49 98 0 1165262128 40344176 10098752
09:08:55 43276 8192000000 10061485040 10498674688 1107212320 11.906 98 27 52 3 1139883808 27681840 4989648
09:10:48 65630 8192000000 10045471968 10489577472 1106536240 11.900 98 13 26 0 1133178000 24511296 2130464
09:13:12 32176 8192000000 10045357584 10474913792 1110288672 11.935 99 16 29 2 1132602496 19406352 2907472
09:14:47 49530 8192000000 10075788688 10510598144 1122186800 12.048 99 21 41 1 1167282208 40899376 4196032
09:16:42 103385 8192000000 10130712592 10582196224 1138863072 12.205 100 16 32 0 1199028656 56611904 3553680
09:20:17 87561 8192000000 10070791536 10521960448 1119274544 12.020 98 15 30 1 1173182448 50695808 3212096
09:23:00 52262 8192000000 10079763680 10559909888 1136449680 12.182 100 29 59 0 1210121488 67588768 6083040
09:25:18 66104 8192000000 10152030752 10631647232 1143443136 12.248 99 19 38 3 1230066000 82472688 4150176
09:28:18 67617 8192000000 10106868944 10560307200 1150016352 12.310 100 24 46 1 1190789168 35838368 4934448
09:30:45 59522 8192000000 10111757424 10555944960 1107995760 11.913 96 19 38 0 1180964688 69057312 3911616
09:32:46 97779 8192000000 10087648944 10525876224 1141931056 12.234 99 22 42 2 1176108736 29732128 4445552
09:35:29 75643 8192000000 10101890864 10640207872 1104843680 11.884 95 21 42 0 1226054320 116338096 4872544
09:37:48 68004 8192000000 10122546144 10571739136 1119438592 12.022 97 24 47 2 1146718352 23006048 4273712
09:39:57 89975 8192000000 10123742032 10589585408 1114904064 11.979 97 41 82 1 1220215568 96908720 8402784
09:42:27 51209 8192000000 10043204624 10510815232 1110221312 11.935 98 5 10 0 1136864480 25932864 710304
09:44:19 58883 8192000000 10084768304 10550267904 1119571648 12.023 98 20 39 0 1176287072 52445152 4270272
09:46:25 36468 8192000000 10060027904 10501603328 1096028208 11.800 97 39 75 0 1124743648 20182784 8532656
09:48:02 87243 8192000000 10126270848 10586189824 1069726912 11.549 95 24 48 0 1125947376 50710608 5509856
09:50:32 168827 8192000000 10041674336 10480562176 1030946576 11.178 92 9 18 0 1083353440 50407488 1999376
09:54:21 67124 8192000000 10066617136 10489303040 1083555680 11.681 97 21 42 1 1134293152 45902368 4835104
09:56:29 120117 8192000000 10063503344 10504908800 1076777312 11.617 97 33 66 1 1134430832 51120240 6533280
09:59:41 40863 8192000000 10056981920 10503311360 1107133152 11.905 100 33 66 1 1171919792 58257504 6529136
10:01:22 53810 8192000000 10101290448 10552918016 1125347424 12.077 100 13 25 1 1198768496 70454240 2966832
10:03:16 56798 8192000000 10145880736 10627473408 1127837824 12.101 100 14 28 1 1256441088 125169040 3434224
10:05:13 62794 8192000000 10051672640 10491535360 1104506240 11.880 98 17 35 0 1141050192 33008816 3535136
10:07:17 59674 8192000000 10099269680 10572877824 1136851600 12.186 100 15 30 0 1172621888 33149984 2620304
10:09:16 72591 8192000000 10084816384 10552901632 1122214752 12.048 98 22 44 0 1250768480 123749552 4804176
10:11:33 54991 8192000000 10076600224 10500657152 1101632544 11.853 97 30 60 2 1148063920 40890176 5541200
10:13:29 15109 8192000000 10100993664 10526945280 1128593280 12.108 99 44 86 0 1175421248 38492640 8335328
10:14:46 32596 8192000000 10100080576 10573811712 1144188416 12.255 100 18 36 1 1240199952 92186192 3825344
10:16:27 98795 8192000000 10091876160 10535432192 1138244976 12.199 100 18 36 0 1185087200 43383744 3458480
10:19:12 10784 8192000000 10109710944 10575331328 1147515792 12.286 100 26 52 0 1211404832 58657104 5231936
10:20:52 42494 8192000000 10072267280 10540630016 1121966896 12.046 98 5 10 1 1151368656 28400256 1001504
10:22:49 51261 8192000000 10074944512 10534875136 1151174592 12.321 100 12 24 1 1180763072 27337168 2251312
10:24:46 62830 8192000000 10060408784 10517331968 1136527648 12.183 100 14 28 1 1181805392 42583872 2693872
10:26:50 65675 8192000000 10098175168 10576539648 1134512976 12.164 99 17 33 1 1193340320 54937264 3890080
10:29:02 86006 8192000000 10071281456 10546954240 1092862480 11.770 95 12 24 0 1132542016 36952976 2726560
10:31:36 31831 8192000000 10080826544 10511544320 1113187776 11.963 98 25 50 0 1143523584 25460544 4875264
10:33:09 51610 8192000000 10053104640 10504450048 1047573920 11.337 93 29 59 1 1093785200 40171536 6039744
10:35:06 59708 8192000000 10115679520 10561101824 1119463120 12.022 100 36 72 1 1229812032 102980832 7368080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-08-2021 10:38:09 to 29-08-2021 10:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 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:02, from 29-08-2021 10:38:25 to 29-08-2021 10:38:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235259
MON$OLDEST_ACTIVE               236138
MON$OLDEST_SNAPSHOT             236138
MON$NEXT_TRANSACTION            236138
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-30 07:30:08.6030 GMT
MON$PAGES                       2039072
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2574 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2574 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Sun Aug 29 10:37:06 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Sun Aug 29 10:37:12 2021
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Sun Aug 29 10:37:14 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

29.08.21 10:38:28. There are 3 logs in /var/tmp/logs-oltp40 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-08-29 10:38:30 No severe PSQL-related problems occured
29.08.21 10:38:31. End of report.