Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2021 07:49
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 15874812 kB
MemAvailable 30779296 kB
Buffers 0 kB
Cached 15804404 kB
SwapCached 1628 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-09-24 07:48:25
DTS_END                         2021-09-24 07:48:25.9070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39590
TRN_ID                          237073
ATT_ID                          630
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: 9365.24 23413 1123829
customer order: creation 172.86 435 20743
customer order: refuse 15.28 353 1833
order to supplier: creation 46.98 1401 5638
order to supplier: removal 3.36 127481 403
invoice (draft): creation 46.18 3388 5542
invoice (draft): removal 52.46 1162 6295
invoice accept: apply 42.72 18174 5126
invoice accept: cancel 48.05 890 5766
customer reserve: creation 3920.51 178 470461
customer reserve: removal 4721.58 70 566590
realization accept: apply 84.28 204 10113
realization accept: cancel 80.57 141 9668
payment from customer: creation 59.98 118 7198
payment from customer: removal 6.24 111 749
payment to supplier: creation 49.17 136 5900
payment to supplier: removal 3.11 112 373
service: total inventory turnovers 4.16 3289 499
service: total monetary turnovers 6.71 555 805
service: aggregate perf. data 1.02 12878 122
service: refresh index statistics 0.04 297176 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-09-2021 07:49:33 to 24-09-2021 07:49:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2812
TEST_TIME 2 5282
TEST_TIME 3 5468
TEST_TIME 4 6007
TEST_TIME 5 5922
TEST_TIME 6 6066
TEST_TIME 7 6025
TEST_TIME 8 5817
TEST_TIME 9 6314
TEST_TIME 10 6653
TEST_TIME 11 6742
TEST_TIME 12 7392
TEST_TIME 13 7651
TEST_TIME 14 7585
TEST_TIME 15 7523
TEST_TIME 16 7466
TEST_TIME 17 7469
TEST_TIME 18 7725
TEST_TIME 19 8205
TEST_TIME 20 8448
TEST_TIME 21 8854
TEST_TIME 22 8912
TEST_TIME 23 9257
TEST_TIME 24 9974
TEST_TIME 25 12068
TEST_TIME 26 11868
TEST_TIME 27 11710
TEST_TIME 28 11436
TEST_TIME 29 11195
TEST_TIME 30 11037
TEST_TIME 31 11550
TEST_TIME 32 11285
TEST_TIME 33 11000
TEST_TIME 34 10857
TEST_TIME 35 10918
TEST_TIME 36 10812
TEST_TIME 37 10771
TEST_TIME 38 10584
TEST_TIME 39 10536
TEST_TIME 40 10506
TEST_TIME 41 10296
TEST_TIME 42 10177
TEST_TIME 43 10360
TEST_TIME 44 10362
TEST_TIME 45 10394
TEST_TIME 46 10404
TEST_TIME 47 10388
TEST_TIME 48 10423
TEST_TIME 49 10598
TEST_TIME 50 10537
TEST_TIME 51 10693
TEST_TIME 52 10595
TEST_TIME 53 10485
TEST_TIME 54 10320
TEST_TIME 55 10262
TEST_TIME 56 10156
TEST_TIME 57 10093
TEST_TIME 58 10036
TEST_TIME 59 10015
TEST_TIME 60 10035
TEST_TIME 61 10001
TEST_TIME 62 9940
TEST_TIME 63 9883
TEST_TIME 64 9883
TEST_TIME 65 9985
TEST_TIME 66 9938
TEST_TIME 67 10209
TEST_TIME 68 10176
TEST_TIME 69 10208
TEST_TIME 70 10242
TEST_TIME 71 10194
TEST_TIME 72 10084
TEST_TIME 73 10064
TEST_TIME 74 9985
TEST_TIME 75 9933
TEST_TIME 76 9827
TEST_TIME 77 9784
TEST_TIME 78 9734
TEST_TIME 79 9719
TEST_TIME 80 9737
TEST_TIME 81 9686
TEST_TIME 82 9692
TEST_TIME 83 9697
TEST_TIME 84 9700
TEST_TIME 85 9724
TEST_TIME 86 9800
TEST_TIME 87 9796
TEST_TIME 88 9812
TEST_TIME 89 9809
TEST_TIME 90 9742
TEST_TIME 91 9681
TEST_TIME 92 9642
TEST_TIME 93 9597
TEST_TIME 94 9558
TEST_TIME 95 9525
TEST_TIME 96 9553
TEST_TIME 97 9582
TEST_TIME 98 9575
TEST_TIME 99 9562
TEST_TIME 100 9572
TEST_TIME 101 9590
TEST_TIME 102 9591
TEST_TIME 103 9613
TEST_TIME 104 9630
TEST_TIME 105 9620
TEST_TIME 106 9707
TEST_TIME 107 9704
TEST_TIME 108 9716
TEST_TIME 109 9651
TEST_TIME 110 9576
TEST_TIME 111 9563
TEST_TIME 112 9561
TEST_TIME 113 9518
TEST_TIME 114 9502
TEST_TIME 115 9469
TEST_TIME 116 9422
TEST_TIME 117 9402
TEST_TIME 118 9410
TEST_TIME 119 9424
TEST_TIME 120 9369

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2021 07:49:37 to 24-09-2021 07:49:40.

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 1559351 1559351 0 0.00 0 20017 6 0 0
doc_list_biud 1559351 1559351 0 0.00 0 33232 33 0 0
sp_add_invoice_to_stock 5235 5126 109 2.08 62 196935 18175 0 109
sp_cancel_adding_invoice 5766 5766 0 0.00 4 109420 890 0 0
sp_cancel_client_order 1833 1833 0 0.00 5 8861 354 0 0
sp_cancel_customer_reserve 566590 566590 0 0.00 1 31571 70 0 0
sp_cancel_pay_from_customer 749 749 0 0.00 3 8286 112 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 7453 113 0 0
sp_cancel_supplier_invoice 6300 6295 5 0.07 1 33194 1163 0 5
sp_cancel_supplier_order 403 403 0 0.00 12 1369071 127481 0 0
sp_cancel_write_off 9668 9668 0 0.00 1 14123 142 0 0
sp_client_order 20743 20743 0 0.00 4 27043 436 0 0
sp_customer_reserve 470461 470461 0 0.00 0 112136 178 0 0
sp_fill_shopping_cart 31920 31920 0 0.00 1 17606 48 0 0
sp_fill_shopping_cart_clo_res 470453 470453 0 0.00 0 20989 18 0 0
sp_get_clo_for_invoice 5113 5113 0 0.00 0 41430 399 0 0
sp_kill_cost_storno 16556 16556 0 0.00 0 13798 67 0 0
sp_kill_qstorno_ret_qs2qd 1149546 1149546 0 0.00 0 33188 39 0 0
sp_kill_qty_storno 605780 605780 0 0.00 0 33232 83 0 0
sp_lock_selected_doc 21186 21186 0 0.00 0 8434 8 0 0
sp_make_cost_storno 28323 28323 0 0.00 0 12483 59 0 0
sp_multiply_rows_for_pdistr 28323 28323 0 0.00 0 15709 35 0 0
sp_multiply_rows_for_qdistr 20743 20743 0 0.00 0 15894 242 0 0
sp_pay_from_customer 7198 7198 0 0.00 4 13232 118 0 0
sp_pay_to_supplier 5900 5900 0 0.00 4 15729 136 0 0
sp_payment_common 13098 13098 0 0.00 1 12965 24 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 8854 309 0 0
sp_qd_handle_on_invoice_upd_sts 10878 10878 0 0.00 0 33231 374 0 0
sp_qd_handle_on_reserve_upd_sts 10113 10113 0 0.00 0 28994 60 0 0
sp_reserve_write_off 10113 10113 0 0.00 6 29052 204 0 0
sp_supplier_invoice 5542 5542 0 0.00 6 139486 3388 0 0
sp_supplier_order 5638 5638 0 0.00 5 33289 1402 0 0
srv_aggregate_perf_data 122 122 0 0.00 2848 161272 12878 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1110 142671 46786 0 0
srv_find_qd_qs_mism 1075458 1075458 0 0.00 0 20016 5 0 0
srv_make_invnt_saldo 499 499 0 0.00 42 109019 3290 0 0
srv_make_money_saldo 805 805 0 0.00 1 13956 555 0 0
srv_recalc_idx_stat 5 5 0 0.00 197823 377956 297177 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5126 5126 0 0.00 0 11499 174 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 2426 74 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 8510 27 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 1980 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 749 749 0 0.00 0 5106 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 1137 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 2031 19 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 17137 1538 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 3298 9 0 0
t$perf-norm:sp_client_order 20743 20743 0 0.00 0 14113 21 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 5778 10 0 0
t$perf-norm:sp_pay_from_customer 7198 7198 0 0.00 0 10224 9 0 0
t$perf-norm:sp_pay_to_supplier 5900 5900 0 0.00 0 10195 11 0 0
t$perf-norm:sp_reserve_write_off 10113 10113 0 0.00 0 6365 12 0 0
t$perf-norm:sp_supplier_invoice 5542 5542 0 0.00 0 4776 21 0 0
t$perf-norm:sp_supplier_order 5638 5638 0 0.00 0 6403 19 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 687 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 64 3 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 1018 16 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 1159 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 137 30 0 0
v_add_invoice_to_stock 10440 10222 218 2.08 0 5926 17 0 218
v_all_customers 37774 37774 0 0.00 0 20 1 0 0
v_all_suppliers 31178 31178 0 0.00 0 116 1 0 0
v_all_wares 41486 41486 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 432 6 0 0
v_cancel_client_order 3666 3666 0 0.00 0 3718 10 0 0
v_cancel_customer_prepayment 1498 1498 0 0.00 0 5243 11 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 1311 6 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 1872 14 0 0
v_cancel_supplier_invoice 828 818 10 1.20 0 1451 14 0 10
v_cancel_supplier_order 806 806 0 0.00 0 1293 7 0 0
v_cancel_write_off 2056 2056 0 0.00 0 3618 15 0 0
v_max_id_clo_ord 5638 5638 0 0.00 0 1747 2 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 5604 22 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 5773 5 0 0
v_max_non_paid_invoice 1488 1488 0 0.00 0 522 2 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 5748 5 0 0
v_min_id_clo_ord 5638 5638 0 0.00 0 3106 6 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 5903 16 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 7270 12 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 12957 16 0 0
v_min_non_paid_realizn 7198 7198 0 0.00 0 10239 16 0 0
v_our_firm 10268 10268 0 0.00 0 15 1 0 0
v_reserve_write_off 20226 20226 0 0.00 0 5232 6 0 0
x_lock_depdocs_on_canc_invoice 11652 11652 0 0.00 0 25239 120 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 18798 400 0 0
x_make_qstorno_1000_1200 5638 5638 0 0.00 2 33265 1317 0 0
x_make_qstorno_1200_2000 5542 5542 0 0.00 3 126124 3202 0 0
x_make_qstorno_2100_3300 470461 470461 0 0.00 0 112135 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2021 07:49:41 to 24-09-2021 07:49:45.

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
05:18:29 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:31 19554 8192000000 9725125536 10023997440 804211344 8.939 100 86 172 0 832147344 7173248 20762752
05:20:55 26311 8192000000 9765418368 10073243648 839875184 9.299 100 90 179 1 872726320 11383328 21467808
05:22:35 14306 8192000000 9799084368 10120175616 873601648 9.636 100 83 166 0 910973136 18698960 18672528
05:23:57 20688 8192000000 9878785216 10217287680 921410864 10.110 100 72 144 2 977923920 40910528 15602528
05:25:21 10973 8192000000 9927816064 10275823616 966387104 10.551 100 79 157 1 1041831184 58504944 16939136
05:26:34 4109 8192000000 9968095456 10323591168 998153344 10.861 100 79 158 0 1085996080 72030112 15812624
05:27:41 3649 8192000000 9982407360 10348990464 1022552048 11.097 100 74 148 1 1099615824 62101008 14962768
05:28:46 1938 8192000000 10026011424 10393972736 1060199184 11.458 100 92 184 0 1139518256 60450736 18868336
05:29:49 1218 8192000000 10046280912 10421305344 1066700416 11.521 100 78 157 0 1156750912 73906752 16143744
05:30:51 4182 8192000000 10011653776 10405216256 1070161888 11.554 100 42 84 1 1130130400 51447888 8520624
05:31:57 6867 8192000000 10004931072 10408288256 1079300896 11.641 100 38 76 1 1123724048 36144704 8278448
05:33:07 9280 8192000000 9991710384 10397061120 1088123280 11.725 100 30 59 1 1110347952 15929680 6294992
05:34:22 5171 8192000000 10012116128 10428653568 1087957520 11.723 100 20 40 1 1113022528 20849200 4215808
05:35:30 6607 8192000000 10026855232 10437570560 1096459424 11.804 100 31 62 1 1145143040 41933056 6750560
05:36:37 7484 8192000000 10028141056 10436395008 1102381248 11.860 100 47 95 1 1140605472 27737696 10486528
05:37:45 18503 8192000000 9996959728 10404843520 1058027648 11.438 97 28 56 0 1084570720 19862784 6680288
05:39:04 16536 8192000000 9997329136 10408534016 1042399072 11.288 96 17 34 0 1073393584 27649984 3344528
05:40:21 4726 8192000000 10004889824 10396258304 1062097872 11.477 99 54 108 1 1093839760 19305328 12436560
05:41:28 8025 8192000000 9947531600 10345816064 998735088 10.866 96 20 40 1 1030280736 26930768 4614880
05:42:37 60701 8192000000 9937124112 10339688448 987354928 10.756 96 27 53 1 1023151712 30430432 5366352
05:44:42 6310 8192000000 9945110400 10339323904 1013987104 11.014 98 44 88 1 1047683616 24208960 9487552
05:45:50 7761 8192000000 9972056368 10356391936 1046371456 11.326 100 27 53 2 1077581584 25298656 5911472
05:46:58 8343 8192000000 9968871360 10368819200 1057597536 11.433 100 30 59 1 1085231104 21868432 5765136
05:48:09 48375 8192000000 9995602304 10392506368 1071177248 11.563 100 44 88 3 1098851168 18533472 9140448
05:50:00 15144 8192000000 10067211856 10465075200 1092735616 11.769 100 54 109 3 1170229184 67300816 10192752
05:51:20 17328 8192000000 10094026032 10496811008 1116729664 11.996 100 38 76 0 1203246080 78788368 7728048
05:52:41 11348 8192000000 10147720496 10552242176 1136977728 12.187 100 54 108 1 1254898224 106961552 10958944
05:53:59 19709 8192000000 10180288144 10602467328 1144937632 12.262 100 41 82 0 1283701456 129646160 9117664
05:55:29 13091 8192000000 10221106608 10638655488 1150580432 12.315 100 48 94 1 1330611248 168624016 11406800
05:56:51 7709 8192000000 10282475168 10692009984 1160742672 12.410 100 55 109 3 1381663776 208952240 11968864
05:58:03 7369 8192000000 10327522560 10750947328 1177527360 12.567 100 73 144 2 1428542032 233965568 17049104
05:59:19 3821 8192000000 10383157792 10791305216 1181633488 12.605 100 75 148 0 1478548064 280675504 16239072
06:00:25 2857 8192000000 10399699760 10811887616 1182473472 12.613 100 77 153 0 1499218432 298993984 17750976
06:01:36 4112 8192000000 10398075984 10803253248 1168045760 12.479 99 75 149 0 1484662800 299970624 16646416
06:02:46 3860 8192000000 10453968912 10867027968 1193560688 12.716 100 76 151 1 1545149584 333622320 17966576
06:03:54 1942 8192000000 10509713488 10914619392 1189524448 12.679 100 87 174 1 1605758064 395922160 20311456
06:05:02 3048 8192000000 10537024272 10955173888 1199753184 12.774 100 69 138 0 1640955168 424871008 16330976
06:06:14 1108 8192000000 10575579136 11004645376 1177672224 12.568 100 87 170 0 1678052032 478804576 21575232
06:07:20 1399 8192000000 10617910368 11036139520 1180041696 12.591 100 71 141 0 1717527504 520065280 17420528
06:08:27 8270 8192000000 10573866816 11005071360 1175880992 12.552 100 45 87 2 1672891424 485401808 11608624
06:09:39 11372 8192000000 10561615488 11042701312 1144918960 12.262 99 36 72 1 1675388176 520720720 9748496
06:10:52 27925 8192000000 10569739264 11043192832 1117333024 12.002 98 32 62 1 1656334704 529836080 9165600
06:12:23 14627 8192000000 10486944976 10978971648 1119169664 12.019 100 37 74 0 1646593552 517260208 10163680
06:13:42 53179 8192000000 10052356912 10468065280 1094119296 11.782 97 24 47 0 1127077584 27697760 5260528
06:15:41 58301 8192000000 10094827200 10527162368 1115702688 11.986 99 17 34 1 1160926688 41366656 3857344
06:17:50 48667 8192000000 10200710160 10649034752 1124174544 12.066 99 41 81 2 1268614224 135761680 8678000
06:20:56 112746 8192000000 10085768016 10524045312 1123097072 12.056 100 40 80 1 1210902304 78699088 9106144
06:23:50 21772 8192000000 10065383520 10494152704 1133938304 12.158 100 24 44 0 1164790208 26703424 4148480
06:25:13 45394 8192000000 10081795808 10557648896 1136816384 12.186 100 21 43 0 1201474096 60271136 4386576
06:27:40 87886 8192000000 10142713456 10612396032 1123627616 12.061 98 20 40 1 1221384768 93060320 4696832
06:31:17 76641 8192000000 10135231376 10603929600 1138393600 12.200 100 24 48 0 1236287216 92476688 5416928
06:33:34 36931 8192000000 10130619824 10601799680 1135132992 12.170 99 27 52 1 1229427232 88571008 5723232
06:35:11 49491 8192000000 10143099808 10580721664 1109636160 11.929 97 22 44 0 1228130000 113577040 4916800
06:37:05 42714 8192000000 10066601264 10513453056 1108726176 11.920 98 15 30 2 1197682768 85422304 3534288
06:38:59 61472 8192000000 10074840624 10555162624 1118941040 12.017 99 23 46 0 1224209088 100436080 4831968
06:41:10 118960 8192000000 10086032128 10540695552 1124651552 12.071 99 33 66 1 1165520672 34509344 6359776
06:44:10 48685 8192000000 10114705552 10571833344 1130161840 12.123 99 12 24 0 1204500384 71699312 2639232
06:47:18 47981 8192000000 10061163984 10546520064 1133672688 12.156 100 11 22 0 1205261296 69163008 2425600
06:49:09 39497 8192000000 10071298320 10536869888 1122972944 12.055 99 21 42 0 1176477952 48887536 4617472
06:51:00 53731 8192000000 10154861264 10600058880 1100510656 11.842 97 24 48 1 1191443312 85338320 5594336
06:52:55 63185 8192000000 10150666208 10606247936 1135892912 12.177 99 28 56 0 1256139936 113725040 6521984
06:55:03 67377 8192000000 10059713456 10552025088 1102609760 11.862 97 15 30 0 1224735680 118912656 3213264
06:57:22 28062 8192000000 10125917760 10572947456 1123755808 12.062 99 33 65 0 1215674352 85683008 6235536
06:58:51 104997 8192000000 10059153088 10519613440 1104244512 11.878 97 16 32 0 1156301392 48741792 3315088
07:01:47 45193 8192000000 10045085840 10502459392 1111984224 11.951 98 10 20 1 1149121168 34622608 2514336
07:03:36 66485 8192000000 10075291120 10533470208 1129419904 12.116 99 8 16 0 1158817520 27839712 1557904
07:05:55 71659 8192000000 10095776464 10546708480 1113245984 11.963 97 18 36 0 1160742448 43374608 4121856
07:08:07 49319 8192000000 10054094448 10494791680 1088341440 11.727 96 29 57 2 1139934592 45692208 5900944
07:10:02 56427 8192000000 10095827968 10552246272 1110608896 11.938 97 26 52 2 1195234448 77965520 6660032
07:12:02 99835 8192000000 10185243712 10642518016 1141550208 12.230 99 27 54 0 1262437872 114484080 6403584
07:14:42 53210 8192000000 10065176368 10551447552 1125284576 12.077 99 14 28 0 1170308720 42486640 2537504
07:16:36 59714 8192000000 10107952032 10548400128 1120090048 12.028 98 34 68 0 1158700992 32185856 6425088
07:18:36 96272 8192000000 10078449856 10521083904 1137535392 12.192 100 44 88 1 1185511776 39386208 8590176
07:21:24 48309 8192000000 10083780960 10568757248 1141089536 12.226 100 11 22 0 1193388016 50205120 2093360
07:23:13 67172 8192000000 10099679040 10613735424 1136887904 12.186 99 23 46 0 1185776944 44008880 4880160
07:25:26 68489 8192000000 10084302048 10543808512 1149260016 12.303 100 11 20 1 1186975056 36014976 1700064
07:27:35 47466 8192000000 10099985088 10574118912 1149727968 12.307 100 21 42 0 1206524784 51715824 5080992
07:29:34 127653 8192000000 10124547680 10604949504 1065589952 11.510 92 23 46 0 1117606432 46534960 5481520
07:32:48 77563 8192000000 10130597088 10639720448 1125400672 12.078 98 12 24 0 1205300256 77348240 2551344
07:35:08 142671 8192000000 10079609216 10550824960 1088130048 11.725 95 12 24 0 1137997536 47010720 2856768
07:38:32 64911 8192000000 10059738160 10490212352 1103928048 11.875 97 38 76 0 1156859824 45188816 7742960
07:40:42 43837 8192000000 10060490656 10533916672 1118157856 12.010 99 7 14 0 1156503392 36667168 1678368
07:42:26 59786 8192000000 10054450352 10519408640 1067977248 11.533 94 15 30 0 1109903904 38621824 3304832
07:44:31 35096 8192000000 10067497552 10524143616 1097953232 11.818 98 23 46 1 1159190368 56383824 4853312
07:46:21 56545 8192000000 10041558096 10489528320 1091998672 11.762 98 18 36 0 1159452960 63485568 3968720
07:48:18 17808 8192000000 8988067760 9223536640 149577536 1.793 14 2 2 1 158076880 8126560 372784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-09-2021 07:49:47 to 24-09-2021 07:50:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2021 07:50:03 to 24-09-2021 07:50:05.

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          237356
MON$OLDEST_ACTIVE               237357
MON$OLDEST_SNAPSHOT             237357
MON$NEXT_TRANSACTION            237357
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                       2048736
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Sep 24 05:53:34 2021
:8:     INET/inet_error: read errno = 104
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Sep 24 07:48:39 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Sep 24 07:48:39 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

24.09.21 07:50:06. 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-09-24 07:50:09 No severe PSQL-related problems occured
24.09.21 07:50:10. End of report.