Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.12.2020 07: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 16832148 kB
MemAvailable 31455956 kB
Buffers 0 kB
Cached 14720340 kB
SwapCached 1980 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-07 07:26:52
DTS_END                         2020-12-07 07:26:52.0930
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/49178
TRN_ID                          233232
ATT_ID                          608
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: 8899.35 20907 1067922
customer order: creation 169.38 424 20325
customer order: refuse 14.91 355 1789
order to supplier: creation 45.79 1381 5495
order to supplier: removal 3.31 114809 397
invoice (draft): creation 45.23 3743 5427
invoice (draft): removal 50.36 933 6043
invoice accept: apply 40.97 17208 4916
invoice accept: cancel 45.23 853 5427
customer reserve: creation 3724.48 169 446937
customer reserve: removal 4470.14 67 536417
realization accept: apply 81.78 179 9814
realization accept: cancel 81.76 107 9811
payment from customer: creation 58.61 114 7033
payment from customer: removal 5.25 121 630
payment to supplier: creation 48.37 118 5804
payment to supplier: removal 3.23 86 388
service: total inventory turnovers 3.55 3731 426
service: total monetary turnovers 6.08 540 730
service: aggregate perf. data 0.90 15036 108
service: refresh index statistics 0.04 258169 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-12-2020 07:27:44 to 07-12-2020 07:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3055
TEST_TIME 2 4286
TEST_TIME 3 5585
TEST_TIME 4 5895
TEST_TIME 5 6188
TEST_TIME 6 6640
TEST_TIME 7 6959
TEST_TIME 8 6950
TEST_TIME 9 6990
TEST_TIME 10 7132
TEST_TIME 11 7253
TEST_TIME 12 7758
TEST_TIME 13 8088
TEST_TIME 14 7916
TEST_TIME 15 8063
TEST_TIME 16 8453
TEST_TIME 17 8541
TEST_TIME 18 8905
TEST_TIME 19 9135
TEST_TIME 20 9445
TEST_TIME 21 9365
TEST_TIME 22 9303
TEST_TIME 23 9655
TEST_TIME 24 9679
TEST_TIME 25 10149
TEST_TIME 26 10370
TEST_TIME 27 10531
TEST_TIME 28 11272
TEST_TIME 29 11561
TEST_TIME 30 11234
TEST_TIME 31 11379
TEST_TIME 32 11152
TEST_TIME 33 11065
TEST_TIME 34 10786
TEST_TIME 35 10604
TEST_TIME 36 10412
TEST_TIME 37 10258
TEST_TIME 38 10146
TEST_TIME 39 10162
TEST_TIME 40 10145
TEST_TIME 41 10012
TEST_TIME 42 9959
TEST_TIME 43 9788
TEST_TIME 44 9708
TEST_TIME 45 9689
TEST_TIME 46 9656
TEST_TIME 47 9486
TEST_TIME 48 9335
TEST_TIME 49 9299
TEST_TIME 50 9370
TEST_TIME 51 9418
TEST_TIME 52 9394
TEST_TIME 53 9380
TEST_TIME 54 9325
TEST_TIME 55 9310
TEST_TIME 56 9407
TEST_TIME 57 9383
TEST_TIME 58 9369
TEST_TIME 59 9366
TEST_TIME 60 9337
TEST_TIME 61 9356
TEST_TIME 62 9421
TEST_TIME 63 9414
TEST_TIME 64 9350
TEST_TIME 65 9257
TEST_TIME 66 9179
TEST_TIME 67 9103
TEST_TIME 68 9075
TEST_TIME 69 9068
TEST_TIME 70 8990
TEST_TIME 71 9031
TEST_TIME 72 9072
TEST_TIME 73 9041
TEST_TIME 74 9085
TEST_TIME 75 9024
TEST_TIME 76 8984
TEST_TIME 77 8969
TEST_TIME 78 8937
TEST_TIME 79 9172
TEST_TIME 80 9274
TEST_TIME 81 9301
TEST_TIME 82 9330
TEST_TIME 83 9293
TEST_TIME 84 9231
TEST_TIME 85 9154
TEST_TIME 86 9143
TEST_TIME 87 9075
TEST_TIME 88 9021
TEST_TIME 89 9046
TEST_TIME 90 8995
TEST_TIME 91 9003
TEST_TIME 92 8968
TEST_TIME 93 8938
TEST_TIME 94 8984
TEST_TIME 95 8964
TEST_TIME 96 8936
TEST_TIME 97 8983
TEST_TIME 98 8972
TEST_TIME 99 8988
TEST_TIME 100 8989
TEST_TIME 101 9071
TEST_TIME 102 9059
TEST_TIME 103 9100
TEST_TIME 104 9033
TEST_TIME 105 8972
TEST_TIME 106 8920
TEST_TIME 107 8877
TEST_TIME 108 8835
TEST_TIME 109 8811
TEST_TIME 110 8801
TEST_TIME 111 8809
TEST_TIME 112 8786
TEST_TIME 113 8757
TEST_TIME 114 8865
TEST_TIME 115 8832
TEST_TIME 116 8836
TEST_TIME 117 8843
TEST_TIME 118 8840
TEST_TIME 119 8853
TEST_TIME 120 8900

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-12-2020 07:27:48 to 07-12-2020 07:27:51.

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 1481104 1481104 0 0.00 0 17750 7 0 0
doc_list_biud 1481104 1481104 0 0.00 0 58417 31 0 0
sp_add_invoice_to_stock 5028 4916 112 2.22 35 182535 17208 0 112
sp_cancel_adding_invoice 5427 5427 0 0.00 3 52827 854 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 23417 355 0 0
sp_cancel_customer_reserve 536417 536417 0 0.00 1 37290 68 0 0
sp_cancel_pay_from_customer 630 630 0 0.00 3 4855 122 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 4085 86 0 0
sp_cancel_supplier_invoice 6052 6043 9 0.14 2 58439 933 0 9
sp_cancel_supplier_order 397 397 0 0.00 11 1146816 114810 0 0
sp_cancel_write_off 9811 9811 0 0.00 1 7923 107 0 0
sp_client_order 20325 20325 0 0.00 4 32397 425 0 0
sp_customer_reserve 446937 446937 0 0.00 0 100445 170 0 0
sp_fill_shopping_cart 31238 31238 0 0.00 1 17387 53 0 0
sp_fill_shopping_cart_clo_res 446930 446930 0 0.00 0 47912 18 0 0
sp_get_clo_for_invoice 4909 4909 0 0.00 0 32728 428 0 0
sp_kill_cost_storno 16256 16256 0 0.00 0 7106 57 0 0
sp_kill_qstorno_ret_qs2qd 1089085 1089085 0 0.00 0 58414 37 0 0
sp_kill_qty_storno 574607 574607 0 0.00 0 58417 79 0 0
sp_lock_selected_doc 20439 20439 0 0.00 0 8327 6 0 0
sp_make_cost_storno 27560 27560 0 0.00 0 15507 54 0 0
sp_multiply_rows_for_pdistr 27560 27560 0 0.00 0 8799 32 0 0
sp_multiply_rows_for_qdistr 20325 20325 0 0.00 0 32348 227 0 0
sp_pay_from_customer 7033 7033 0 0.00 4 15237 114 0 0
sp_pay_to_supplier 5804 5804 0 0.00 5 15371 119 0 0
sp_payment_common 12837 12837 0 0.00 1 7368 22 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 18964 318 0 0
sp_qd_handle_on_invoice_upd_sts 10336 10336 0 0.00 0 36025 379 0 0
sp_qd_handle_on_reserve_upd_sts 9814 9814 0 0.00 0 22756 53 0 0
sp_reserve_write_off 9814 9814 0 0.00 7 22801 179 0 0
sp_supplier_invoice 5427 5427 0 0.00 7 79373 3744 0 0
sp_supplier_order 5495 5495 0 0.00 6 35801 1382 0 0
srv_aggregate_perf_data 108 108 0 0.00 2527 299879 15037 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1270 112518 45991 0 0
srv_find_qd_qs_mism 1020936 1020936 0 0.00 0 17749 5 0 0
srv_make_invnt_saldo 426 426 0 0.00 57 101367 3731 0 0
srv_make_money_saldo 730 730 0 0.00 1 10357 541 0 0
srv_recalc_idx_stat 5 5 0 0.00 182235 367189 258169 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4916 4916 0 0.00 0 14152 170 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 4700 93 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 5271 13 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 2857 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 630 630 0 0.00 0 271 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 84 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 997 17 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 23493 1406 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 883 7 0 0
t$perf-norm:sp_client_order 20325 20325 0 0.00 0 6019 18 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 6015 18 0 0
t$perf-norm:sp_pay_from_customer 7033 7033 0 0.00 0 5372 10 0 0
t$perf-norm:sp_pay_to_supplier 5804 5804 0 0.00 0 5802 9 0 0
t$perf-norm:sp_reserve_write_off 9814 9814 0 0.00 0 5231 9 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 4722 15 0 0
t$perf-norm:sp_supplier_order 5495 5495 0 0.00 0 6156 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 182 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 129 6 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 598 7 0 0
t$perf-norm:srv_make_money_saldo 730 730 0 0.00 0 583 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 10 3 0 0
v_add_invoice_to_stock 10042 9818 224 2.23 0 4967 16 0 224
v_all_customers 36788 36788 0 0.00 0 19 1 0 0
v_all_suppliers 30266 30266 0 0.00 0 19 1 0 0
v_all_wares 40650 40650 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 1130 7 0 0
v_cancel_client_order 3578 3578 0 0.00 0 23322 11 0 0
v_cancel_customer_prepayment 1260 1260 0 0.00 0 3995 12 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 3057 7 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 4079 9 0 0
v_cancel_supplier_invoice 848 830 18 2.12 0 1338 15 0 18
v_cancel_supplier_order 794 794 0 0.00 0 598 5 0 0
v_cancel_write_off 2038 2038 0 0.00 0 1167 13 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 321 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 2010 18 0 0
v_max_id_ord_sup 5419 5419 0 0.00 0 7830 8 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 156 2 0 0
v_max_non_paid_realizn 3797 3797 0 0.00 0 798 3 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 4639 12 0 0
v_min_id_clo_res 980 980 0 0.00 0 3376 11 0 0
v_min_id_ord_sup 5419 5419 0 0.00 0 11220 9 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 7364 16 0 0
v_min_non_paid_realizn 7033 7033 0 0.00 0 5242 15 0 0
v_our_firm 10334 10334 0 0.00 0 34 1 0 0
v_reserve_write_off 19628 19628 0 0.00 0 13471 7 0 0
x_lock_depdocs_on_canc_invoice 11055 11055 0 0.00 0 18472 128 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 27629 435 0 0
x_make_qstorno_1000_1200 5495 5495 0 0.00 2 35783 1290 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 76436 3535 0 0
x_make_qstorno_2100_3300 446937 446937 0 0.00 0 100371 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-12-2020 07:27:52 to 07-12-2020 07:27:57.

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
04:56:55 6 8192000000 8796649056 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
04:57:56 23542 8192000000 9716436384 10012545024 792558080 8.821 100 88 176 0 822146768 8009584 21579104
04:59:26 11921 8192000000 9749211024 10051776512 826764672 9.167 100 89 178 1 860397472 12076448 21556352
05:00:51 18615 8192000000 9781161392 10100924416 856443632 9.465 100 82 163 2 898192496 22945312 18803552
05:02:17 16465 8192000000 9868425600 10200694784 918129680 10.078 100 82 163 2 979126368 42338704 18657984
05:03:35 9969 8192000000 9930664336 10271375360 956519728 10.455 100 77 154 0 1035650608 62400768 16730112
05:04:49 5266 8192000000 9949104432 10301100032 990814912 10.789 100 85 170 3 1060009264 51897216 17297136
05:05:55 3236 8192000000 9995540096 10359230464 1017951712 11.052 100 81 158 1 1117571584 84453056 15166816
05:07:01 1995 8192000000 10030063568 10393890816 1044674496 11.310 100 90 180 1 1137736704 73868256 19193952
05:08:04 30598 8192000000 10008825920 10392248320 1061968064 11.475 100 74 150 1 1133597536 55061920 16567552
05:09:35 7430 8192000000 9998431056 10387435520 1064756224 11.502 100 38 76 1 1111899232 39842416 7300592
05:10:44 5146 8192000000 9989958896 10391334912 1071418400 11.566 100 49 98 1 1125999040 42980000 11600640
05:11:50 3947 8192000000 9994189824 10392559616 1086288368 11.707 100 47 93 3 1124128064 28042944 9796752
05:12:55 32165 8192000000 10009849840 10398019584 1084776560 11.693 100 51 103 0 1129163280 32484096 11902624
05:14:28 5814 8192000000 10030242704 10430263296 1090646832 11.749 100 41 82 0 1149541120 50053200 8841088
05:15:37 9180 8192000000 10018675392 10426163200 1096574960 11.805 100 49 98 2 1119853984 11820848 11458176
05:16:48 8287 8192000000 9988355680 10378473472 1041704032 11.281 97 26 50 4 1076127280 28282784 6140464
05:17:58 20295 8192000000 9962054512 10363265024 1022405184 11.095 95 25 50 1 1051182432 23285056 5492192
05:19:21 3896 8192000000 9952903424 10335961088 1031089344 11.179 99 43 84 3 1058959152 18524976 9344832
05:20:25 7417 8192000000 9932905200 10319974400 995231120 10.832 97 30 59 3 1016540768 14726704 6582944
05:21:33 62589 8192000000 9953932640 10337730560 1028035520 11.150 100 32 63 1 1065000160 30583904 6380736
05:23:36 4067 8192000000 9979285776 10373513216 1044698160 11.310 100 49 97 3 1086266464 30872592 10695712
05:24:42 6908 8192000000 9971716720 10363752448 1060873696 11.465 100 37 74 3 1085693600 16977616 7842288
05:25:50 11803 8192000000 9986769824 10380013568 1068767088 11.540 100 38 75 1 1103015600 26059568 8188944
05:27:04 16955 8192000000 10013989744 10414866432 1089768880 11.740 100 43 86 0 1113998960 15017056 9213024
05:28:27 25128 8192000000 10064768800 10472140800 1109866832 11.931 100 43 86 3 1171286960 53296864 8123264
05:30:02 3827 8192000000 10095339648 10495078400 1126640704 12.090 100 70 138 2 1201242192 59477664 15123824
05:31:13 17805 8192000000 10125557952 10531782656 1144535824 12.258 100 55 110 2 1229301776 72552272 12213680
05:32:36 10319 8192000000 10174766528 10586292224 1151711792 12.326 100 50 100 1 1287801760 125329248 10760720
05:33:51 4729 8192000000 10155042208 10568376320 1162572864 12.427 100 65 125 2 1271592016 95528592 13490560
05:34:58 1269 8192000000 10216397680 10615865344 1163313488 12.434 100 75 150 1 1314716640 135585088 15818064
05:36:04 2195 8192000000 10230686784 10632605696 1158040800 12.385 99 70 139 2 1321335248 147219424 16075024
05:37:09 1439 8192000000 10271313888 10673418240 1170871856 12.505 100 84 167 0 1368384128 179337824 18174448
05:38:15 2316 8192000000 10287758944 10709286912 1145275968 12.265 98 73 144 1 1373990704 212440048 16274688
05:39:21 6937 8192000000 10274633696 10680754176 1160399568 12.407 100 67 135 0 1376623952 200950352 15274032
05:40:39 3569 8192000000 10297656544 10704519168 1143436800 12.248 99 63 126 0 1389190912 231030448 14723664
05:41:57 4726 8192000000 10340849792 10760142848 1165420640 12.454 100 70 138 4 1445789440 264507136 15861664
05:43:11 2263 8192000000 10327514880 10744311808 1136562128 12.183 100 50 101 1 1430981312 281526624 12892560
05:44:20 2533 8192000000 10310894608 10730508288 1114790736 11.978 100 77 153 3 1411351056 279128336 17431984
05:45:29 3714 8192000000 10281979696 10694180864 1120295760 12.030 100 62 125 2 1381769584 246597728 14876096
05:46:37 5051 8192000000 10320504032 10754744320 1139256416 12.209 100 60 118 1 1415280976 261320944 14703616
05:47:46 2740 8192000000 10350346656 10753101824 1148439504 12.295 100 82 162 5 1446522720 278098656 19984560
05:48:52 1330 8192000000 10411650672 10820907008 1165631216 12.456 100 91 175 2 1515810640 328919312 21260112
05:49:55 7297 8192000000 10414908768 10840719360 1150990848 12.319 100 44 88 1 1508859264 346610112 11258304
05:51:15 18500 8192000000 10326281456 10768416768 1129047120 12.112 99 33 66 1 1490021616 351786512 9187984
05:52:39 16733 8192000000 10335532688 10784587776 1132193584 12.142 100 32 64 3 1437682144 296798640 8689920
05:53:58 25422 8192000000 10231646688 10780508160 1124861520 12.073 100 19 38 2 1407946384 278507136 4577728
05:55:28 84007 8192000000 10111783600 10540457984 1096472320 11.804 98 34 65 1 1188748528 85012736 7263472
05:58:06 81558 8192000000 10061455104 10492968960 1094992256 11.790 97 23 47 1 1140004512 41051296 3960960
06:00:30 105736 8192000000 10051433184 10490150912 1082421120 11.671 96 13 26 1 1140710224 55680896 2608208
06:03:29 89517 8192000000 10116238704 10578579456 1126253296 12.086 100 28 57 2 1207836320 75209168 6373856
06:06:26 47763 8192000000 10070626320 10503401472 1126118592 12.085 100 44 89 0 1165292080 28756256 10417232
06:08:17 108681 8192000000 10105386544 10534895616 1127076928 12.094 99 29 58 1 1195171440 61869792 6224720
06:11:09 82096 8192000000 10098754304 10545610752 1137484448 12.192 100 17 34 2 1214647888 72721920 4441520
06:13:35 80970 8192000000 10161442176 10619256832 1152147792 12.330 100 20 40 0 1258077312 101844560 4084960
06:16:10 70512 8192000000 10115021600 10621169664 1147766224 12.289 100 7 14 0 1227017088 77433824 1817040
06:18:26 80728 8192000000 10079323856 10549473280 1123766464 12.063 98 17 34 0 1182846592 55231920 3848208
06:20:55 61261 8192000000 10125926992 10587742208 1126471712 12.088 98 24 48 1 1193553056 61956480 5124864
06:23:04 47942 8192000000 10072213472 10522341376 1095549216 11.795 96 16 32 0 1122072496 23104592 3418688
06:24:58 34040 8192000000 10086493072 10523344896 1126097360 12.085 99 34 68 1 1166348400 33493392 6757648
06:27:54 58981 8192000000 10063202400 10532118528 1096310320 11.803 97 7 14 0 1141549376 43917120 1321936
06:29:54 112518 8192000000 10084290608 10539212800 1118952256 12.017 99 27 54 0 1153043424 28227552 5863616
06:33:22 69669 8192000000 10064651184 10537820160 1093282192 11.774 96 14 28 1 1144785088 48509600 2993296
06:35:32 51579 8192000000 10084903040 10587770880 1110054512 11.933 98 11 22 1 1154175088 41886016 2234560
06:37:24 95158 8192000000 10081328608 10570395648 1113350352 11.964 98 11 22 0 1175974848 60378384 2246112
06:40:05 90986 8192000000 10094798960 10557329408 1098640224 11.825 97 9 18 0 1175068240 73916592 2511424
06:42:37 68591 8192000000 10219084016 10656149504 1123334016 12.058 98 31 62 1 1257943024 127931200 6677808
06:44:56 47814 8192000000 10148035920 10668982272 1117058304 11.999 99 16 32 0 1250890224 130033936 3797984
06:46:44 78297 8192000000 10093517056 10578051072 1112117424 11.952 99 11 22 0 1179739680 65363824 2258432
06:49:08 20054 8192000000 10071623408 10491797504 1085231872 11.697 97 34 68 0 1122259616 29969296 7058448
06:50:32 94204 8192000000 10051759968 10504937472 1098058064 11.819 98 30 60 3 1153352976 48637856 6657056
06:53:16 41380 8192000000 10053858608 10532114432 1111609584 11.948 99 4 8 1 1158684048 46365680 708784
06:55:34 69502 8192000000 10053945488 10536734720 1081833968 11.665 96 8 16 0 1131640736 48123024 1683744
06:57:45 58210 8192000000 10056203920 10495393792 1114346688 11.974 100 25 50 0 1163988576 44106176 5535712
06:59:43 49532 8192000000 10041245664 10492289024 1103169808 11.868 98 21 42 0 1154125408 46645664 4309936
07:01:36 24672 8192000000 10081488880 10513678336 1122692944 12.052 100 26 52 2 1161631360 33818688 5119728
07:03:04 74194 8192000000 10063479872 10508992512 1112586016 11.957 98 24 44 2 1175912960 58288112 5038832
07:05:21 46137 8192000000 10104306992 10553733120 1145176304 12.264 100 39 78 1 1225015440 72353248 7485888
07:07:14 79757 8192000000 10100753408 10577039360 1130213296 12.123 99 15 30 0 1211603584 78292096 3098192
07:09:36 102612 8192000000 10071955328 10538930176 1140528320 12.221 100 10 21 1 1175592784 32786544 2277920
07:13:13 49936 8192000000 10049446384 10511122432 1140134176 12.217 100 5 10 0 1168985152 27928320 922656
07:15:04 38934 8192000000 10076849216 10525941760 1142707648 12.241 100 17 34 3 1162262784 16195968 3359168
07:16:44 69367 8192000000 10088414112 10553421824 1125961984 12.083 98 21 40 2 1180151344 49720864 4468496
07:19:16 100204 8192000000 10100622240 10648899584 1149549376 12.305 100 18 36 3 1271358416 118067424 3741616
07:21:56 72650 8192000000 10054049824 10521063424 1083058416 11.677 95 14 28 2 1132150816 46788320 2304080
07:24:09 49705 8192000000 10108933984 10555383808 1120071872 12.028 98 23 47 3 1193893392 69197600 4623920
07:26:03 21060 8192000000 10119148944 10554257408 1132737344 12.147 99 35 70 2 1213020656 73369584 6913728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2020 07:27:58 to 07-12-2020 07:28:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2020 07:28:14 to 07-12-2020 07:28:16.

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          230576
MON$OLDEST_ACTIVE               233643
MON$OLDEST_SNAPSHOT             233643
MON$NEXT_TRANSACTION            233643
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                       2094784
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             793
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
:86: 
:87: mscs-test-161.budzdorov.ru    Mon Dec  7 07:27:11 2020
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Mon Dec  7 07:27:12 2020
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:93: 
--- end of diff output ---

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

07.12.20 07:28:17. 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-07 07:28:20 No severe PSQL-related problems occured
07.12.20 07:28:21. End of report.