Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2020 10:29
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16750492 kB
MemAvailable 31641316 kB
Buffers 0 kB
Cached 14935188 kB
SwapCached 5652 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-10-11 10:26:49
DTS_END                         2020-10-11 10:26:49.6060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/54710
TRN_ID                          244544
ATT_ID                          630
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: 9299.24 18110 1115909
customer order: creation 179.47 387 21536
customer order: refuse 15.74 349 1889
order to supplier: creation 48.94 1253 5873
order to supplier: removal 3.65 110632 438
invoice (draft): creation 47.53 3336 5703
invoice (draft): removal 52.17 985 6260
invoice accept: apply 42.21 15688 5065
invoice accept: cancel 47.38 959 5686
customer reserve: creation 3889.10 154 466692
customer reserve: removal 4671.18 68 560542
realization accept: apply 87.94 157 10553
realization accept: cancel 80.17 114 9620
payment from customer: creation 60.90 110 7308
payment from customer: removal 6.21 62 745
payment to supplier: creation 51.79 116 6215
payment to supplier: removal 3.57 81 428
service: total inventory turnovers 4.18 2792 501
service: total monetary turnovers 6.25 486 750
service: aggregate perf. data 0.84 17142 101
service: refresh index statistics 0.03 207332 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-10-2020 10:29:42 to 11-10-2020 10:29:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1682
TEST_TIME 2 2139
TEST_TIME 3 3395
TEST_TIME 4 4102
TEST_TIME 5 4257
TEST_TIME 6 4437
TEST_TIME 7 4608
TEST_TIME 8 4583
TEST_TIME 9 4728
TEST_TIME 10 4919
TEST_TIME 11 5118
TEST_TIME 12 5754
TEST_TIME 13 5821
TEST_TIME 14 6472
TEST_TIME 15 7056
TEST_TIME 16 7402
TEST_TIME 17 7419
TEST_TIME 18 7678
TEST_TIME 19 7701
TEST_TIME 20 8051
TEST_TIME 21 8715
TEST_TIME 22 9049
TEST_TIME 23 9609
TEST_TIME 24 9871
TEST_TIME 25 10365
TEST_TIME 26 10719
TEST_TIME 27 11054
TEST_TIME 28 10964
TEST_TIME 29 10986
TEST_TIME 30 10770
TEST_TIME 31 10646
TEST_TIME 32 10655
TEST_TIME 33 10775
TEST_TIME 34 10703
TEST_TIME 35 10605
TEST_TIME 36 10590
TEST_TIME 37 10826
TEST_TIME 38 10682
TEST_TIME 39 10467
TEST_TIME 40 10218
TEST_TIME 41 10263
TEST_TIME 42 10166
TEST_TIME 43 10056
TEST_TIME 44 10047
TEST_TIME 45 10104
TEST_TIME 46 10046
TEST_TIME 47 9908
TEST_TIME 48 9849
TEST_TIME 49 9842
TEST_TIME 50 9737
TEST_TIME 51 9752
TEST_TIME 52 9727
TEST_TIME 53 9682
TEST_TIME 54 9685
TEST_TIME 55 9663
TEST_TIME 56 9660
TEST_TIME 57 9710
TEST_TIME 58 9730
TEST_TIME 59 9740
TEST_TIME 60 9754
TEST_TIME 61 9777
TEST_TIME 62 9829
TEST_TIME 63 9754
TEST_TIME 64 9791
TEST_TIME 65 9868
TEST_TIME 66 9780
TEST_TIME 67 9715
TEST_TIME 68 9646
TEST_TIME 69 9562
TEST_TIME 70 9473
TEST_TIME 71 9386
TEST_TIME 72 9436
TEST_TIME 73 9424
TEST_TIME 74 9384
TEST_TIME 75 9379
TEST_TIME 76 9368
TEST_TIME 77 9341
TEST_TIME 78 9358
TEST_TIME 79 9515
TEST_TIME 80 9523
TEST_TIME 81 9484
TEST_TIME 82 9440
TEST_TIME 83 9448
TEST_TIME 84 9375
TEST_TIME 85 9384
TEST_TIME 86 9388
TEST_TIME 87 9353
TEST_TIME 88 9310
TEST_TIME 89 9345
TEST_TIME 90 9313
TEST_TIME 91 9305
TEST_TIME 92 9291
TEST_TIME 93 9318
TEST_TIME 94 9351
TEST_TIME 95 9359
TEST_TIME 96 9410
TEST_TIME 97 9419
TEST_TIME 98 9452
TEST_TIME 99 9465
TEST_TIME 100 9471
TEST_TIME 101 9496
TEST_TIME 102 9416
TEST_TIME 103 9342
TEST_TIME 104 9353
TEST_TIME 105 9289
TEST_TIME 106 9244
TEST_TIME 107 9231
TEST_TIME 108 9199
TEST_TIME 109 9185
TEST_TIME 110 9198
TEST_TIME 111 9181
TEST_TIME 112 9169
TEST_TIME 113 9160
TEST_TIME 114 9214
TEST_TIME 115 9197
TEST_TIME 116 9206
TEST_TIME 117 9232
TEST_TIME 118 9248
TEST_TIME 119 9302
TEST_TIME 120 9301

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-10-2020 10:29:46 to 11-10-2020 10:29:50.

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 1548819 1548819 0 0.00 0 22145 6 0 0
doc_list_biud 1548794 1548794 0 0.00 0 89659 31 0 0
sp_add_invoice_to_stock 5261 5065 196 3.72 35 201920 15689 0 196
sp_cancel_adding_invoice 5686 5686 0 0.00 4 89688 960 0 0
sp_cancel_client_order 1889 1889 0 0.00 4 21178 350 0 0
sp_cancel_customer_reserve 560542 560542 0 0.00 1 26833 69 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 3 3588 62 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 6886 82 0 0
sp_cancel_supplier_invoice 6276 6260 16 0.25 3 62449 985 0 16
sp_cancel_supplier_order 438 438 0 0.00 12 1026292 110633 0 0
sp_cancel_write_off 9620 9620 0 0.00 1 8936 115 0 0
sp_client_order 21536 21536 0 0.00 4 39149 388 0 0
sp_customer_reserve 466692 466692 0 0.00 0 48793 155 0 0
sp_fill_shopping_cart 33110 33110 0 0.00 1 42731 46 0 0
sp_fill_shopping_cart_clo_res 466691 466691 0 0.00 0 30742 17 0 0
sp_get_clo_for_invoice 5063 5063 0 0.00 0 39863 359 0 0
sp_kill_cost_storno 16479 16479 0 0.00 0 5684 53 0 0
sp_kill_qstorno_ret_qs2qd 1137402 1137402 0 0.00 0 62438 38 0 0
sp_kill_qty_storno 600037 600037 0 0.00 0 89658 80 0 0
sp_lock_selected_doc 21803 21803 0 0.00 0 10743 7 0 0
sp_make_cost_storno 29139 29139 0 0.00 0 22140 55 0 0
sp_multiply_rows_for_pdistr 29123 29123 0 0.00 0 12555 27 0 0
sp_multiply_rows_for_qdistr 21536 21536 0 0.00 0 39132 212 0 0
sp_pay_from_customer 7308 7308 0 0.00 4 19134 110 0 0
sp_pay_to_supplier 6215 6215 0 0.00 5 14937 116 0 0
sp_payment_common 13512 13512 0 0.00 1 11604 20 0 0
sp_qd_handle_on_cancel_clo 1889 1889 0 0.00 0 21170 317 0 0
sp_qd_handle_on_invoice_upd_sts 10742 10742 0 0.00 0 89657 376 0 0
sp_qd_handle_on_reserve_upd_sts 10546 10546 0 0.00 0 13260 42 0 0
sp_reserve_write_off 10553 10553 0 0.00 7 22159 157 0 0
sp_supplier_invoice 5703 5703 0 0.00 7 141284 3336 0 0
sp_supplier_order 5873 5873 0 0.00 6 27923 1253 0 0
srv_aggregate_perf_data 101 101 0 0.00 2748 392750 17143 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 979 161706 44321 0 0
srv_find_qd_qs_mism 1067073 1067073 0 0.00 0 7929 5 0 0
srv_make_invnt_saldo 501 501 0 0.00 30 97717 2793 0 0
srv_make_money_saldo 750 750 0 0.00 1 17134 487 0 0
srv_recalc_idx_stat 4 4 0 0.00 108585 285189 207332 0 0
t$perf-abend:sp_add_invoice_to_stock 196 196 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 9457 125 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 2744 74 0 0
t$perf-norm:sp_cancel_client_order 1889 1889 0 0.00 0 4978 13 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 2680 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 1103 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1089 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 3649 21 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 16845 1455 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 1347 7 0 0
t$perf-norm:sp_client_order 21536 21536 0 0.00 0 6919 14 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 6987 13 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 2722 7 0 0
t$perf-norm:sp_pay_to_supplier 6215 6215 0 0.00 0 2402 5 0 0
t$perf-norm:sp_reserve_write_off 10553 10553 0 0.00 0 5334 7 0 0
t$perf-norm:sp_supplier_invoice 5703 5703 0 0.00 0 3539 11 0 0
t$perf-norm:sp_supplier_order 5873 5873 0 0.00 0 7808 12 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 176 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2419 42 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 627 6 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 5098 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10502 10110 392 3.73 0 6078 19 0 392
v_all_customers 38902 38902 0 0.00 0 153 1 0 0
v_all_suppliers 31962 31962 0 0.00 0 153 1 0 0
v_all_wares 43072 43072 0 0.00 0 178 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2738 11 0 0
v_cancel_client_order 3778 3778 0 0.00 0 4974 5 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 1454 5 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 4354 8 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4685 15 0 0
v_cancel_supplier_invoice 880 848 32 3.63 0 5956 18 0 32
v_cancel_supplier_order 876 876 0 0.00 0 352 4 0 0
v_cancel_write_off 2200 2200 0 0.00 0 6565 19 0 0
v_max_id_clo_ord 5871 5871 0 0.00 0 429 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 6348 12 0 0
v_max_id_ord_sup 5703 5703 0 0.00 0 5665 6 0 0
v_max_non_paid_invoice 1799 1799 0 0.00 0 609 2 0 0
v_max_non_paid_realizn 3949 3949 0 0.00 0 727 2 0 0
v_min_id_clo_ord 5871 5871 0 0.00 0 13364 9 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2884 9 0 0
v_min_id_ord_sup 5703 5703 0 0.00 0 10130 14 0 0
v_min_non_paid_invoice 6206 6206 0 0.00 0 3866 11 0 0
v_min_non_paid_realizn 7305 7305 0 0.00 0 6187 14 0 0
v_our_firm 10882 10882 0 0.00 0 13 1 0 0
v_reserve_write_off 21092 21092 0 0.00 0 6556 5 0 0
x_lock_depdocs_on_canc_invoice 11522 11522 0 0.00 0 17506 96 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 7528 283 0 0
x_make_qstorno_1000_1200 5873 5873 0 0.00 2 27910 1186 0 0
x_make_qstorno_1200_2000 5703 5703 0 0.00 3 140440 3145 0 0
x_make_qstorno_2100_3300 466692 466692 0 0.00 0 48792 136 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-10-2020 10:29:50 to 11-10-2020 10:29:55.

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
07:56:52 1 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:53 23171 8192000000 9702391888 9994493952 781278128 8.706 100 86 172 1 810275312 7979360 21017824
07:59:22 22405 8192000000 9747354320 10051325952 823018992 9.129 100 84 168 0 854643664 11641712 19982960
08:00:54 19630 8192000000 9798674704 10114334720 858905968 9.489 100 86 172 1 903634592 24615440 20113184
08:02:18 18469 8192000000 9848547840 10180706304 898468064 9.883 100 75 150 0 952778896 38062608 16248224
08:03:42 5711 8192000000 9912614832 10251948032 954247520 10.433 100 82 163 0 1020266256 49026400 16992336
08:04:50 5054 8192000000 9962639344 10317225984 997830128 10.857 100 83 166 0 1073334528 58127088 17377312
08:05:58 3267 8192000000 10015519744 10374639616 1023464288 11.105 100 88 176 0 1121922416 80929360 17528768
08:07:03 1874 8192000000 10029288880 10393948160 1043635696 11.300 100 91 182 0 1147357504 85547232 18174576
08:08:07 1118 8192000000 10041453312 10413883392 1062915488 11.484 100 90 177 0 1154471952 73361088 18195376
08:09:09 22950 8192000000 9961317504 10361053184 1062659824 11.482 100 30 58 2 1091654672 22011312 6983536
08:10:36 3794 8192000000 9976176464 10368290816 1070232400 11.554 100 38 77 2 1093946192 14843456 8870336
08:11:41 9053 8192000000 9988384832 10376658944 1076765328 11.617 100 26 53 0 1107739968 25279536 5695104
08:12:51 65210 8192000000 10001892816 10412314624 1081006432 11.657 100 40 80 4 1104737168 15384816 8345920
08:14:56 4625 8192000000 9997479520 10395832320 1090689296 11.749 100 49 98 4 1110704832 8775088 11240448
08:16:02 8990 8192000000 9996516912 10398580736 1067014560 11.524 98 32 63 1 1103109312 29757072 6337680
08:17:15 13065 8192000000 9973549680 10366640128 1043749888 11.301 97 31 62 2 1072623808 22003136 6870784
08:18:33 2358 8192000000 9988582480 10362392576 1040823600 11.273 98 80 162 3 1081190400 22602016 17764784
08:19:39 8495 8192000000 9933777248 10327404544 986892224 10.751 95 26 50 3 1021242496 28080528 6269744
08:20:48 7275 8192000000 9921047392 10299736064 974994128 10.635 96 32 63 1 1014395200 31697136 7703936
08:21:56 8312 8192000000 9926302608 10313728000 1016848528 11.042 99 34 67 3 1045935808 20896096 8191184
08:23:05 20330 8192000000 9937011888 10338324480 1023413600 11.105 99 32 63 2 1060250032 29772128 7064304
08:24:27 8374 8192000000 9967409600 10364588032 1053705824 11.396 100 54 108 0 1081625376 15405088 12514464
08:25:38 5490 8192000000 9983488496 10374336512 1063687824 11.492 100 27 55 1 1100995072 31509216 5798032
08:26:44 18865 8192000000 9995486896 10386100224 1086729936 11.712 100 44 87 4 1111643984 16157760 8756288
08:28:18 35694 8192000000 10058740400 10459467776 1107083184 11.905 100 44 88 2 1166439888 50619600 8737104
08:30:06 3496 8192000000 10123863424 10521554944 1124521968 12.070 100 63 127 1 1231117472 93450096 13145408
08:31:18 16560 8192000000 10128958704 10537476096 1141079936 12.226 100 39 78 2 1242729728 93234064 8415728
08:32:41 24823 8192000000 10182745248 10600493056 1156207424 12.368 99 52 104 1 1289642544 121810272 11624848
08:34:13 12524 8192000000 10255789184 10658271232 1174364144 12.538 100 74 148 1 1352985760 161434848 17186768
08:35:35 14822 8192000000 10297094960 10711732224 1176472672 12.557 100 54 108 0 1397499840 208498176 12528992
08:37:02 2915 8192000000 10343136000 10750046208 1177952128 12.571 100 71 141 0 1438717024 244448800 16316096
08:38:12 970 8192000000 10353757312 10776350720 1185897152 12.645 100 91 182 0 1451729280 244833872 20998256
08:39:18 3782 8192000000 10388520240 10800443392 1190476208 12.688 100 71 138 2 1490884432 283387456 17020768
08:40:26 1593 8192000000 10412806784 10831851520 1167297616 12.472 100 84 169 1 1516180192 328298256 20584320
08:41:37 2726 8192000000 10389057856 10794561536 1184858784 12.635 100 68 133 1 1481285664 280790848 15636032
08:42:46 4937 8192000000 10383228768 10806910976 1177007760 12.562 99 47 93 1 1481174208 292893760 11272688
08:43:58 8919 8192000000 10423023392 10846191616 1160268112 12.406 99 54 107 1 1516813584 343077312 13468160
08:45:21 21178 8192000000 10413635712 10833309696 1135072864 12.169 98 36 72 2 1492633856 347743808 9817184
08:46:46 3974 8192000000 10431526032 10862354432 1143749760 12.251 100 78 157 0 1545779040 382350672 19678608
08:47:54 9192 8192000000 10349773456 10798862336 1121760672 12.044 99 36 72 1 1439115152 308302592 9051888
08:49:04 10799 8192000000 10269897936 10755080192 1103148512 11.868 98 26 52 1 1413341072 303319680 6872880
08:50:17 15912 8192000000 10260038896 10688483328 1111921808 11.951 100 24 48 2 1355591936 237702272 5967856
08:51:34 20823 8192000000 10178215616 10658586624 1087615408 11.720 98 21 42 0 1294291904 201173872 5502624
08:52:56 27328 8192000000 10097433824 10642075648 1106960336 11.904 100 18 36 2 1239446464 128588320 3897808
08:54:28 60249 8192000000 10053737824 10476453888 1080610256 11.653 98 18 36 0 1140226336 55783536 3832544
08:56:29 61661 8192000000 10067239360 10493673472 1094780064 11.788 99 37 69 2 1126118128 24443328 6894736
08:58:31 41083 8192000000 10086516688 10518794240 1097968016 11.818 98 19 37 1 1159642240 57588128 4086096
09:00:13 68209 8192000000 10099440032 10532896768 1132650544 12.146 100 12 24 2 1176861088 41922576 2287968
09:02:40 37384 8192000000 10116346544 10574303232 1125793888 12.082 99 6 12 0 1218410464 91022704 1593872
09:04:55 161706 8192000000 10142339152 10570055680 1146631472 12.278 100 23 46 1 1212598032 60665520 5301040
09:08:40 44396 8192000000 10102717696 10542485504 1142601376 12.240 100 33 66 1 1204423712 54019008 7803328
09:10:26 54008 8192000000 10108940368 10590990336 1150021184 12.310 100 16 32 2 1224385072 70707904 3655984
09:12:21 49257 8192000000 10100328768 10558758912 1151989680 12.328 100 18 36 1 1193161680 37523200 3648800
09:14:10 40950 8192000000 10128433248 10573361152 1156413536 12.370 100 15 30 1 1225127488 65101024 3612928
09:15:52 118429 8192000000 10097314016 10597978112 1137353440 12.191 99 16 32 1 1204624320 64038720 3232160
09:18:53 28314 8192000000 10070245264 10518323200 1123188480 12.057 98 22 44 2 1148907744 21434064 4285200
09:20:27 27553 8192000000 10069606704 10506575872 1086048448 11.705 95 25 50 0 1114027600 23224832 4754320
09:21:56 32366 8192000000 10089683856 10523947008 1129492720 12.117 99 25 50 2 1166402096 31981360 4928016
09:23:31 40027 8192000000 10105028704 10555117568 1126425696 12.088 99 15 29 1 1180962112 51654304 2882112
09:25:26 22083 8192000000 10069593392 10510389248 1111399968 11.946 99 42 83 2 1155724640 35850400 8474272
09:26:51 67355 8192000000 10081998144 10540613632 1046209824 11.324 93 16 32 1 1150717296 101018160 3489312
09:28:58 25072 8192000000 10084224896 10502352896 1113761456 11.968 100 40 80 0 1144616080 22982384 7872240
09:30:24 68916 8192000000 10043155552 10490449920 1078938928 11.637 97 22 44 1 1156972320 73986208 4047184
09:32:33 74523 8192000000 10019170896 10481754112 1070595872 11.558 97 15 30 4 1127538224 53442944 3499408
09:35:05 83163 8192000000 10021190832 10470268928 1057512032 11.433 96 10 20 1 1097430080 37514576 2403472
09:38:52 48377 8192000000 10036496368 10490302464 1099181968 11.830 100 18 36 0 1155608256 52526448 3899840
09:40:43 24453 8192000000 10085962544 10515226624 1108883536 11.922 100 37 74 1 1175995712 59263248 7848928
09:42:07 39451 8192000000 10132534864 10566119424 1119384176 12.021 99 36 70 2 1199453296 72440736 7628384
09:43:55 73776 8192000000 10162411776 10597629952 1123238016 12.058 99 20 40 1 1262135632 134089776 4807840
09:46:09 53763 8192000000 10067649184 10494263296 1068831408 11.541 95 36 72 2 1118344496 42018624 7494464
09:48:04 44352 8192000000 10138733136 10549563392 1125296640 12.077 100 55 109 1 1215689168 79566528 10826000
09:49:49 88728 8192000000 10102391984 10580385792 1129033760 12.112 100 33 66 1 1222711616 86937360 6740496
09:52:23 59770 8192000000 10049479648 10540158976 1106280528 11.897 98 3 6 0 1147587968 40730096 577344
09:54:23 80874 8192000000 10088718272 10556657664 1130684576 12.128 100 22 44 2 1191957296 56570304 4702416
09:56:45 49040 8192000000 10098117040 10552045568 1120603344 12.033 98 28 55 1 1170840672 44447824 5789504
09:58:35 52165 8192000000 10114005104 10582233088 1135661024 12.175 99 22 44 0 1207536560 67172416 4703120
10:00:32 56904 8192000000 10109826000 10547576832 1137557456 12.193 99 25 51 1 1190125616 47414288 5153872
10:02:40 39853 8192000000 10086776720 10545455104 1115369088 11.983 98 18 35 2 1160492144 41151040 3972016
10:04:24 146216 8192000000 10050989104 10505920512 1032159824 11.189 91 13 26 1 1051665776 17090672 2415280
10:08:01 124018 8192000000 10073154288 10532433920 1098299168 11.822 97 22 44 1 1142345504 39268352 4777984
10:11:16 52594 8192000000 10036510608 10502291456 1089292000 11.736 97 6 12 0 1120194144 29751440 1150704
10:13:29 74811 8192000000 10036759072 10478010368 1086304560 11.708 97 18 36 0 1129761824 39934336 3522928
10:15:44 58872 8192000000 10035490368 10482864128 1093965760 11.780 97 30 60 1 1159360288 58537072 6857456
10:17:43 22484 8192000000 10076214240 10504265728 1109603856 11.929 98 58 116 2 1154073552 32768368 11701328
10:19:13 31039 8192000000 10082924336 10527629312 1090905232 11.751 97 31 62 0 1163382272 65623968 6853072
10:20:49 74429 8192000000 10111300304 10569850880 1083589920 11.682 96 19 37 3 1148138880 60502272 4046688
10:23:58 80263 8192000000 10111712192 10593886208 1118707760 12.015 99 26 52 1 1201351744 76783280 5860704
10:26:24 36022 8192000000 9363842416 9713627136 483185568 5.569 43 2 4 0 523946240 40378448 382224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2020 10:29:56 to 11-10-2020 10:30:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 196 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2020 10:30:12 to 11-10-2020 10:30:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          244543
MON$OLDEST_ACTIVE               245550
MON$OLDEST_SNAPSHOT             245550
MON$NEXT_TRANSACTION            245550
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                       2124864
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             805
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 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.2225 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Sun Oct 11 10:27:39 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sun Oct 11 10:27:40 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

11.10.20 10:30:15. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-11 10:30:18 No severe PSQL-related problems occured
11.10.20 10:30:19. End of report.