Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2020 04:27
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 16620020 kB
MemAvailable 31334764 kB
Buffers 20 kB
Cached 14868708 kB
SwapCached 3696 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 2020-12-20 04:26:55
DTS_END                         2020-12-20 04:26:55.0380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53874
TRN_ID                          235170
ATT_ID                          620
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: 9248.88 22146 1109865
customer order: creation 172.94 448 20753
customer order: refuse 14.42 326 1730
order to supplier: creation 46.36 1383 5563
order to supplier: removal 3.43 117704 411
invoice (draft): creation 45.78 3433 5494
invoice (draft): removal 53.04 854 6365
invoice accept: apply 41.12 17298 4934
invoice accept: cancel 48.08 861 5769
customer reserve: creation 3845.97 166 461516
customer reserve: removal 4674.91 70 560989
realization accept: apply 84.92 177 10190
realization accept: cancel 88.77 114 10652
payment from customer: creation 59.23 107 7108
payment from customer: removal 6.33 82 759
payment to supplier: creation 48.97 120 5876
payment to supplier: removal 3.72 79 446
service: total inventory turnovers 3.66 3225 439
service: total monetary turnovers 6.44 519 773
service: aggregate perf. data 0.78 19926 94
service: refresh index statistics 0.03 276020 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2020 04:27:57 to 20-12-2020 04:27:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2627
TEST_TIME 2 4034
TEST_TIME 3 5587
TEST_TIME 4 5786
TEST_TIME 5 6024
TEST_TIME 6 6070
TEST_TIME 7 6363
TEST_TIME 8 6600
TEST_TIME 9 7163
TEST_TIME 10 7055
TEST_TIME 11 7491
TEST_TIME 12 7338
TEST_TIME 13 7376
TEST_TIME 14 7390
TEST_TIME 15 7560
TEST_TIME 16 7670
TEST_TIME 17 7931
TEST_TIME 18 8804
TEST_TIME 19 8800
TEST_TIME 20 8860
TEST_TIME 21 9152
TEST_TIME 22 9117
TEST_TIME 23 9569
TEST_TIME 24 9636
TEST_TIME 25 9674
TEST_TIME 26 9821
TEST_TIME 27 11773
TEST_TIME 28 11795
TEST_TIME 29 11592
TEST_TIME 30 11340
TEST_TIME 31 11546
TEST_TIME 32 11421
TEST_TIME 33 11569
TEST_TIME 34 11545
TEST_TIME 35 11312
TEST_TIME 36 11210
TEST_TIME 37 10944
TEST_TIME 38 10847
TEST_TIME 39 10707
TEST_TIME 40 10579
TEST_TIME 41 10436
TEST_TIME 42 10437
TEST_TIME 43 10422
TEST_TIME 44 10298
TEST_TIME 45 10381
TEST_TIME 46 10252
TEST_TIME 47 10271
TEST_TIME 48 10316
TEST_TIME 49 10237
TEST_TIME 50 10208
TEST_TIME 51 10180
TEST_TIME 52 10152
TEST_TIME 53 10316
TEST_TIME 54 10449
TEST_TIME 55 10411
TEST_TIME 56 10414
TEST_TIME 57 10375
TEST_TIME 58 10484
TEST_TIME 59 10397
TEST_TIME 60 10259
TEST_TIME 61 10160
TEST_TIME 62 10093
TEST_TIME 63 9997
TEST_TIME 64 9932
TEST_TIME 65 9856
TEST_TIME 66 9824
TEST_TIME 67 9864
TEST_TIME 68 9831
TEST_TIME 69 9786
TEST_TIME 70 9800
TEST_TIME 71 9815
TEST_TIME 72 9805
TEST_TIME 73 9861
TEST_TIME 74 9898
TEST_TIME 75 9836
TEST_TIME 76 9894
TEST_TIME 77 9904
TEST_TIME 78 9911
TEST_TIME 79 9824
TEST_TIME 80 9745
TEST_TIME 81 9676
TEST_TIME 82 9643
TEST_TIME 83 9607
TEST_TIME 84 9552
TEST_TIME 85 9513
TEST_TIME 86 9528
TEST_TIME 87 9537
TEST_TIME 88 9505
TEST_TIME 89 9495
TEST_TIME 90 9485
TEST_TIME 91 9507
TEST_TIME 92 9489
TEST_TIME 93 9571
TEST_TIME 94 9576
TEST_TIME 95 9561
TEST_TIME 96 9516
TEST_TIME 97 9449
TEST_TIME 98 9404
TEST_TIME 99 9409
TEST_TIME 100 9386
TEST_TIME 101 9386
TEST_TIME 102 9352
TEST_TIME 103 9336
TEST_TIME 104 9336
TEST_TIME 105 9299
TEST_TIME 106 9307
TEST_TIME 107 9264
TEST_TIME 108 9348
TEST_TIME 109 9401
TEST_TIME 110 9412
TEST_TIME 111 9379
TEST_TIME 112 9330
TEST_TIME 113 9289
TEST_TIME 114 9263
TEST_TIME 115 9242
TEST_TIME 116 9241
TEST_TIME 117 9217
TEST_TIME 118 9202
TEST_TIME 119 9173
TEST_TIME 120 9215
TEST_TIME 121 9175

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2020 04:28:00 to 20-12-2020 04:28:04.

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 1535884 1535884 0 0.00 0 27926 6 0 0
doc_list_biud 1535884 1535884 0 0.00 0 70269 31 0 0
sp_add_invoice_to_stock 5044 4934 110 2.18 39 230697 17299 0 110
sp_cancel_adding_invoice 5769 5769 0 0.00 5 83165 861 0 0
sp_cancel_client_order 1730 1730 0 0.00 5 10490 327 0 0
sp_cancel_customer_reserve 560989 560989 0 0.00 1 70272 70 0 0
sp_cancel_pay_from_customer 759 759 0 0.00 2 10390 83 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 8577 79 0 0
sp_cancel_supplier_invoice 6371 6365 6 0.09 1 24764 854 0 6
sp_cancel_supplier_order 411 411 0 0.00 5 973740 117705 0 0
sp_cancel_write_off 10652 10652 0 0.00 1 11788 114 0 0
sp_client_order 20753 20753 0 0.00 4 90701 448 0 0
sp_customer_reserve 461516 461516 0 0.00 0 218524 167 0 0
sp_fill_shopping_cart 31804 31804 0 0.00 1 25622 44 0 0
sp_fill_shopping_cart_clo_res 461512 461512 0 0.00 0 40331 20 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 59693 424 0 0
sp_kill_cost_storno 17626 17626 0 0.00 0 7079 57 0 0
sp_kill_qstorno_ret_qs2qd 1139406 1139406 0 0.00 0 70261 37 0 0
sp_kill_qty_storno 601033 601033 0 0.00 0 70269 79 0 0
sp_lock_selected_doc 20988 20988 0 0.00 0 9016 9 0 0
sp_make_cost_storno 28101 28101 0 0.00 0 14881 55 0 0
sp_multiply_rows_for_pdistr 28101 28101 0 0.00 0 8870 30 0 0
sp_multiply_rows_for_qdistr 20753 20753 0 0.00 0 90511 279 0 0
sp_pay_from_customer 7108 7108 0 0.00 4 19091 107 0 0
sp_pay_to_supplier 5876 5876 0 0.00 5 12638 120 0 0
sp_payment_common 12984 12984 0 0.00 1 8709 22 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 10447 297 0 0
sp_qd_handle_on_invoice_upd_sts 10696 10696 0 0.00 0 16315 342 0 0
sp_qd_handle_on_reserve_upd_sts 10190 10190 0 0.00 0 22309 51 0 0
sp_reserve_write_off 10190 10190 0 0.00 6 22364 177 0 0
sp_supplier_invoice 5494 5494 0 0.00 6 126567 3433 0 0
sp_supplier_order 5563 5563 0 0.00 6 98758 1384 0 0
srv_aggregate_perf_data 94 94 0 0.00 2751 422097 19926 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1418 189498 45158 0 0
srv_find_qd_qs_mism 1061368 1061368 0 0.00 0 27853 5 0 0
srv_make_invnt_saldo 439 439 0 0.00 58 123914 3225 0 0
srv_make_money_saldo 773 773 0 0.00 0 13845 520 0 0
srv_recalc_idx_stat 4 4 0 0.00 121723 459798 276021 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4935 4935 0 0.00 0 9101 174 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 6281 97 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 2943 10 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 733 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 759 759 0 0.00 0 846 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 2615 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 778 9 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 17650 1420 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1633 6 0 0
t$perf-norm:sp_client_order 20753 20753 0 0.00 0 5622 17 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 3224 9 0 0
t$perf-norm:sp_pay_from_customer 7108 7108 0 0.00 0 7160 14 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 2852 7 0 0
t$perf-norm:sp_reserve_write_off 10190 10190 0 0.00 0 5057 10 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 5814 18 0 0
t$perf-norm:sp_supplier_order 5563 5563 0 0.00 0 4711 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 548 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1112 31 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 2961 19 0 0
t$perf-norm:srv_make_money_saldo 773 773 0 0.00 0 1915 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10072 9852 220 2.18 0 7695 18 0 220
v_all_customers 37384 37384 0 0.00 0 18 1 0 0
v_all_suppliers 30589 30589 0 0.00 0 102 1 0 0
v_all_wares 41505 41505 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 338 6 0 0
v_cancel_client_order 3460 3460 0 0.00 0 2147 4 0 0
v_cancel_customer_prepayment 1518 1518 0 0.00 0 3535 7 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 2042 5 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 1380 6 0 0
v_cancel_supplier_invoice 774 762 12 1.55 0 3623 19 0 12
v_cancel_supplier_order 822 822 0 0.00 0 435 4 0 0
v_cancel_write_off 2000 2000 0 0.00 0 2204 13 0 0
v_max_id_clo_ord 5560 5560 0 0.00 0 713 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2718 13 0 0
v_max_id_ord_sup 5489 5489 0 0.00 0 5873 6 0 0
v_max_non_paid_invoice 1630 1630 0 0.00 0 1560 4 0 0
v_max_non_paid_realizn 4006 4006 0 0.00 0 2084 3 0 0
v_min_id_clo_ord 5560 5560 0 0.00 0 4258 6 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 5050 8 0 0
v_min_id_ord_sup 5489 5489 0 0.00 0 8435 9 0 0
v_min_non_paid_invoice 5875 5875 0 0.00 0 6706 15 0 0
v_min_non_paid_realizn 7108 7108 0 0.00 0 6513 11 0 0
v_our_firm 10322 10322 0 0.00 0 14 1 0 0
v_reserve_write_off 20380 20380 0 0.00 0 5387 6 0 0
x_lock_depdocs_on_canc_invoice 11753 11753 0 0.00 0 30598 108 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 9415 306 0 0
x_make_qstorno_1000_1200 5563 5563 0 0.00 1 98698 1312 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 3 123423 3260 0 0
x_make_qstorno_2100_3300 461516 461516 0 0.00 0 218523 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2020 04:28:05 to 20-12-2020 04:28:09.

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
01:56:58 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
01:58:00 20066 8192000000 9709588432 10004529152 792932912 8.825 100 85 170 0 821681248 7764256 20984080
01:59:27 15677 8192000000 9761117024 10065076224 837599200 9.276 100 93 186 0 871544224 11968320 21976704
02:00:49 17087 8192000000 9798182416 10116874240 866526272 9.565 100 81 160 3 908845760 24130176 18189312
02:02:09 14522 8192000000 9867996560 10201575424 919794144 10.094 100 81 162 1 976968416 39104432 18069840
02:03:26 7785 8192000000 9935417536 10280128512 970682944 10.593 100 79 157 1 1044220176 56767168 16770064
02:04:36 5555 8192000000 9975014144 10329513984 998864544 10.868 100 82 162 2 1082715232 67035088 16815600
02:05:43 2704 8192000000 9997661168 10360188928 1026913552 11.139 100 94 187 0 1117565344 71821120 18830672
02:06:48 2189 8192000000 10020863056 10391949312 1049831216 11.359 100 84 168 2 1135486768 68647200 17008352
02:07:51 1169 8192000000 10036919136 10412568576 1065524400 11.509 100 87 173 0 1144759616 60858160 18377056
02:08:55 2358 8192000000 10043067072 10419499008 1071924896 11.570 100 83 166 2 1160820848 72124800 16771152
02:09:58 4508 8192000000 9999297504 10385809408 1074405872 11.594 100 49 97 1 1110479696 26157952 9915872
02:11:04 31781 8192000000 10006364544 10399563776 1082204240 11.668 100 61 122 1 1111619216 16209776 13205200
02:12:44 6498 8192000000 10005508080 10402775040 1087546416 11.719 100 47 94 0 1120548832 22796048 10206368
02:13:52 6734 8192000000 10000402000 10404761600 1091218432 11.754 100 24 48 0 1114501408 18136144 5146832
02:14:59 4958 8192000000 10001284272 10401304576 1093972736 11.780 100 49 96 2 1117785712 13097776 10715200
02:16:07 24650 8192000000 10002799920 10418749440 1094127104 11.782 100 51 105 1 1120471424 15855520 10488800
02:17:33 9747 8192000000 9987366288 10387288064 1067680688 11.530 99 35 72 1 1101382896 25224896 8477312
02:18:45 6958 8192000000 9955296352 10345365504 1019805712 11.070 97 34 66 2 1040166256 12958128 7402416
02:19:55 6272 8192000000 9917938368 10303074304 999581648 10.874 97 41 82 3 1027688192 18733440 9373104
02:21:04 31094 8192000000 9921717824 10311352320 955370048 10.444 93 35 70 1 993174880 30037904 7766928
02:22:35 9505 8192000000 9939031504 10328158208 1025999264 11.130 100 36 72 1 1048097776 13683392 8415120
02:23:48 4976 8192000000 9964693376 10353078272 1042032752 11.284 100 59 119 2 1081981088 26820272 13128064
02:24:55 10177 8192000000 9970169664 10359644160 1062395056 11.479 100 23 46 2 1092236128 25184000 4657072
02:26:06 47894 8192000000 9979988000 10388385792 1072441856 11.575 100 22 42 2 1096508240 19250416 4815968
02:28:06 6406 8192000000 10039172784 10437144576 1101468752 11.852 100 48 97 2 1151411104 40166496 9775856
02:29:17 26413 8192000000 10077879360 10486177792 1113897184 11.969 100 46 92 1 1202251648 78922576 9431888
02:30:54 16990 8192000000 10110041504 10518466560 1133290928 12.152 100 44 88 3 1227127856 84394384 9442544
02:32:16 5752 8192000000 10145732048 10552692736 1145212944 12.265 100 61 120 1 1256447776 98854960 12379872
02:33:31 11164 8192000000 10188594720 10596024320 1163072720 12.432 100 58 116 3 1292758752 117445680 12240352
02:34:44 5604 8192000000 10236490960 10657816576 1167390688 12.472 100 50 100 1 1342268128 163788960 11088480
02:35:53 7102 8192000000 10241448912 10658693120 1166868192 12.468 100 66 132 2 1336254640 154561440 14825008
02:37:04 5249 8192000000 10248235744 10689273856 1168731536 12.485 100 55 110 2 1356637248 175629648 12276064
02:38:14 12374 8192000000 10270922608 10698784768 1177453264 12.566 100 45 89 1 1387861648 199119504 11288880
02:39:42 4789 8192000000 10315399104 10729496576 1183632736 12.624 100 61 123 1 1420749120 222179936 14936448
02:40:52 6086 8192000000 10342321296 10749050880 1179907872 12.589 100 80 158 3 1444120656 246463104 17749680
02:42:08 3456 8192000000 10372785008 10783010816 1173122880 12.526 99 77 152 2 1464763168 273508064 18132224
02:43:14 2587 8192000000 10421298672 10825789440 1176643104 12.559 100 88 175 3 1515669088 318463056 20562928
02:44:21 1415 8192000000 10413345120 10822119424 1141207152 12.227 98 80 158 0 1503531216 343883632 18440432
02:45:25 4081 8192000000 10358027520 10764959744 1161313488 12.416 99 60 116 1 1453108288 277373904 14420896
02:46:32 4994 8192000000 10403740096 10824585216 1167743184 12.476 100 42 84 0 1503806992 325262544 10801264
02:47:44 15827 8192000000 10386331584 10820362240 1156718464 12.373 100 35 69 2 1496299376 330044960 9535952
02:49:14 22061 8192000000 10406785376 10856927232 1159820272 12.402 100 34 68 0 1510879664 342103872 8955520
02:50:39 19552 8192000000 10457902912 10931572736 1156120688 12.367 100 38 76 0 1561571088 395869600 9580800
02:52:38 27801 8192000000 10413723424 10916143104 1109286368 11.926 99 18 36 0 1527594176 412624880 5682928
02:54:27 76967 8192000000 10028598240 10450264064 1050143472 11.362 95 20 40 0 1069432896 14456464 4832960
02:56:47 8368 8192000000 10133970112 10552274944 1130558848 12.127 100 41 82 0 1220844880 81645680 8640352
02:57:57 84298 8192000000 10102785712 10521915392 1085734960 11.702 97 33 65 2 1141336192 48173760 7427472
03:00:31 94449 8192000000 10109909792 10548195328 1121731536 12.043 99 14 28 0 1190458400 65958960 2767904
03:03:58 6159 8192000000 10070538752 10554724352 1134004224 12.159 100 29 59 3 1167502384 26988176 6509984
03:05:06 31068 8192000000 10110503984 10547519488 1124999568 12.074 99 35 70 1 1184694880 52413968 7281344
03:06:46 65915 8192000000 10144420240 10582540288 1105926544 11.894 97 14 28 1 1215176624 106183552 3066528
03:08:53 189498 8192000000 10118144752 10586836992 1121330928 12.040 98 19 38 0 1225659872 100340272 3988672
03:13:05 54277 8192000000 10122009072 10638508032 1149464624 12.304 100 11 22 0 1223496768 71632624 2399520
03:15:06 47409 8192000000 10083566272 10521169920 1150317072 12.312 100 31 62 1 1178304448 21264464 6722912
03:16:57 58056 8192000000 10116829040 10564665344 1139240336 12.208 98 20 40 0 1202738208 59039280 4458592
03:18:56 86086 8192000000 10138135600 10766573568 1159425376 12.398 100 7 14 0 1340378624 179021216 1932032
03:21:35 47804 8192000000 10104602304 10540589056 1154607568 12.353 100 31 60 4 1202206224 41391904 6206752
03:23:33 71132 8192000000 10098095440 10585247744 1105518528 11.890 96 23 46 2 1196332304 85710528 5103248
03:25:44 126210 8192000000 10059089296 10515083264 1119158528 12.019 98 29 58 1 1160577712 35419744 5999440
03:29:28 43900 8192000000 10030941920 10494705664 1103410656 11.870 98 14 28 0 1137967680 31783248 2773776
03:31:19 51235 8192000000 10109003792 10588565504 1135472208 12.173 100 8 16 0 1185673888 48902256 1299424
03:33:11 53716 8192000000 10068848528 10531606528 1096852320 11.808 96 12 24 0 1150134720 51075632 2206768
03:35:08 52119 8192000000 10091507248 10527084544 1091799072 11.760 96 29 58 5 1146405136 48262000 6344064
03:37:05 59475 8192000000 10077524512 10515030016 1065157056 11.506 94 21 42 1 1136329808 66663584 4509168
03:39:04 47600 8192000000 10079775360 10525249536 1101058448 11.848 97 27 52 1 1158370064 51595296 5716320
03:40:54 41577 8192000000 10085731440 10516774912 1087315072 11.717 97 39 78 2 1163026688 66512640 9198976
03:42:40 43681 8192000000 10065996560 10551701504 1098959024 11.828 99 21 42 0 1221677904 118423072 4295808
03:44:24 58200 8192000000 10051692576 10479558656 1053025024 11.390 95 18 36 3 1092244896 35230800 3989072
03:46:27 126763 8192000000 10031636368 10498670592 1092578464 11.767 99 14 28 2 1122450784 27057984 2814336
03:49:41 34097 8192000000 10045676688 10539110400 1107055744 11.905 100 5 10 1 1150973088 42954080 963264
03:51:20 52010 8192000000 10071343920 10521743360 1092862304 11.770 98 17 34 1 1132811200 36616464 3332432
03:53:12 60178 8192000000 10073902864 10520621056 1108073616 11.914 99 22 44 1 1157599552 45342864 4183072
03:55:20 54777 8192000000 10075205056 10506317824 1078411056 11.632 97 23 47 0 1140420544 57311136 4698352
03:57:15 48368 8192000000 10065394992 10494500864 1072540128 11.576 96 31 62 1 1135341744 56415888 6385728
03:59:06 52895 8192000000 10068992912 10538143744 1104058592 11.876 99 16 29 2 1159710000 52701536 2949872
04:00:59 39904 8192000000 10088300768 10514485248 1115285632 11.982 99 25 50 0 1164393616 44288400 4819584
04:02:40 161332 8192000000 10074352368 10568056832 1120414400 12.031 99 11 21 0 1175345888 52871760 2059728
04:07:02 38296 8192000000 10086563664 10533531648 1132372944 12.144 100 24 48 1 1179260224 41741120 5146160
04:08:51 49742 8192000000 10061477600 10514624512 1128464416 12.107 99 23 46 3 1191331872 57764880 5102576
04:11:00 83795 8192000000 10146076352 10614480896 1143850064 12.252 100 20 39 2 1203275552 55243680 4181808
04:13:25 41919 8192000000 10146275248 10619129856 1139753504 12.213 99 26 51 1 1249123520 103803056 5566960
04:15:14 42933 8192000000 10076076848 10536546304 1121254944 12.039 98 16 32 1 1177591904 52899056 3437904
04:17:06 36702 8192000000 10078680000 10508636160 1122914864 12.055 99 20 41 1 1162368752 35424336 4029552
04:18:45 87024 8192000000 10045603840 10492649472 1073164880 11.582 95 18 36 0 1108963168 31561856 4236432
04:21:14 44447 8192000000 10040257936 10499211264 1055073616 11.409 95 8 16 0 1088006112 30917808 2014688
04:22:58 53888 8192000000 10053065312 10495131648 1093258464 11.774 98 20 39 2 1126299376 28798432 4242480
04:24:53 51336 8192000000 10121243952 10549886976 1098854048 11.827 98 19 38 1 1177622400 74555344 4213008
04:26:51 18034 8192000000 9214894608 9548226560 330140688 3.873 30 4 8 0 365698432 35063952 493792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2020 04:28:11 to 20-12-2020 04:28:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2020 04:28:27 to 20-12-2020 04:28:29.

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          234524
MON$OLDEST_ACTIVE               235682
MON$OLDEST_SNAPSHOT             235682
MON$NEXT_TRANSACTION            235682
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                       2103680
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             800
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Dec 20 04:27:15 2020
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

20.12.20 04:28:30. 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
2020-12-20 04:28:32 No severe PSQL-related problems occured
20.12.20 04:28:33. End of report.