Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.01.2021 13:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16399004 kB
MemAvailable 31268136 kB
Buffers 0 kB
Cached 15205216 kB
SwapCached 4864 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.0
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-01-17 13:26:59
DTS_END                         2021-01-17 13:26:59.0040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42278
TRN_ID                          235069
ATT_ID                          640
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: 9311.30 22916 1117356
customer order: creation 165.98 414 19918
customer order: refuse 14.03 344 1683
order to supplier: creation 45.07 1362 5408
order to supplier: removal 3.38 119794 405
invoice (draft): creation 43.65 3238 5238
invoice (draft): removal 53.47 980 6416
invoice accept: apply 41.23 17384 4947
invoice accept: cancel 49.20 902 5904
customer reserve: creation 3807.38 169 456886
customer reserve: removal 4789.68 66 574761
realization accept: apply 81.83 218 9819
realization accept: cancel 91.78 121 11013
payment from customer: creation 56.83 166 6820
payment from customer: removal 5.49 127 659
payment to supplier: creation 48.12 175 5774
payment to supplier: removal 3.23 92 387
service: total inventory turnovers 4.31 3019 517
service: total monetary turnovers 5.76 462 691
service: aggregate perf. data 0.87 13420 104
service: refresh index statistics 0.05 295871 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-01-2021 13:28:42 to 17-01-2021 13:28:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3859
TEST_TIME 2 2573
TEST_TIME 3 2610
TEST_TIME 4 2422
TEST_TIME 5 2666
TEST_TIME 6 3210
TEST_TIME 7 3677
TEST_TIME 8 3970
TEST_TIME 9 4440
TEST_TIME 10 4931
TEST_TIME 11 5491
TEST_TIME 12 5654
TEST_TIME 13 6033
TEST_TIME 14 6494
TEST_TIME 15 7029
TEST_TIME 16 7305
TEST_TIME 17 7797
TEST_TIME 18 7757
TEST_TIME 19 7866
TEST_TIME 20 8342
TEST_TIME 21 8595
TEST_TIME 22 8943
TEST_TIME 23 8993
TEST_TIME 24 9901
TEST_TIME 25 9970
TEST_TIME 26 10373
TEST_TIME 27 11300
TEST_TIME 28 11396
TEST_TIME 29 11184
TEST_TIME 30 11028
TEST_TIME 31 11003
TEST_TIME 32 10952
TEST_TIME 33 11209
TEST_TIME 34 11044
TEST_TIME 35 10820
TEST_TIME 36 10636
TEST_TIME 37 10638
TEST_TIME 38 10603
TEST_TIME 39 10544
TEST_TIME 40 10494
TEST_TIME 41 10469
TEST_TIME 42 10399
TEST_TIME 43 10290
TEST_TIME 44 10228
TEST_TIME 45 10260
TEST_TIME 46 10157
TEST_TIME 47 10158
TEST_TIME 48 10138
TEST_TIME 49 10205
TEST_TIME 50 10184
TEST_TIME 51 10131
TEST_TIME 52 10200
TEST_TIME 53 10177
TEST_TIME 54 10202
TEST_TIME 55 10134
TEST_TIME 56 10267
TEST_TIME 57 10203
TEST_TIME 58 10187
TEST_TIME 59 10046
TEST_TIME 60 9966
TEST_TIME 61 9965
TEST_TIME 62 9933
TEST_TIME 63 9883
TEST_TIME 64 9820
TEST_TIME 65 9751
TEST_TIME 66 9667
TEST_TIME 67 9590
TEST_TIME 68 9656
TEST_TIME 69 9647
TEST_TIME 70 9608
TEST_TIME 71 9643
TEST_TIME 72 9602
TEST_TIME 73 9685
TEST_TIME 74 9675
TEST_TIME 75 9641
TEST_TIME 76 9718
TEST_TIME 77 9780
TEST_TIME 78 9788
TEST_TIME 79 9757
TEST_TIME 80 9699
TEST_TIME 81 9610
TEST_TIME 82 9552
TEST_TIME 83 9550
TEST_TIME 84 9502
TEST_TIME 85 9460
TEST_TIME 86 9474
TEST_TIME 87 9432
TEST_TIME 88 9442
TEST_TIME 89 9452
TEST_TIME 90 9458
TEST_TIME 91 9421
TEST_TIME 92 9484
TEST_TIME 93 9485
TEST_TIME 94 9527
TEST_TIME 95 9602
TEST_TIME 96 9619
TEST_TIME 97 9621
TEST_TIME 98 9533
TEST_TIME 99 9501
TEST_TIME 100 9471
TEST_TIME 101 9434
TEST_TIME 102 9441
TEST_TIME 103 9424
TEST_TIME 104 9397
TEST_TIME 105 9362
TEST_TIME 106 9314
TEST_TIME 107 9392
TEST_TIME 108 9491
TEST_TIME 109 9457
TEST_TIME 110 9453
TEST_TIME 111 9462
TEST_TIME 112 9503
TEST_TIME 113 9485
TEST_TIME 114 9518
TEST_TIME 115 9489
TEST_TIME 116 9424
TEST_TIME 117 9368
TEST_TIME 118 9368
TEST_TIME 119 9314
TEST_TIME 120 9314

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-01-2021 13:28:46 to 17-01-2021 13:28:49.

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 1540485 1540485 0 0.00 0 79848 7 0 0
doc_list_biud 1540485 1540485 0 0.00 0 87181 31 0 0
sp_add_invoice_to_stock 5091 4947 144 2.82 36 185404 17384 0 144
sp_cancel_adding_invoice 5904 5904 0 0.00 5 78928 902 0 0
sp_cancel_client_order 1683 1683 0 0.00 5 13556 344 0 0
sp_cancel_customer_reserve 574761 574761 0 0.00 1 61757 66 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 16152 127 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 9216 92 0 0
sp_cancel_supplier_invoice 6429 6416 13 0.20 2 87187 980 0 13
sp_cancel_supplier_order 405 405 0 0.00 5 1172905 119795 0 0
sp_cancel_write_off 11013 11013 0 0.00 1 20621 121 0 0
sp_client_order 19918 19918 0 0.00 4 30198 414 0 0
sp_customer_reserve 456886 456886 0 0.00 0 56497 170 0 0
sp_fill_shopping_cart 30557 30557 0 0.00 1 29938 45 0 0
sp_fill_shopping_cart_clo_res 456882 456882 0 0.00 0 28229 19 0 0
sp_get_clo_for_invoice 4940 4940 0 0.00 0 30821 356 0 0
sp_kill_cost_storno 17963 17963 0 0.00 0 24771 60 0 0
sp_kill_qstorno_ret_qs2qd 1167356 1167356 0 0.00 0 87180 36 0 0
sp_kill_qty_storno 614941 614941 0 0.00 0 87181 78 0 0
sp_lock_selected_doc 20373 20373 0 0.00 0 12013 8 0 0
sp_make_cost_storno 27353 27353 0 0.00 0 59650 89 0 0
sp_multiply_rows_for_pdistr 27353 27353 0 0.00 0 20232 42 0 0
sp_multiply_rows_for_qdistr 19918 19918 0 0.00 0 29959 233 0 0
sp_pay_from_customer 6820 6820 0 0.00 4 24614 166 0 0
sp_pay_to_supplier 5774 5774 0 0.00 5 28470 175 0 0
sp_payment_common 12594 12594 0 0.00 1 24584 29 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 13549 313 0 0
sp_qd_handle_on_invoice_upd_sts 10844 10844 0 0.00 0 38305 396 0 0
sp_qd_handle_on_reserve_upd_sts 9819 9819 0 0.00 0 16788 49 0 0
sp_reserve_write_off 9819 9819 0 0.00 7 29129 219 0 0
sp_supplier_invoice 5238 5238 0 0.00 5 119884 3239 0 0
sp_supplier_order 5408 5408 0 0.00 6 90431 1363 0 0
srv_aggregate_perf_data 104 104 0 0.00 2809 117637 13421 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1851 183395 47809 0 0
srv_find_qd_qs_mism 1070897 1070897 0 0.00 0 19953 5 0 0
srv_make_invnt_saldo 517 517 0 0.00 40 80278 3019 0 0
srv_make_money_saldo 691 691 0 0.00 1 13378 463 0 0
srv_recalc_idx_stat 6 6 0 0.00 203248 350611 295871 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4947 4947 0 0.00 0 12763 178 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 8880 117 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 2233 11 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 5209 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 2285 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 2156 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 741 14 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 25835 1706 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 4791 12 0 0
t$perf-norm:sp_client_order 19918 19918 0 0.00 0 10095 16 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 4720 8 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 5589 8 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 13156 12 0 0
t$perf-norm:sp_reserve_write_off 9819 9819 0 0.00 0 11533 11 0 0
t$perf-norm:sp_supplier_invoice 5238 5238 0 0.00 0 8261 22 0 0
t$perf-norm:sp_supplier_order 5408 5408 0 0.00 0 6334 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 525 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 151 3 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 1465 11 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 1850 11 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 9 3 0 0
v_add_invoice_to_stock 10166 9878 288 2.83 0 8435 19 0 288
v_all_customers 36124 36124 0 0.00 0 59 1 0 0
v_all_suppliers 29738 29738 0 0.00 0 31 1 0 0
v_all_wares 39834 39834 0 0.00 0 33 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 4263 13 0 0
v_cancel_client_order 3366 3366 0 0.00 0 5809 8 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 5252 10 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 4106 8 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 5453 10 0 0
v_cancel_supplier_invoice 806 780 26 3.22 0 2401 18 0 26
v_cancel_supplier_order 810 810 0 0.00 0 1632 7 0 0
v_cancel_write_off 1986 1986 0 0.00 0 4587 19 0 0
v_max_id_clo_ord 5407 5407 0 0.00 0 619 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 4079 18 0 0
v_max_id_ord_sup 5233 5233 0 0.00 0 9937 10 0 0
v_max_non_paid_invoice 1545 1545 0 0.00 0 492 3 0 0
v_max_non_paid_realizn 3652 3652 0 0.00 0 3256 4 0 0
v_min_id_clo_ord 5407 5407 0 0.00 0 29630 26 0 0
v_min_id_clo_res 983 983 0 0.00 0 2636 17 0 0
v_min_id_ord_sup 5233 5233 0 0.00 0 5307 7 0 0
v_min_non_paid_invoice 5774 5774 0 0.00 0 17526 18 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 24581 17 0 0
v_our_firm 10046 10046 0 0.00 0 20 1 0 0
v_reserve_write_off 19638 19638 0 0.00 0 19323 9 0 0
x_lock_depdocs_on_canc_invoice 11930 11930 0 0.00 0 18112 109 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 13680 429 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 2 90424 1268 0 0
x_make_qstorno_1200_2000 5238 5238 0 0.00 2 114878 3080 0 0
x_make_qstorno_2100_3300 456886 456886 0 0.00 0 56495 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-01-2021 13:28:50 to 17-01-2021 13:28:54.

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
10:57:02 5 8192000000 8798473952 8809861120 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:03 25331 8192000000 9714762720 10007277568 794257888 8.838 100 86 172 0 823016528 7654208 21104432
10:59:32 21687 8192000000 9757059264 10062295040 831722928 9.217 100 88 176 0 864716640 11721552 21272160
11:00:58 21000 8192000000 9795765136 10111250432 867770064 9.578 100 85 170 1 907221824 19939520 19512240
11:02:24 14042 8192000000 9872649840 10202001408 914178144 10.039 100 81 162 1 981560416 49299232 18083040
11:03:43 13002 8192000000 9912555152 10258423808 959928592 10.488 100 82 162 2 1032427744 55533232 16965920
11:05:00 4931 8192000000 9955367888 10309419008 992599808 10.807 100 80 161 0 1067068720 58502752 15966160
11:06:07 2987 8192000000 9992849120 10356625408 1020739376 11.079 100 88 179 0 1107989408 69359568 17890464
11:07:12 1844 8192000000 10010936144 10379939840 1045089696 11.314 100 88 176 0 1123156720 60322704 17744320
11:08:15 22635 8192000000 10033246448 10419064832 1059881536 11.455 100 64 130 0 1145025264 71879840 13263888
11:09:38 1667 8192000000 10014961264 10408091648 1067249168 11.526 100 41 82 1 1125245264 50053440 7942656
11:10:40 5475 8192000000 9986131456 10379354112 1076043376 11.610 100 37 74 1 1097364240 12797904 8522960
11:11:47 54186 8192000000 9989871456 10398281728 1082480640 11.671 100 43 86 1 1126827520 35207600 9139280
11:13:43 4551 8192000000 10013203776 10408071168 1086718176 11.711 100 33 62 0 1118551936 24969248 6864512
11:14:47 8692 8192000000 10007556256 10414133248 1094981184 11.790 100 34 68 0 1120893008 18710944 7200880
11:15:57 6690 8192000000 9995944896 10404589568 1079486256 11.643 99 20 40 2 1112683744 28880992 4316496
11:17:07 67467 8192000000 9997712720 10402824192 1061554224 11.471 98 45 88 1 1092770928 21426992 9789712
11:19:16 4222 8192000000 9948855392 10329309184 1007510016 10.951 96 62 127 0 1031944096 10953184 13480896
11:20:22 5390 8192000000 9937031072 10318548992 1015518512 11.029 99 40 80 1 1047074672 22543328 9012832
11:21:30 8133 8192000000 9931802608 10315943936 1005720672 10.934 98 29 59 2 1035086320 23059984 6305664
11:22:41 38437 8192000000 9949642672 10335707136 1021677232 11.088 99 44 87 0 1050664752 17965824 11021696
11:24:21 6731 8192000000 9958169152 10346541056 1044021792 11.303 100 42 84 3 1068043872 14140336 9881744
11:25:32 10513 8192000000 9986296656 10382458880 1058971888 11.447 100 33 67 1 1090215664 23784336 7459440
11:26:46 8440 8192000000 9983220448 10381516800 1069928080 11.551 100 42 84 1 1088116672 9249808 8938784
11:28:04 117210 8192000000 10074164352 10483470336 1109211456 11.925 100 46 92 1 1166612240 47648768 9752016
11:31:04 47967 8192000000 10132274768 10539577344 1131765936 12.138 100 34 67 1 1252764240 113877760 7120544
11:32:54 26128 8192000000 10170123568 10580488192 1127437904 12.097 99 43 86 0 1268856912 132190512 9228496
11:34:35 4592 8192000000 10235829168 10633555968 1154111888 12.348 100 74 148 1 1341349312 170448896 16788528
11:35:44 6361 8192000000 10277783968 10691571712 1170195664 12.499 100 62 122 1 1385075952 201393264 13487024
11:36:55 9585 8192000000 10303064960 10713116672 1172494336 12.520 100 64 127 0 1408052864 221367520 14191008
11:38:08 2849 8192000000 10289249632 10687795200 1153094592 12.339 99 65 129 1 1389441968 221897360 14450016
11:39:13 6698 8192000000 10348373392 10755424256 1163116032 12.432 100 75 151 1 1454898048 274551424 17230592
11:40:25 2383 8192000000 10377693920 10780504064 1167535840 12.474 100 90 180 1 1474724496 287370432 19818224
11:41:36 4411 8192000000 10348678256 10765131776 1158936432 12.393 100 68 136 1 1443220304 269123072 15160800
11:42:44 1850 8192000000 10373581136 10771087360 1157644224 12.381 100 87 174 1 1473581632 295317168 20620240
11:43:48 5625 8192000000 10346170880 10754080768 1148599760 12.296 99 51 98 0 1441331840 280281840 12450240
11:44:59 6055 8192000000 10443459792 10852548608 1155125888 12.358 100 56 112 0 1534524528 364465216 14933424
11:46:10 6754 8192000000 10430693472 10864726016 1153651040 12.344 100 47 94 0 1551635600 386146576 11837984
11:47:20 11052 8192000000 10431119808 10851491840 1145942256 12.271 100 46 92 1 1525511472 367505984 12063232
11:48:39 22386 8192000000 10391322176 10819207168 1122600704 12.052 99 29 58 1 1470671744 340433504 7637536
11:50:02 13384 8192000000 10409293584 10854100992 1125867136 12.082 100 30 60 0 1535510384 401607584 8035664
11:51:17 15195 8192000000 10321457616 10772770816 1127192752 12.095 100 26 51 0 1417471744 283614928 6664064
11:52:45 38337 8192000000 10086421920 10618413056 1095353936 11.794 98 8 16 0 1261546624 163856400 2336288
11:54:30 67377 8192000000 10047635424 10466807808 1113358416 11.964 99 33 68 0 1139804560 19237536 7208608
11:56:40 11322 8192000000 10071026816 10506571776 1130951216 12.130 100 58 117 3 1156075904 12077136 13047552
11:57:56 51105 8192000000 10119160592 10591358976 1132198144 12.142 100 13 26 1 1220747552 85462256 3087152
11:59:55 76774 8192000000 10082041184 10541756416 1112697664 11.958 98 10 20 0 1161079104 45859856 2521584
12:02:33 81239 8192000000 10063612048 10517458944 1110694848 11.939 98 38 77 1 1193853184 74970096 8188240
12:04:58 24442 8192000000 10069357952 10538418176 1128507488 12.107 100 10 20 2 1187625216 57196752 1920976
12:06:28 57947 8192000000 10072773712 10508709888 1115123712 11.981 98 33 66 0 1193872304 72449984 6298608
12:08:35 30448 8192000000 10092566512 10533367808 1139356944 12.209 100 17 34 0 1198652720 55533136 3762640
12:10:14 28657 8192000000 10142558512 10579935232 1148623632 12.297 100 24 48 0 1226150592 72100176 5426784
12:11:51 69528 8192000000 10129016240 10610429952 1144083456 12.254 99 19 38 1 1239162160 90815280 4263424
12:14:39 39630 8192000000 10102281984 10545086464 1120071856 12.028 98 42 84 0 1176781440 47681088 9028496
12:16:20 46570 8192000000 10086147680 10519580672 1150418192 12.313 100 37 73 0 1175827120 17848912 7560016
12:18:14 43303 8192000000 10090117872 10552750080 1136176656 12.180 99 14 28 2 1165924144 27173264 2574224
12:19:58 84247 8192000000 10093120192 10533113856 1114693040 11.977 97 19 38 1 1172698400 54067152 3938208
12:22:26 74885 8192000000 10063093088 10498703360 1070749360 11.559 94 11 22 0 1116616224 43750960 2115904
12:24:43 93566 8192000000 10152138272 10605821952 1135687088 12.175 100 29 58 0 1248470720 106040400 6743232
12:28:46 60383 8192000000 10038016688 10491375616 1090656928 11.749 97 11 22 0 1116327040 23450992 2219120
12:30:49 59988 8192000000 10037737712 10492391424 1084209376 11.688 96 10 20 0 1112728176 26169008 2349792
12:32:50 67018 8192000000 10103349200 10547515392 1101873872 11.855 98 11 22 1 1166290432 62014112 2402448
12:35:09 48381 8192000000 10063455120 10498715648 1097191568 11.811 97 20 38 1 1143193248 42179920 3821760
12:36:58 34941 8192000000 10064449696 10500980736 1119257088 12.020 100 35 70 3 1190410368 63776624 7376656
12:38:33 36904 8192000000 10118633760 10569416704 1110812528 11.940 98 47 93 1 1185668320 65412784 9443008
12:40:10 47569 8192000000 10094172448 10540994560 1088584048 11.729 97 27 54 1 1157117184 62979104 5554032
12:41:58 43621 8192000000 10115361056 10601480192 1115757840 11.987 99 24 48 1 1234355872 113199536 5398496
12:44:10 76436 8192000000 10067477760 10515570688 1119414096 12.021 99 27 54 0 1167705552 42696736 5594720
12:46:27 183395 8192000000 10073875040 10547687424 1127776896 12.100 100 37 74 0 1194184992 58829488 7578608
12:50:32 42321 8192000000 10103838704 10579275776 1136323008 12.181 100 4 8 0 1188383744 51196896 863840
12:53:04 73246 8192000000 10108423264 10563817472 1102649088 11.863 97 25 50 0 1172368000 64345344 5373568
12:55:22 60202 8192000000 10097214864 10557837312 1110927952 11.941 98 27 54 2 1178600320 62091536 5580832
12:57:32 50805 8192000000 10119724672 10581737472 1122233312 12.048 99 27 53 1 1213217280 84402864 6581104
12:59:32 48884 8192000000 10116717632 10589614080 1136305808 12.181 100 13 26 0 1225041456 85932192 2803456
13:01:25 63667 8192000000 10147454976 10625449984 1130850672 12.129 99 7 14 0 1205981376 73669632 1461072
13:03:48 113007 8192000000 10082123408 10554200064 1133369968 12.153 99 16 32 0 1191575920 55579056 2626896
13:06:49 83192 8192000000 10081523216 10533265408 1100125520 11.839 96 18 36 1 1132007680 28493504 3388656
13:09:19 46285 8192000000 10065776096 10503876608 1108312576 11.916 97 11 22 0 1138235696 27925536 1997584
13:11:05 24189 8192000000 10115127952 10546323456 1133966848 12.159 99 18 36 0 1183912944 46267728 3678368
13:12:36 102653 8192000000 10148254944 10656870400 1147244928 12.284 99 29 58 0 1308795776 155250096 6300752
13:15:27 69848 8192000000 10106299344 10544181248 1112812608 11.959 97 16 32 0 1172372384 56451712 3108064
13:17:40 36523 8192000000 10135310512 10568761344 1145073584 12.263 100 27 54 2 1222673248 71881856 5717808
13:19:28 56017 8192000000 10125120096 10553712640 1104247264 11.878 97 25 50 0 1177411984 67873536 5291184
13:21:25 19678 8192000000 10102599488 10520834048 1119637792 12.024 98 27 54 0 1160592768 35192064 5762912
13:23:02 141339 8192000000 10062905536 10532593664 1104749680 11.883 98 17 34 1 1168221728 59231584 4240464
13:26:24 43579 8192000000 9610363776 10010595328 723644144 8.116 64 5 10 0 749562864 24963120 955600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-01-2021 13:28:56 to 17-01-2021 13:29:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-01-2021 13:29:12 to 17-01-2021 13:29:14.

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          234967
MON$OLDEST_ACTIVE               235256
MON$OLDEST_SNAPSHOT             235256
MON$NEXT_TRANSACTION            235256
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                       2122368
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             798
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2325 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:201: 
:202: mscs-test-161.budzdorov.ru    Sun Jan 17 13:27:12 2021
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Sun Jan 17 13:27:18 2021
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Sun Jan 17 13:27:18 2021
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Sun Jan 17 13:27:18 2021
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Sun Jan 17 13:27:18 2021
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:220: 
--- end of diff output ---

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

17.01.21 13:29:15. 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-01-17 13:29:17 No severe PSQL-related problems occured
17.01.21 13:29:19. End of report.