Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2020 04:30
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 16537108 kB
MemAvailable 31424932 kB
Buffers 20 kB
Cached 15015500 kB
SwapCached 3712 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-16 04:26:55
DTS_END                         2020-12-16 04:26:55.3080
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/46404
TRN_ID                          239286
ATT_ID                          680
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: 9118.98 17894 1094277
customer order: creation 178.39 396 21407
customer order: refuse 15.63 346 1876
order to supplier: creation 47.33 1390 5679
order to supplier: removal 3.31 98101 397
invoice (draft): creation 46.99 3678 5639
invoice (draft): removal 48.14 831 5777
invoice accept: apply 43.91 16345 5269
invoice accept: cancel 44.13 864 5296
customer reserve: creation 4015.50 161 481860
customer reserve: removal 4387.43 60 526491
realization accept: apply 86.64 194 10397
realization accept: cancel 71.35 124 8562
payment from customer: creation 60.49 121 7259
payment from customer: removal 5.88 100 705
payment to supplier: creation 49.24 120 5909
payment to supplier: removal 3.58 69 429
service: total inventory turnovers 3.96 3157 475
service: total monetary turnovers 6.10 503 732
service: aggregate perf. data 0.95 15075 114
service: refresh index statistics 0.03 216240 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2020 04:30:27 to 16-12-2020 04:30:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7801
TEST_TIME 2 8460
TEST_TIME 3 7326
TEST_TIME 4 6985
TEST_TIME 5 7263
TEST_TIME 6 6791
TEST_TIME 7 6513
TEST_TIME 8 6953
TEST_TIME 9 7173
TEST_TIME 10 7455
TEST_TIME 11 7678
TEST_TIME 12 7874
TEST_TIME 13 8054
TEST_TIME 14 8370
TEST_TIME 15 8459
TEST_TIME 16 8708
TEST_TIME 17 8990
TEST_TIME 18 9274
TEST_TIME 19 9344
TEST_TIME 20 9685
TEST_TIME 21 9853
TEST_TIME 22 10198
TEST_TIME 23 10140
TEST_TIME 24 10009
TEST_TIME 25 10338
TEST_TIME 26 10545
TEST_TIME 27 11184
TEST_TIME 28 11498
TEST_TIME 29 11360
TEST_TIME 30 11175
TEST_TIME 31 11353
TEST_TIME 32 11170
TEST_TIME 33 11083
TEST_TIME 34 10990
TEST_TIME 35 11099
TEST_TIME 36 11238
TEST_TIME 37 11027
TEST_TIME 38 10767
TEST_TIME 39 10538
TEST_TIME 40 10552
TEST_TIME 41 10467
TEST_TIME 42 10512
TEST_TIME 43 10429
TEST_TIME 44 10445
TEST_TIME 45 10296
TEST_TIME 46 10269
TEST_TIME 47 10251
TEST_TIME 48 10179
TEST_TIME 49 10067
TEST_TIME 50 10155
TEST_TIME 51 10046
TEST_TIME 52 10121
TEST_TIME 53 10056
TEST_TIME 54 10059
TEST_TIME 55 10061
TEST_TIME 56 10100
TEST_TIME 57 10036
TEST_TIME 58 10002
TEST_TIME 59 10164
TEST_TIME 60 10100
TEST_TIME 61 10196
TEST_TIME 62 10195
TEST_TIME 63 10144
TEST_TIME 64 10016
TEST_TIME 65 9975
TEST_TIME 66 9885
TEST_TIME 67 9833
TEST_TIME 68 9863
TEST_TIME 69 9844
TEST_TIME 70 9770
TEST_TIME 71 9693
TEST_TIME 72 9848
TEST_TIME 73 9824
TEST_TIME 74 9745
TEST_TIME 75 9706
TEST_TIME 76 9723
TEST_TIME 77 9691
TEST_TIME 78 9678
TEST_TIME 79 9707
TEST_TIME 80 9691
TEST_TIME 81 9592
TEST_TIME 82 9549
TEST_TIME 83 9480
TEST_TIME 84 9456
TEST_TIME 85 9384
TEST_TIME 86 9344
TEST_TIME 87 9309
TEST_TIME 88 9324
TEST_TIME 89 9293
TEST_TIME 90 9289
TEST_TIME 91 9303
TEST_TIME 92 9323
TEST_TIME 93 9342
TEST_TIME 94 9354
TEST_TIME 95 9386
TEST_TIME 96 9356
TEST_TIME 97 9350
TEST_TIME 98 9386
TEST_TIME 99 9427
TEST_TIME 100 9464
TEST_TIME 101 9487
TEST_TIME 102 9396
TEST_TIME 103 9327
TEST_TIME 104 9279
TEST_TIME 105 9234
TEST_TIME 106 9212
TEST_TIME 107 9181
TEST_TIME 108 9170
TEST_TIME 109 9136
TEST_TIME 110 9133
TEST_TIME 111 9113
TEST_TIME 112 9119
TEST_TIME 113 9081
TEST_TIME 114 9114
TEST_TIME 115 9180
TEST_TIME 116 9202
TEST_TIME 117 9214
TEST_TIME 118 9231
TEST_TIME 119 9179
TEST_TIME 120 9116
TEST_TIME 121 9051

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-12-2020 04:30:30 to 16-12-2020 04:30:34.

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 1538953 1538953 0 0.00 0 16014 6 0 0
doc_list_biud 1538950 1538950 0 0.00 0 48160 26 0 0
sp_add_invoice_to_stock 5397 5269 128 2.37 46 172245 16345 0 128
sp_cancel_adding_invoice 5296 5296 0 0.00 6 57904 864 0 0
sp_cancel_client_order 1876 1876 0 0.00 4 33158 346 0 0
sp_cancel_customer_reserve 526491 526491 0 0.00 1 48162 60 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 9160 101 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 2477 70 0 0
sp_cancel_supplier_invoice 5781 5777 4 0.06 1 25325 831 0 4
sp_cancel_supplier_order 397 397 0 0.00 9 921248 98101 0 0
sp_cancel_write_off 8562 8562 0 0.00 1 10943 124 0 0
sp_client_order 21407 21407 0 0.00 4 81703 397 0 0
sp_customer_reserve 481860 481860 0 0.00 0 39533 162 0 0
sp_fill_shopping_cart 32713 32713 0 0.00 1 17298 44 0 0
sp_fill_shopping_cart_clo_res 481846 481846 0 0.00 0 24012 16 0 0
sp_get_clo_for_invoice 5255 5255 0 0.00 0 33345 389 0 0
sp_kill_cost_storno 14992 14992 0 0.00 0 7275 53 0 0
sp_kill_qstorno_ret_qs2qd 1067718 1067718 0 0.00 0 48151 32 0 0
sp_kill_qty_storno 564050 564050 0 0.00 0 48159 71 0 0
sp_lock_selected_doc 21633 21633 0 0.00 0 7111 6 0 0
sp_make_cost_storno 28819 28819 0 0.00 0 14991 56 0 0
sp_multiply_rows_for_pdistr 28819 28819 0 0.00 0 9170 30 0 0
sp_multiply_rows_for_qdistr 21407 21407 0 0.00 0 81675 204 0 0
sp_pay_from_customer 7259 7259 0 0.00 4 17880 122 0 0
sp_pay_to_supplier 5909 5909 0 0.00 5 11693 120 0 0
sp_payment_common 13168 13168 0 0.00 1 9100 27 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 33151 319 0 0
sp_qd_handle_on_invoice_upd_sts 10550 10550 0 0.00 0 38415 358 0 0
sp_qd_handle_on_reserve_upd_sts 10397 10397 0 0.00 0 16749 60 0 0
sp_reserve_write_off 10397 10397 0 0.00 7 17320 194 0 0
sp_supplier_invoice 5639 5639 0 0.00 6 100751 3678 0 0
sp_supplier_order 5679 5679 0 0.00 5 113507 1391 0 0
srv_aggregate_perf_data 114 114 0 0.00 2675 152819 15075 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1092 173341 47026 0 0
srv_find_qd_qs_mism 1044359 1044359 0 0.00 0 14300 5 0 0
srv_make_invnt_saldo 475 475 0 0.00 45 96183 3158 0 0
srv_make_money_saldo 732 732 0 0.00 1 14342 504 0 0
srv_recalc_idx_stat 4 4 0 0.00 58164 317608 216240 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5269 5269 0 0.00 0 22604 177 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 4682 104 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 6228 14 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1360 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 1796 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 2584 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 810 13 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 14407 1479 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 2472 7 0 0
t$perf-norm:sp_client_order 21407 21407 0 0.00 0 24327 21 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 1060 4 0 0
t$perf-norm:sp_pay_from_customer 7259 7259 0 0.00 0 24230 13 0 0
t$perf-norm:sp_pay_to_supplier 5909 5909 0 0.00 0 9856 11 0 0
t$perf-norm:sp_reserve_write_off 10397 10397 0 0.00 0 5984 10 0 0
t$perf-norm:sp_supplier_invoice 5639 5639 0 0.00 0 5883 25 0 0
t$perf-norm:sp_supplier_order 5679 5679 0 0.00 0 12472 17 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 588 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 877 18 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1826 11 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 642 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10764 10508 256 2.37 0 7585 19 0 256
v_all_customers 38282 38282 0 0.00 0 18 1 0 0
v_all_suppliers 30702 30702 0 0.00 0 34 1 0 0
v_all_wares 42814 42814 0 0.00 0 231 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1820 8 0 0
v_cancel_client_order 3752 3752 0 0.00 0 3905 7 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 6198 20 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 598 4 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 2458 8 0 0
v_cancel_supplier_invoice 782 774 8 1.02 0 2406 25 0 8
v_cancel_supplier_order 794 794 0 0.00 0 195 3 0 0
v_cancel_write_off 2090 2090 0 0.00 0 3910 17 0 0
v_max_id_clo_ord 5677 5677 0 0.00 0 1286 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 4151 20 0 0
v_max_id_ord_sup 5629 5629 0 0.00 0 7189 9 0 0
v_max_non_paid_invoice 1864 1864 0 0.00 0 7767 6 0 0
v_max_non_paid_realizn 4199 4199 0 0.00 0 2518 3 0 0
v_min_id_clo_ord 5677 5677 0 0.00 0 9457 11 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 7033 22 0 0
v_min_id_ord_sup 5629 5629 0 0.00 0 13446 17 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 9096 17 0 0
v_min_non_paid_realizn 7259 7259 0 0.00 0 7712 19 0 0
v_our_firm 10652 10652 0 0.00 0 19 1 0 0
v_reserve_write_off 20794 20794 0 0.00 0 6758 7 0 0
x_lock_depdocs_on_canc_invoice 10686 10686 0 0.00 0 11161 100 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 7877 286 0 0
x_make_qstorno_1000_1200 5679 5679 0 0.00 2 110410 1301 0 0
x_make_qstorno_1200_2000 5639 5639 0 0.00 3 100353 3518 0 0
x_make_qstorno_2100_3300 481860 481860 0 0.00 0 39531 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2020 04:30:35 to 16-12-2020 04:30:39.

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 8 8192000000 8796641776 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:59 37317 8192000000 9739261664 10041434112 811945792 9.017 100 89 178 1 841017248 7522784 21548672
01:59:38 21309 8192000000 9776071888 10085765120 847320272 9.373 100 90 180 2 881935200 13491792 21123136
02:01:09 20046 8192000000 9818173216 10144440320 878549296 9.685 100 88 176 1 925121920 26470480 20102144
02:02:36 13234 8192000000 9885068768 10221297664 934814480 10.242 100 80 161 2 1002436448 50536160 17085808
02:04:01 9475 8192000000 9940610064 10290708480 977485104 10.660 100 74 147 1 1046552336 53828624 15238608
02:05:12 9745 8192000000 9978638608 10339676160 1006158896 10.938 100 80 160 0 1088971360 66843232 15969232
02:06:23 4763 8192000000 10000600496 10364563456 1036977904 11.236 100 81 163 0 1110017520 56186432 16853184
02:07:29 2345 8192000000 10021577024 10390708224 1050484240 11.365 100 85 170 1 1139305632 71212064 17609328
02:08:33 1362 8192000000 10021937920 10401734656 1060665184 11.463 100 72 144 2 1143563600 68374544 14523872
02:09:36 4406 8192000000 9988990928 10388750336 1066263408 11.516 100 40 80 1 1104812448 30000896 8548144
02:10:43 45816 8192000000 9996239904 10379894784 1074068480 11.591 100 63 128 1 1094686784 7345632 13272672
02:12:30 7460 8192000000 10009593952 10406248448 1081059760 11.658 100 37 74 1 1113249936 24290480 7899696
02:13:43 6588 8192000000 9995352608 10396418048 1086560240 11.710 100 37 72 3 1117669312 22836656 8272416
02:14:54 4737 8192000000 10012283936 10407469056 1095772672 11.798 100 48 96 2 1121794096 15318368 10703056
02:16:02 40107 8192000000 9989837984 10398920704 1066343792 11.517 97 34 66 3 1101894736 28946304 6604640
02:17:43 6275 8192000000 9985972544 10385125376 1065032448 11.505 99 47 96 2 1097158240 21843344 10282448
02:18:53 4586 8192000000 9963153728 10347384832 1043838240 11.302 99 44 88 2 1065619712 10430912 11350560
02:19:59 8362 8192000000 9915212160 10297208832 961663072 10.505 94 34 68 3 986514144 17900928 6950144
02:21:11 6365 8192000000 9944996512 10340405248 992087424 10.802 98 24 47 0 1048182032 51236304 4858304
02:22:21 6818 8192000000 9943443104 10325352448 1025313920 11.123 100 53 105 1 1057272944 20316576 11642448
02:23:29 8783 8192000000 9962419296 10349662208 1036914192 11.235 100 51 102 4 1066087760 17677568 11496000
02:24:45 60414 8192000000 9980480464 10379272192 1055204416 11.411 100 44 88 1 1076609248 11464656 9940176
02:26:49 7505 8192000000 10002906128 10399641600 1080920624 11.656 100 38 76 0 1106948624 17897472 8130528
02:27:58 4060 8192000000 10047418080 10442752000 1103229456 11.868 100 66 130 4 1160081056 43111536 13740064
02:29:08 33355 8192000000 10060640368 10472255488 1116216240 11.991 100 28 57 2 1171267632 48519696 6531696
02:30:43 17221 8192000000 10078590848 10486145024 1131946800 12.140 100 35 68 1 1199006528 60213856 6845872
02:32:03 9549 8192000000 10118225120 10528464896 1144555680 12.258 100 52 102 0 1230140576 74999392 10585504
02:33:18 4045 8192000000 10187106064 10588790784 1157693184 12.382 100 77 152 1 1296835040 123331568 15810288
02:34:27 6750 8192000000 10197871728 10612191232 1160006320 12.403 100 58 115 1 1304208096 130260656 13941120
02:35:47 15077 8192000000 10207334112 10638696448 1164950496 12.450 100 43 84 0 1302737952 128805568 8981888
02:37:06 2929 8192000000 10229008496 10638323712 1179500560 12.586 100 70 140 4 1330505632 136107568 14897504
02:38:16 8688 8192000000 10217116992 10639355904 1169879392 12.496 100 41 80 3 1323048304 143925264 9243648
02:39:36 1092 8192000000 10252522784 10664775680 1168755648 12.485 100 76 151 0 1354852480 169562304 16534528
02:40:43 3505 8192000000 10263580016 10665799680 1155326672 12.359 100 77 154 1 1358825536 186435360 17063504
02:41:51 2177 8192000000 10266508832 10664521728 1153484240 12.342 100 87 170 2 1362208320 189484704 19239376
02:42:59 3018 8192000000 10268244752 10666524672 1117423600 12.003 100 71 139 3 1357330224 223635984 16270640
02:44:07 4110 8192000000 10287595280 10688077824 1118906352 12.017 100 65 131 0 1385416416 251790656 14719408
02:45:15 2406 8192000000 10255311424 10663215104 1128718848 12.109 100 57 114 2 1363036544 220968608 13349088
02:46:26 8615 8192000000 10284574544 10700689408 1108294288 11.916 99 36 72 1 1389552048 272821760 8436000
02:47:38 9950 8192000000 10277422992 10710667264 1115996704 11.989 100 30 60 0 1370453600 246452208 8004688
02:48:51 18601 8192000000 10275564096 10687934464 1115875472 11.988 100 33 66 4 1372083600 248466320 7741808
02:50:13 21976 8192000000 10335938016 10755780608 1116618816 11.995 99 36 72 0 1423146880 297026704 9501360
02:51:51 29676 8192000000 10315948656 10784727040 1120538720 12.032 100 23 46 1 1427901264 301338112 6024432
02:54:14 93405 8192000000 10068555904 10489729024 1094304544 11.784 98 18 34 0 1184572064 86193952 4073568
02:56:49 33721 8192000000 10075339168 10543284224 1120382512 12.031 100 32 64 2 1194443264 65866224 8194528
02:58:30 42585 8192000000 10060775728 10475880448 1093632128 11.777 97 18 34 2 1123497680 26538480 3327072
03:00:13 74966 8192000000 10157667264 10609692672 1142238272 12.237 100 17 34 0 1226077568 80592416 3246880
03:02:54 102876 8192000000 10084538896 10508742656 1122939776 12.055 99 32 64 3 1150684192 21409616 6334800
03:05:52 65092 8192000000 10119422592 10566520832 1142034352 12.235 100 24 48 2 1201500544 53927248 5538944
03:08:01 58423 8192000000 10060408064 10535919616 1132406848 12.144 99 16 32 0 1174258144 38385648 3465648
03:10:20 43925 8192000000 10064411008 10548817920 1131295856 12.134 99 19 38 0 1189501888 54121776 4084256
03:12:05 32690 8192000000 10092414896 10540724224 1135355248 12.172 99 27 55 1 1194204080 52539120 6309712
03:13:41 29324 8192000000 10128692560 10569797632 1148032912 12.291 100 26 52 0 1224022832 69373136 6616784
03:15:14 68402 8192000000 10095598432 10602196992 1150515584 12.314 100 10 20 0 1228987984 75759600 2712800
03:17:57 85478 8192000000 10052434112 10498396160 1042909552 11.293 91 11 22 1 1067994784 23145360 1939872
03:20:27 32304 8192000000 10095097248 10533711872 1110554592 11.938 98 41 82 1 1150084880 31174224 8356064
03:22:06 74994 8192000000 10119764672 10594197504 1122568496 12.051 99 25 50 1 1197246576 69426896 5251184
03:24:22 63120 8192000000 10102032112 10555019264 1080876320 11.656 96 18 35 0 1166367024 81553024 3937680
03:26:25 66053 8192000000 10038927248 10477690880 1065316736 11.507 95 16 32 1 1137866512 68978896 3570880
03:29:23 73053 8192000000 10018262032 10448420864 1090980240 11.752 97 18 36 0 1115977680 21705440 3292000
03:31:38 64317 8192000000 10047966176 10489352192 1090786976 11.750 97 11 22 1 1134634288 41344528 2502784
03:33:58 70315 8192000000 10053573824 10498310144 1100846976 11.846 98 12 24 1 1140865904 37731008 2287920
03:36:15 27465 8192000000 10088949600 10528727040 1122871360 12.054 99 27 53 0 1169514960 40842384 5801216
03:37:45 63340 8192000000 10081446688 10572963840 1075102048 11.601 96 22 44 2 1187356992 107088336 5166608
03:39:49 64447 8192000000 10068658720 10493865984 1076520048 11.614 96 29 58 2 1113850112 31373280 5956784
03:42:03 96561 8192000000 10046380736 10513092608 1083557328 11.681 97 22 44 1 1142939712 54977712 4404672
03:44:41 28310 8192000000 10084085168 10554703872 1102396880 11.860 99 29 58 0 1169855568 61280144 6178544
03:46:14 108885 8192000000 10061320704 10499473408 1103258880 11.869 98 25 50 2 1143519584 34968176 5292528
03:49:19 47008 8192000000 10097188368 10580471808 1132352944 12.144 100 9 18 1 1206083488 71876272 1854272
03:51:18 59300 8192000000 10046105184 10540040192 1126688560 12.090 100 5 10 0 1179855648 52065520 1101568
03:53:19 52984 8192000000 10094946144 10543915008 1137394816 12.191 100 21 42 3 1201865776 59857664 4613296
03:55:15 41021 8192000000 10085359744 10531614720 1132757680 12.147 99 25 50 1 1172360736 34549072 5053984
03:56:56 62096 8192000000 10121018336 10583293952 1145812080 12.270 100 24 48 1 1228351280 76941104 5598096
03:58:59 103963 8192000000 10082417408 10545668096 1115754064 11.987 97 17 33 1 1142764816 23945888 3064864
04:01:43 47955 8192000000 10102771536 10549493760 1116879808 11.998 97 30 60 1 1154612992 31921488 5811696
04:03:32 51200 8192000000 10146509824 10597593088 1118760544 12.015 97 35 70 2 1200389056 74601344 7027168
04:05:24 72238 8192000000 10095083504 10624634880 1138054912 12.197 100 6 12 2 1202764880 63297392 1412576
04:07:43 173339 8192000000 10063651776 10522992640 1131272304 12.133 99 13 26 0 1169520944 35329296 2919344
04:13:12 35760 8192000000 10071589696 10510630912 1120069920 12.028 99 32 62 0 1156537328 29971936 6495472
04:14:56 72494 8192000000 10110607040 10544402432 1079970256 11.647 94 21 42 1 1137064160 52401952 4691952
04:17:08 108078 8192000000 10142320096 10609549312 1118997856 12.018 98 18 36 1 1222263008 99398112 3867040
04:19:58 49821 8192000000 10186211152 10640805888 1135148608 12.170 100 22 44 0 1256450512 116028672 5273232
04:22:03 70563 8192000000 10110633664 10570358784 1112031392 11.952 98 19 37 2 1181973408 65934944 4007072
04:24:15 65486 8192000000 10085905408 10500980736 1075445840 11.604 95 28 56 2 1122756800 41773568 5537392
04:26:24 70896 8192000000 9475411040 9870761984 570423008 6.509 50 11 21 0 658489552 85762336 2304208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2020 04:30:41 to 16-12-2020 04:30:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-12-2020 04:30:57 to 16-12-2020 04:30:58.

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          238113
MON$OLDEST_ACTIVE               239746
MON$OLDEST_SNAPSHOT             239746
MON$NEXT_TRANSACTION            239746
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                       2125504
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             808
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2296 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.2296 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 ---
:175: 
:176: mscs-test-161.budzdorov.ru    Wed Dec 16 04:27:56 2020
:177:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:178: 
--- end of diff output ---

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

16.12.20 04:31:00. 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-16 04:31:02 No severe PSQL-related problems occured
16.12.20 04:31:03. End of report.