Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.09.2021 07: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 15904444 kB
MemAvailable 30874252 kB
Buffers 0 kB
Cached 15804736 kB
SwapCached 396 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-13 07:37:05
DTS_END                         2021-09-13 07:37:05.7350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37388
TRN_ID                          228176
ATT_ID                          670
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: 8943.22 24923 1073186
customer order: creation 161.01 387 19321
customer order: refuse 14.29 372 1715
order to supplier: creation 43.75 1463 5250
order to supplier: removal 3.43 125262 411
invoice (draft): creation 43.40 3750 5208
invoice (draft): removal 51.18 1021 6141
invoice accept: apply 39.45 18858 4734
invoice accept: cancel 46.98 901 5637
customer reserve: creation 3677.50 182 441300
customer reserve: removal 4581.63 73 549795
realization accept: apply 79.74 225 9569
realization accept: cancel 80.48 145 9658
payment from customer: creation 55.83 140 6700
payment from customer: removal 5.44 115 653
payment to supplier: creation 46.08 165 5529
payment to supplier: removal 2.98 120 358
service: total inventory turnovers 3.41 4324 409
service: total monetary turnovers 5.74 603 689
service: aggregate perf. data 0.88 16334 105
service: refresh index statistics 0.03 324028 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-09-2021 07:38:59 to 13-09-2021 07:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4293
TEST_TIME 2 3799
TEST_TIME 3 4214
TEST_TIME 4 3799
TEST_TIME 5 3850
TEST_TIME 6 3936
TEST_TIME 7 4331
TEST_TIME 8 4839
TEST_TIME 9 4923
TEST_TIME 10 5374
TEST_TIME 11 5522
TEST_TIME 12 5441
TEST_TIME 13 5956
TEST_TIME 14 6708
TEST_TIME 15 6857
TEST_TIME 16 7187
TEST_TIME 17 7515
TEST_TIME 18 7680
TEST_TIME 19 7913
TEST_TIME 20 8335
TEST_TIME 21 8340
TEST_TIME 22 8846
TEST_TIME 23 8717
TEST_TIME 24 8928
TEST_TIME 25 9374
TEST_TIME 26 9476
TEST_TIME 27 9525
TEST_TIME 28 9660
TEST_TIME 29 10951
TEST_TIME 30 10827
TEST_TIME 31 10602
TEST_TIME 32 10738
TEST_TIME 33 10796
TEST_TIME 34 10807
TEST_TIME 35 10721
TEST_TIME 36 10693
TEST_TIME 37 10570
TEST_TIME 38 10451
TEST_TIME 39 10369
TEST_TIME 40 10325
TEST_TIME 41 10150
TEST_TIME 42 10209
TEST_TIME 43 10127
TEST_TIME 44 9972
TEST_TIME 45 9911
TEST_TIME 46 9856
TEST_TIME 47 9743
TEST_TIME 48 9578
TEST_TIME 49 9494
TEST_TIME 50 9377
TEST_TIME 51 9358
TEST_TIME 52 9489
TEST_TIME 53 9501
TEST_TIME 54 9390
TEST_TIME 55 9280
TEST_TIME 56 9220
TEST_TIME 57 9219
TEST_TIME 58 9180
TEST_TIME 59 9177
TEST_TIME 60 9228
TEST_TIME 61 9215
TEST_TIME 62 9195
TEST_TIME 63 9281
TEST_TIME 64 9299
TEST_TIME 65 9288
TEST_TIME 66 9327
TEST_TIME 67 9414
TEST_TIME 68 9368
TEST_TIME 69 9398
TEST_TIME 70 9452
TEST_TIME 71 9424
TEST_TIME 72 9346
TEST_TIME 73 9311
TEST_TIME 74 9224
TEST_TIME 75 9226
TEST_TIME 76 9193
TEST_TIME 77 9151
TEST_TIME 78 9178
TEST_TIME 79 9140
TEST_TIME 80 9111
TEST_TIME 81 9023
TEST_TIME 82 9068
TEST_TIME 83 9027
TEST_TIME 84 9052
TEST_TIME 85 9045
TEST_TIME 86 9048
TEST_TIME 87 9108
TEST_TIME 88 9099
TEST_TIME 89 9112
TEST_TIME 90 9102
TEST_TIME 91 9188
TEST_TIME 92 9180
TEST_TIME 93 9201
TEST_TIME 94 9176
TEST_TIME 95 9319
TEST_TIME 96 9264
TEST_TIME 97 9210
TEST_TIME 98 9153
TEST_TIME 99 9137
TEST_TIME 100 9123
TEST_TIME 101 9096
TEST_TIME 102 9081
TEST_TIME 103 9051
TEST_TIME 104 9009
TEST_TIME 105 8970
TEST_TIME 106 8983
TEST_TIME 107 8969
TEST_TIME 108 8953
TEST_TIME 109 8990
TEST_TIME 110 9026
TEST_TIME 111 9024
TEST_TIME 112 9030
TEST_TIME 113 9084
TEST_TIME 114 9071
TEST_TIME 115 9058
TEST_TIME 116 9077
TEST_TIME 117 9111
TEST_TIME 118 9063
TEST_TIME 119 9010
TEST_TIME 120 8944

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-09-2021 07:39:03 to 13-09-2021 07:39:06.

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 1482237 1482237 0 0.00 0 22168 7 0 0
doc_list_biud 1482236 1482236 0 0.00 0 54613 34 0 0
sp_add_invoice_to_stock 4858 4734 124 2.55 42 163626 18859 0 124
sp_cancel_adding_invoice 5637 5637 0 0.00 5 88688 902 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 8715 373 0 0
sp_cancel_customer_reserve 549795 549795 0 0.00 1 54614 73 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 8176 115 0 0
sp_cancel_pay_to_supplier 358 358 0 0.00 3 14295 121 0 0
sp_cancel_supplier_invoice 6146 6141 5 0.08 2 24424 1021 0 5
sp_cancel_supplier_order 411 411 0 0.00 11 1121095 125262 0 0
sp_cancel_write_off 9658 9658 0 0.00 1 10374 145 0 0
sp_client_order 19321 19321 0 0.00 4 34864 387 0 0
sp_customer_reserve 441300 441300 0 0.00 0 77664 182 0 0
sp_fill_shopping_cart 29773 29773 0 0.00 1 27528 53 0 0
sp_fill_shopping_cart_clo_res 441298 441298 0 0.00 0 12172 18 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 32064 448 0 0
sp_kill_cost_storno 16306 16306 0 0.00 0 13198 85 0 0
sp_kill_qstorno_ret_qs2qd 1115800 1115800 0 0.00 0 54607 39 0 0
sp_kill_qty_storno 587657 587657 0 0.00 0 54611 85 0 0
sp_lock_selected_doc 19833 19833 0 0.00 0 10531 5 0 0
sp_make_cost_storno 26529 26529 0 0.00 0 20031 73 0 0
sp_multiply_rows_for_pdistr 26529 26529 0 0.00 0 9955 36 0 0
sp_multiply_rows_for_qdistr 19321 19321 0 0.00 0 12641 193 0 0
sp_pay_from_customer 6700 6700 0 0.00 4 20038 140 0 0
sp_pay_to_supplier 5529 5529 0 0.00 5 12195 165 0 0
sp_payment_common 12229 12229 0 0.00 1 10599 33 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 8016 338 0 0
sp_qd_handle_on_invoice_upd_sts 10368 10368 0 0.00 0 27961 396 0 0
sp_qd_handle_on_reserve_upd_sts 9569 9569 0 0.00 0 19192 67 0 0
sp_reserve_write_off 9569 9569 0 0.00 7 19212 226 0 0
sp_supplier_invoice 5208 5208 0 0.00 6 75146 3750 0 0
sp_supplier_order 5250 5250 0 0.00 5 62121 1463 0 0
srv_aggregate_perf_data 105 105 0 0.00 3029 163111 16335 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1174 189283 45798 0 0
srv_find_qd_qs_mism 1028330 1028330 0 0.00 0 22161 6 0 0
srv_make_invnt_saldo 409 409 0 0.00 66 106820 4324 0 0
srv_make_money_saldo 689 689 0 0.00 1 12071 603 0 0
srv_recalc_idx_stat 4 4 0 0.00 241717 494325 324028 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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 4734 4734 0 0.00 0 7253 175 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 6027 120 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 2161 14 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1272 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 3807 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 358 358 0 0.00 0 1147 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 3437 23 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 20094 1799 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 3653 13 0 0
t$perf-norm:sp_client_order 19321 19321 0 0.00 0 7413 21 0 0
t$perf-norm:sp_customer_reserve 921 921 0 0.00 0 890 5 0 0
t$perf-norm:sp_pay_from_customer 6700 6700 0 0.00 0 3765 11 0 0
t$perf-norm:sp_pay_to_supplier 5529 5529 0 0.00 0 3230 7 0 0
t$perf-norm:sp_reserve_write_off 9569 9569 0 0.00 0 10435 13 0 0
t$perf-norm:sp_supplier_invoice 5208 5208 0 0.00 0 7692 25 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 6851 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 552 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 114 4 0 0
t$perf-norm:srv_make_invnt_saldo 409 409 0 0.00 0 2719 25 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 3485 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 487 123 0 0
v_add_invoice_to_stock 9710 9462 248 2.55 0 6297 17 0 248
v_all_customers 35526 35526 0 0.00 0 93 1 0 0
v_all_suppliers 29270 29270 0 0.00 0 22 1 0 0
v_all_wares 38642 38642 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1150 8 0 0
v_cancel_client_order 3430 3430 0 0.00 0 2404 6 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 7444 16 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1092 5 0 0
v_cancel_payment_to_supplier 716 716 0 0.00 0 1209 6 0 0
v_cancel_supplier_invoice 756 746 10 1.32 0 3921 21 0 10
v_cancel_supplier_order 822 822 0 0.00 0 5291 14 0 0
v_cancel_write_off 1966 1966 0 0.00 0 3063 16 0 0
v_max_id_clo_ord 5250 5250 0 0.00 0 1739 2 0 0
v_max_id_clo_res 921 921 0 0.00 0 5067 25 0 0
v_max_id_ord_sup 5202 5202 0 0.00 0 4215 3 0 0
v_max_non_paid_invoice 1346 1346 0 0.00 0 931 2 0 0
v_max_non_paid_realizn 3511 3511 0 0.00 0 4394 5 0 0
v_min_id_clo_ord 5250 5250 0 0.00 0 6503 11 0 0
v_min_id_clo_res 921 921 0 0.00 0 1797 6 0 0
v_min_id_ord_sup 5202 5202 0 0.00 0 6150 10 0 0
v_min_non_paid_invoice 5528 5528 0 0.00 0 10594 24 0 0
v_min_non_paid_realizn 6700 6700 0 0.00 0 8624 23 0 0
v_our_firm 9494 9494 0 0.00 0 33 1 0 0
v_reserve_write_off 19138 19138 0 0.00 0 5095 7 0 0
x_lock_depdocs_on_canc_invoice 11405 11405 0 0.00 0 22323 124 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 8075 389 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 2 62095 1370 0 0
x_make_qstorno_1200_2000 5208 5208 0 0.00 3 71374 3545 0 0
x_make_qstorno_2100_3300 441300 441300 0 0.00 0 77662 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-09-2021 07:39:07 to 13-09-2021 07:39:11.

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:07:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 32372 8192000000 9736762496 10036105216 813408848 9.032 100 90 180 0 842368320 7607936 21351536
05:09:46 18964 8192000000 9770735888 10075541504 841606816 9.316 100 93 186 0 876973264 13334800 22031648
05:11:11 23540 8192000000 9808753408 10130014208 865824240 9.558 100 87 174 0 915530192 29392448 20313504
05:12:38 14456 8192000000 9866525248 10199670784 910288784 10.000 100 74 148 0 973949616 47662288 15998544
05:13:56 11613 8192000000 9924870096 10270773248 954124864 10.432 100 81 162 0 1043981520 73270240 16586416
05:15:12 4883 8192000000 9960647840 10314645504 991064736 10.792 100 86 168 1 1084889440 76597632 17227072
05:16:21 3357 8192000000 9989272256 10352152576 1021181312 11.083 100 82 164 0 1102940784 64981408 16778064
05:17:27 1812 8192000000 10021850880 10387918848 1037961488 11.245 100 87 174 1 1132984032 77657344 17365200
05:18:30 1815 8192000000 10039942848 10409766912 1071071488 11.562 100 95 190 1 1151517136 60926752 19518896
05:19:32 9046 8192000000 9990807424 10391547904 1071976368 11.571 100 25 50 0 1129045440 51856032 5213040
05:20:42 5317 8192000000 10000842064 10389901312 1087305520 11.717 100 38 75 1 1118819008 24034928 7478560
05:21:48 7316 8192000000 10000146368 10404999168 1094474048 11.785 100 39 79 0 1136551616 33803712 8273856
05:22:59 46487 8192000000 10017759008 10425630720 1094405168 11.785 100 32 65 0 1116199072 15156896 6637008
05:24:48 9210 8192000000 10012504528 10414735360 1099453408 11.832 100 20 40 0 1123280000 19843952 3982640
05:25:58 9340 8192000000 10014601456 10420924416 1094291456 11.783 99 33 66 0 1130775632 29449424 7034752
05:27:08 10287 8192000000 10011354592 10413920256 1103420720 11.870 100 22 44 0 1125901984 17911152 4570112
05:28:21 28436 8192000000 10000822928 10409140224 1077490000 11.624 98 18 36 1 1104074160 23049488 3534672
05:29:57 7295 8192000000 9965191440 10351570944 1006195104 10.939 95 39 78 2 1031139264 16226928 8717232
05:31:08 5969 8192000000 9937596528 10322759680 999811776 10.877 98 44 88 0 1043546896 33778736 9956384
05:32:16 45954 8192000000 9942862752 10315673600 1012189648 10.997 98 56 111 1 1032372880 7711312 12471920
05:34:09 6820 8192000000 9963410960 10354167808 1032510416 11.193 100 46 93 1 1068699024 25285312 10903296
05:35:16 3330 8192000000 9965751216 10360135680 1049683184 11.358 100 51 98 1 1078208016 16871536 11653296
05:36:21 6836 8192000000 9977127696 10381033472 1066596080 11.520 100 22 44 1 1101014688 29115824 5302784
05:37:33 24683 8192000000 10015693792 10405171200 1088763216 11.731 100 52 104 0 1110919408 10658992 11497200
05:39:01 59747 8192000000 10065620448 10481438720 1106598336 11.900 100 34 66 1 1175566256 61927920 7040000
05:41:07 21311 8192000000 10095352288 10506027008 1125904288 12.083 100 41 82 1 1204691424 69424240 9362896
05:42:31 40258 8192000000 10166849872 10586603520 1143496352 12.248 100 40 80 1 1268315376 115832800 8986224
05:44:29 16872 8192000000 10196798352 10610143232 1157565728 12.380 100 54 109 0 1303780688 132855488 13359472
05:45:54 11552 8192000000 10212194928 10645377024 1164246432 12.443 100 43 84 0 1320794432 147169632 9378368
05:47:12 3053 8192000000 10294054240 10707263488 1182888960 12.617 100 73 146 1 1404917600 205898704 16129936
05:48:23 8199 8192000000 10310527952 10738511872 1187821632 12.663 100 62 122 1 1421156064 218977456 14356976
05:49:39 12292 8192000000 10366541440 10790055936 1194033520 12.721 100 66 132 2 1474660960 265435024 15192416
05:50:59 1464 8192000000 10342022480 10767974400 1191905728 12.701 100 86 170 0 1444394576 233512336 18976512
05:52:04 1561 8192000000 10365968576 10774691840 1190859248 12.691 100 93 187 2 1458746304 246522496 21364560
05:53:09 2374 8192000000 10388155120 10806808576 1181918128 12.608 100 79 158 0 1489014480 289413280 17683072
05:54:17 2963 8192000000 10368600688 10774622208 1155430096 12.360 98 75 148 2 1460746160 288000320 17315744
05:55:24 8999 8192000000 10398653808 10824355840 1173367712 12.528 100 54 109 0 1508198480 321936672 12894096
05:56:39 3371 8192000000 10389224656 10800918528 1150164288 12.311 99 65 127 0 1478694544 313159744 15370512
05:57:48 7225 8192000000 10376429520 10800054272 1154032576 12.347 100 52 105 0 1482825552 315681456 13111520
05:59:00 1171 8192000000 10447636464 10852732928 1177906512 12.571 100 94 186 0 1540709792 339987984 22815296
06:00:03 6847 8192000000 10383037152 10833842176 1153690720 12.344 100 46 92 0 1488304640 322521936 12091984
06:01:19 4664 8192000000 10444911040 10885079040 1153376672 12.341 100 43 85 1 1547465616 382355152 11733792
06:02:26 7415 8192000000 10382521984 10845745152 1149558368 12.305 100 28 56 2 1475977920 319582256 6837296
06:03:40 16459 8192000000 10373585280 10854584320 1131042800 12.131 100 24 48 1 1490107040 352701088 6363152
06:05:19 4741 8192000000 10373824016 10793959424 1118957488 12.017 99 68 132 1 1454801040 320393696 15449856
06:06:26 18595 8192000000 10153616704 10669068288 1130650736 12.127 100 16 32 0 1267061680 132466688 3944256
06:07:45 40368 8192000000 10172781968 10600685568 1123547504 12.060 99 47 93 0 1243107552 108203360 11356688
06:09:35 33479 8192000000 10169134592 10661322752 1138261136 12.199 100 26 52 0 1274979104 131298432 5419536
06:11:09 59665 8192000000 10097596528 10573983744 1124444032 12.069 99 15 29 2 1227612144 99881360 3286752
06:13:10 60469 8192000000 10084067408 10578657280 1139357952 12.209 100 25 51 0 1216252384 71132224 5762208
06:15:11 33351 8192000000 10074806528 10523820032 1145420160 12.266 100 37 74 0 1174568416 20642096 8506160
06:16:45 79266 8192000000 10113985520 10588413952 1105749184 11.892 97 16 32 0 1199141552 89325728 4066640
06:19:52 91843 8192000000 10051663120 10481381376 1110957648 11.941 98 39 77 0 1125658784 6605328 8095808
06:23:21 64237 8192000000 10059383472 10511413248 1106919360 11.903 97 32 64 1 1146121216 31745008 7456848
06:25:43 18257 8192000000 10090481024 10522030080 1143429360 12.248 100 40 76 1 1190109568 38378240 8301968
06:27:03 25474 8192000000 10124966544 10566029312 1137795728 12.195 99 27 54 0 1223991744 80272336 5923680
06:28:42 124689 8192000000 10059495024 10568450048 1132249264 12.143 99 23 46 1 1268022352 130483744 5289344
06:32:10 72450 8192000000 10068834208 10527502336 1134861008 12.167 100 10 20 1 1164063456 26734288 2468160
06:35:09 33794 8192000000 10071108400 10518118400 1145550688 12.268 100 31 61 0 1180387632 28305008 6531936
06:36:59 103551 8192000000 10082367312 10535038976 1087793136 11.722 94 18 36 0 1122929920 31866048 3270736
06:39:49 65283 8192000000 10067327872 10525859840 1085652592 11.701 95 13 26 0 1159166928 70668288 2846048
06:42:06 66035 8192000000 10086983136 10519142400 1081954560 11.666 95 22 44 0 1114213856 27996512 4262784
06:44:17 99774 8192000000 10083737680 10516307968 1089850784 11.741 96 14 28 1 1148157504 55174704 3132016
06:46:58 189282 8192000000 10083301232 10567413760 1089495136 11.738 96 18 36 0 1142236784 48985312 3756336
06:51:07 54644 8192000000 10078178352 10541633536 1121340128 12.040 99 12 24 1 1183406832 59615296 2451408
06:53:02 72290 8192000000 10065073744 10515132416 1078294080 11.631 96 32 64 0 1164678960 79076496 7308384
06:55:24 39125 8192000000 10046577712 10520436736 1078245280 11.631 96 16 32 0 1133573184 51736480 3591424
06:57:06 39404 8192000000 10058154000 10540670976 1116519824 11.994 100 8 16 1 1168576880 49918624 2138432
06:58:46 55762 8192000000 10061629552 10573045760 1121941584 12.045 100 12 24 1 1178711088 54243296 2526208
07:01:25 42952 8192000000 10059857552 10544513024 1100783680 11.845 98 11 22 0 1173005968 69851648 2370640
07:03:11 50192 8192000000 10100718944 10544984064 1106714032 11.901 99 41 81 0 1186765184 71663664 8387488
07:05:06 39690 8192000000 10076263824 10529275904 1117099952 12.000 99 11 22 1 1154032304 34188464 2743888
07:06:46 45678 8192000000 10115448496 10580516864 1134419248 12.163 100 27 54 2 1216938240 76732768 5786224
07:08:36 81319 8192000000 10067623296 10531758080 1097461792 11.814 96 19 37 1 1131520304 30617840 3440672
07:11:03 33540 8192000000 10102954976 10534129664 1146709424 12.279 100 29 58 0 1200522528 48040560 5772544
07:12:38 65021 8192000000 10071762160 10528600064 1091108448 11.753 95 11 22 0 1130165824 37041424 2015952
07:14:53 109637 8192000000 10114655120 10552074240 1141401344 12.229 100 32 64 2 1189230016 41080304 6748368
07:17:43 75336 8192000000 10077030464 10543161344 1142474080 12.239 100 8 16 1 1182840368 38801616 1564672
07:20:16 35736 8192000000 10083042144 10562813952 1147604048 12.287 100 14 28 3 1192373168 41922768 2846352
07:22:02 59900 8192000000 10099627968 10573033472 1131976464 12.140 98 8 16 0 1199148384 65362016 1809904
07:24:02 68232 8192000000 10113141616 10558648320 1116540528 11.994 97 16 32 1 1212636400 92116720 3979152
07:26:13 69684 8192000000 10120867840 10558857216 1109472144 11.927 97 20 40 0 1206707312 92767680 4467488
07:29:04 58359 8192000000 10102809920 10542632960 1106110656 11.896 98 28 54 1 1171706464 60318560 5277248
07:31:02 48515 8192000000 10043671664 10488524800 1102352288 11.860 99 18 36 2 1136635696 30067008 4216400
07:32:52 84268 8192000000 10057720560 10523869184 1079583536 11.644 96 25 50 2 1149644832 64844896 5216400
07:35:18 108736 8192000000 9998504304 10439434240 1027232928 11.142 91 21 42 0 1068333056 36989952 4110176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-09-2021 07:39:13 to 13-09-2021 07:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 13-09-2021 07:39:29 to 13-09-2021 07:39:31.

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          226825
MON$OLDEST_ACTIVE               228370
MON$OLDEST_SNAPSHOT             228370
MON$NEXT_TRANSACTION            228370
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                       2064352
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2588 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2588 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2588 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 ---
:78: 
:79: mscs-test-161.budzdorov.ru    Mon Sep 13 07:37:48 2021
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:81: 
--- end of diff output ---

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

13.09.21 07:39:32. 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-13 07:39:34 No severe PSQL-related problems occured
13.09.21 07:39:35. End of report.