Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2020 13:29
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 16476120 kB
MemAvailable 31407120 kB
Buffers 0 kB
Cached 15125920 kB
SwapCached 3700 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-24 13:27:35
DTS_END                         2020-12-24 13:26:55.5210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37768
TRN_ID                          243239
ATT_ID                          259
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: 9469.32 17633 1136318
customer order: creation 184.68 353 22161
customer order: refuse 15.75 310 1890
order to supplier: creation 47.87 1481 5744
order to supplier: removal 3.53 105139 423
invoice (draft): creation 48.23 3285 5787
invoice (draft): removal 52.19 887 6263
invoice accept: apply 44.07 16062 5288
invoice accept: cancel 47.38 912 5686
customer reserve: creation 4054.40 158 486528
customer reserve: removal 4666.02 63 559923
realization accept: apply 88.80 160 10656
realization accept: cancel 79.19 128 9503
payment from customer: creation 64.63 100 7755
payment from customer: removal 5.72 69 686
payment to supplier: creation 52.01 107 6241
payment to supplier: removal 3.57 66 428
service: total inventory turnovers 4.02 3281 482
service: total monetary turnovers 6.33 551 760
service: aggregate perf. data 0.92 16490 110
service: refresh index statistics 0.03 203048 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2020 13:29:30 to 24-12-2020 13:29:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3569
TEST_TIME 2 4322
TEST_TIME 3 4613
TEST_TIME 4 5839
TEST_TIME 5 5940
TEST_TIME 6 5823
TEST_TIME 7 6050
TEST_TIME 8 6179
TEST_TIME 9 6791
TEST_TIME 10 7258
TEST_TIME 11 7693
TEST_TIME 12 7728
TEST_TIME 13 7662
TEST_TIME 14 7538
TEST_TIME 15 7803
TEST_TIME 16 8108
TEST_TIME 17 8529
TEST_TIME 18 8472
TEST_TIME 19 8878
TEST_TIME 20 8840
TEST_TIME 21 9259
TEST_TIME 22 9160
TEST_TIME 23 9224
TEST_TIME 24 9216
TEST_TIME 25 9262
TEST_TIME 26 9580
TEST_TIME 27 9904
TEST_TIME 28 10136
TEST_TIME 29 11722
TEST_TIME 30 11706
TEST_TIME 31 11488
TEST_TIME 32 11372
TEST_TIME 33 11323
TEST_TIME 34 11168
TEST_TIME 35 11115
TEST_TIME 36 11003
TEST_TIME 37 11175
TEST_TIME 38 11105
TEST_TIME 39 11114
TEST_TIME 40 10876
TEST_TIME 41 10747
TEST_TIME 42 10534
TEST_TIME 43 10573
TEST_TIME 44 10460
TEST_TIME 45 10379
TEST_TIME 46 10361
TEST_TIME 47 10375
TEST_TIME 48 10344
TEST_TIME 49 10261
TEST_TIME 50 10217
TEST_TIME 51 10099
TEST_TIME 52 10123
TEST_TIME 53 10109
TEST_TIME 54 10038
TEST_TIME 55 10023
TEST_TIME 56 10187
TEST_TIME 57 10154
TEST_TIME 58 10344
TEST_TIME 59 10259
TEST_TIME 60 10366
TEST_TIME 61 10327
TEST_TIME 62 10445
TEST_TIME 63 10379
TEST_TIME 64 10256
TEST_TIME 65 10140
TEST_TIME 66 10094
TEST_TIME 67 10019
TEST_TIME 68 9936
TEST_TIME 69 9904
TEST_TIME 70 9808
TEST_TIME 71 9753
TEST_TIME 72 9701
TEST_TIME 73 9671
TEST_TIME 74 9651
TEST_TIME 75 9640
TEST_TIME 76 9611
TEST_TIME 77 9678
TEST_TIME 78 9735
TEST_TIME 79 9853
TEST_TIME 80 9813
TEST_TIME 81 9869
TEST_TIME 82 9922
TEST_TIME 83 9858
TEST_TIME 84 9774
TEST_TIME 85 9752
TEST_TIME 86 9674
TEST_TIME 87 9625
TEST_TIME 88 9642
TEST_TIME 89 9621
TEST_TIME 90 9573
TEST_TIME 91 9629
TEST_TIME 92 9624
TEST_TIME 93 9618
TEST_TIME 94 9631
TEST_TIME 95 9596
TEST_TIME 96 9597
TEST_TIME 97 9703
TEST_TIME 98 9701
TEST_TIME 99 9693
TEST_TIME 100 9786
TEST_TIME 101 9770
TEST_TIME 102 9810
TEST_TIME 103 9718
TEST_TIME 104 9647
TEST_TIME 105 9624
TEST_TIME 106 9576
TEST_TIME 107 9557
TEST_TIME 108 9536
TEST_TIME 109 9508
TEST_TIME 110 9539
TEST_TIME 111 9504
TEST_TIME 112 9520
TEST_TIME 113 9486
TEST_TIME 114 9477
TEST_TIME 115 9498
TEST_TIME 116 9468
TEST_TIME 117 9570
TEST_TIME 118 9559
TEST_TIME 119 9526
TEST_TIME 120 9473
TEST_TIME 121 9395

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-12-2020 13:29:33 to 24-12-2020 13:29:37.

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 1586342 1586342 0 0.00 0 15956 6 0 0
doc_list_biud 1586342 1586342 0 0.00 0 37464 29 0 0
sp_add_invoice_to_stock 5463 5288 175 3.20 35 138346 16063 0 175
sp_cancel_adding_invoice 5686 5686 0 0.00 5 102657 913 0 0
sp_cancel_client_order 1890 1890 0 0.00 5 7938 310 0 0
sp_cancel_customer_reserve 559923 559923 0 0.00 1 27747 64 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 5166 70 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 1677 67 0 0
sp_cancel_supplier_invoice 6269 6263 6 0.09 2 30204 888 0 6
sp_cancel_supplier_order 423 423 0 0.00 11 1077775 105139 0 0
sp_cancel_write_off 9503 9503 0 0.00 1 11795 129 0 0
sp_client_order 22161 22161 0 0.00 4 56343 354 0 0
sp_customer_reserve 486528 486528 0 0.00 0 55221 158 0 0
sp_fill_shopping_cart 33690 33690 0 0.00 1 20840 44 0 0
sp_fill_shopping_cart_clo_res 486522 486522 0 0.00 0 16352 14 0 0
sp_get_clo_for_invoice 5280 5280 0 0.00 0 32132 309 0 0
sp_kill_cost_storno 16303 16303 0 0.00 0 8780 59 0 0
sp_kill_qstorno_ret_qs2qd 1136035 1136035 0 0.00 0 30196 35 0 0
sp_kill_qty_storno 599624 599624 0 0.00 0 37464 75 0 0
sp_lock_selected_doc 22067 22067 0 0.00 0 8714 5 0 0
sp_make_cost_storno 29932 29932 0 0.00 0 10199 49 0 0
sp_multiply_rows_for_pdistr 29932 29932 0 0.00 0 7957 29 0 0
sp_multiply_rows_for_qdistr 22161 22161 0 0.00 0 16222 180 0 0
sp_pay_from_customer 7755 7755 0 0.00 4 12594 100 0 0
sp_pay_to_supplier 6241 6241 0 0.00 5 15492 108 0 0
sp_payment_common 13996 13996 0 0.00 1 6853 17 0 0
sp_qd_handle_on_cancel_clo 1890 1890 0 0.00 0 7879 289 0 0
sp_qd_handle_on_invoice_upd_sts 10966 10966 0 0.00 0 37464 359 0 0
sp_qd_handle_on_reserve_upd_sts 10656 10656 0 0.00 0 15473 47 0 0
sp_reserve_write_off 10656 10656 0 0.00 7 16433 160 0 0
sp_supplier_invoice 5787 5787 0 0.00 7 90301 3285 0 0
sp_supplier_order 5744 5744 0 0.00 5 54800 1481 0 0
srv_aggregate_perf_data 110 110 0 0.00 3011 321035 16490 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1425 129945 42584 0 0
srv_find_qd_qs_mism 1085431 1085431 0 0.00 0 15950 5 0 0
srv_make_invnt_saldo 482 482 0 0.00 50 83130 3281 0 0
srv_make_money_saldo 760 760 0 0.00 1 20276 551 0 0
srv_recalc_idx_stat 4 4 0 0.00 144525 279086 203048 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5288 5288 0 0.00 0 15339 162 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 1 3876 119 0 0
t$perf-norm:sp_cancel_client_order 1890 1890 0 0.00 0 1484 11 0 0
t$perf-norm:sp_cancel_customer_reserve 741 741 0 0.00 0 3914 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 5073 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1719 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1885 25 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 20292 1579 0 0
t$perf-norm:sp_cancel_write_off 1097 1097 0 0.00 0 4317 18 0 0
t$perf-norm:sp_client_order 22161 22161 0 0.00 0 6531 17 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 14827 20 0 0
t$perf-norm:sp_pay_from_customer 7755 7755 0 0.00 0 3642 8 0 0
t$perf-norm:sp_pay_to_supplier 6241 6241 0 0.00 0 7312 7 0 0
t$perf-norm:sp_reserve_write_off 10656 10656 0 0.00 0 4958 9 0 0
t$perf-norm:sp_supplier_invoice 5787 5787 0 0.00 0 7337 18 0 0
t$perf-norm:sp_supplier_order 5744 5744 0 0.00 0 4790 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 78 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 408 13 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 528 8 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 399 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10910 10560 350 3.20 0 12294 17 0 350
v_all_customers 41072 41072 0 0.00 0 19 1 0 0
v_all_suppliers 32428 32428 0 0.00 0 21 1 0 0
v_all_wares 44322 44322 0 0.00 0 174 1 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 773 6 0 0
v_cancel_client_order 3780 3780 0 0.00 0 2108 4 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2089 5 0 0
v_cancel_customer_reserve 1482 1482 0 0.00 0 1919 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 1666 7 0 0
v_cancel_supplier_invoice 842 830 12 1.42 0 1378 14 0 12
v_cancel_supplier_order 846 846 0 0.00 0 621 4 0 0
v_cancel_write_off 2194 2194 0 0.00 0 2102 13 0 0
v_max_id_clo_ord 5743 5743 0 0.00 0 2830 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 5663 23 0 0
v_max_id_ord_sup 5786 5786 0 0.00 0 7988 6 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 91 2 0 0
v_max_non_paid_realizn 3864 3864 0 0.00 0 626 3 0 0
v_min_id_clo_ord 5743 5743 0 0.00 0 2674 5 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 1764 4 0 0
v_min_id_ord_sup 5786 5786 0 0.00 0 8740 10 0 0
v_min_non_paid_invoice 6241 6241 0 0.00 0 6531 10 0 0
v_min_non_paid_realizn 7755 7755 0 0.00 0 6850 12 0 0
v_our_firm 11032 11032 0 0.00 0 16 1 0 0
v_reserve_write_off 21312 21312 0 0.00 0 12140 5 0 0
x_lock_depdocs_on_canc_invoice 11534 11534 0 0.00 0 10232 88 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 11053 299 0 0
x_make_qstorno_1000_1200 5744 5744 0 0.00 2 54767 1406 0 0
x_make_qstorno_1200_2000 5787 5787 0 0.00 3 90293 3109 0 0
x_make_qstorno_2100_3300 486528 486528 0 0.00 0 55220 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2020 13:29:38 to 24-12-2020 13:29:42.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:58 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:59 21826 8192000000 9718122864 10011168768 790474288 8.800 100 87 174 1 819824400 7628208 21721904
10:59:30 18199 8192000000 9748858336 10055450624 832600976 9.225 100 84 168 1 863883648 11319680 19962992
11:00:51 17123 8192000000 9799000912 10115686400 867777456 9.578 100 86 172 1 906144960 18040144 20327360
11:02:13 14536 8192000000 9860693920 10188541952 905020320 9.948 100 82 162 1 963938208 40869344 18048544
11:03:32 9383 8192000000 9901694288 10242908160 943007360 10.323 100 86 172 0 1018057008 57007552 18042096
11:04:44 4020 8192000000 9942638288 10295115776 981325184 10.697 100 72 145 1 1055729920 59562544 14842192
11:05:49 3822 8192000000 9990414944 10347143168 1015292016 11.027 100 87 173 2 1109440368 77065296 17083056
11:06:55 2201 8192000000 10019626224 10382446592 1037436304 11.240 100 90 180 2 1129596800 73149008 19011488
11:07:59 1681 8192000000 10022914656 10391580672 1054577344 11.405 100 92 187 1 1137355552 64089232 18688976
11:09:02 8550 8192000000 10001208992 10378194944 1065108672 11.505 100 78 156 2 1121399120 40038624 16251824
11:10:11 4390 8192000000 10011247184 10397409280 1073705792 11.587 100 50 100 0 1122840512 38747360 10387360
11:11:18 14003 8192000000 10003851168 10388066304 1079903856 11.647 100 62 127 2 1110835280 16802112 14129312
11:12:32 12167 8192000000 9993396800 10390847488 1080080592 11.648 100 22 44 3 1098805504 13612320 5112592
11:13:46 7962 8192000000 9988872544 10391785472 1086885712 11.713 100 23 46 0 1120338384 28631904 4820768
11:14:54 8170 8192000000 9985737504 10391044096 1079687760 11.644 99 25 48 3 1106909984 21639632 5582592
11:16:02 26791 8192000000 10002205584 10398773248 1096266496 11.802 100 55 107 3 1118495200 11273280 10955424
11:17:30 12210 8192000000 9985554944 10387148800 1056637344 11.424 97 41 80 2 1082409072 16528336 9243392
11:18:43 8259 8192000000 9968981648 10372734976 1044809728 11.311 98 29 58 0 1068282784 17014400 6458656
11:19:58 4951 8192000000 9906496352 10287337472 1003217984 10.910 98 20 40 2 1029704592 21861696 4624912
11:21:04 28205 8192000000 9935807296 10302840832 1001694688 10.895 98 61 123 1 1034219216 18827568 13696960
11:22:33 10552 8192000000 9947231696 10337435648 1001684720 10.895 97 36 72 0 1032424464 22419760 8319984
11:23:47 8113 8192000000 9965686848 10355236864 1040390656 11.268 100 41 82 4 1072087616 21798832 9898128
11:24:58 9786 8192000000 9987069728 10373804032 1051855056 11.378 100 26 52 1 1082634992 24545632 6234304
11:26:09 21596 8192000000 9989806096 10369114112 1061761744 11.473 100 70 139 3 1091375520 14879440 14734336
11:27:48 38944 8192000000 10046198576 10448142336 1092269744 11.764 100 40 79 0 1147568080 47505424 7792912
11:29:33 4747 8192000000 10071458416 10471772160 1104560544 11.881 100 58 115 1 1185611680 69011312 12039824
11:30:43 6249 8192000000 10078784256 10476371968 1118659264 12.014 100 57 113 0 1184471024 53604720 12207040
11:32:03 11028 8192000000 10148836528 10547429376 1139395824 12.210 100 60 120 3 1251216064 99050992 12769248
11:33:18 10795 8192000000 10152237296 10573479936 1138253376 12.199 100 48 97 1 1262565968 113084208 11228384
11:34:38 3254 8192000000 10174776864 10591576064 1141001264 12.225 99 50 100 2 1273801344 121451472 11348608
11:35:46 7395 8192000000 10211930096 10624258048 1155404480 12.360 100 64 126 0 1314274896 145238320 13632096
11:37:00 7336 8192000000 10199881488 10618167296 1157482176 12.380 100 48 97 1 1307523344 138466352 11574816
11:38:12 5643 8192000000 10201411360 10625576960 1164383904 12.444 100 60 118 2 1306294336 128371008 13539424
11:39:22 3030 8192000000 10244133168 10658508800 1175905184 12.552 100 60 120 1 1349373936 160553840 12914912
11:40:35 1676 8192000000 10271085840 10663911424 1146729424 12.279 99 83 166 0 1368894048 203542192 18622432
11:41:42 2339 8192000000 10284382288 10710499328 1169875808 12.496 100 73 146 1 1387613616 200860720 16877088
11:42:52 2232 8192000000 10307091920 10704637952 1153276304 12.340 100 91 180 3 1404646560 230861456 20508800
11:43:57 3322 8192000000 10278897888 10684964864 1149515280 12.305 100 62 122 2 1374053104 209521632 15016192
11:45:04 1423 8192000000 10325566768 10729713664 1142418000 12.238 100 77 152 1 1425374816 265266848 17689968
11:46:11 3434 8192000000 10345502208 10758733824 1154734528 12.354 100 65 130 2 1443264272 272905360 15624384
11:47:22 11389 8192000000 10325516400 10780626944 1149790464 12.308 99 43 85 0 1425016032 264260960 10964608
11:48:46 10414 8192000000 10407161152 10828607488 1150009424 12.310 99 37 75 2 1500301440 340788448 9503568
11:49:59 18510 8192000000 10450471952 10881433600 1157223872 12.377 100 31 62 1 1547971584 382103952 8643760
11:51:32 17942 8192000000 10499882800 10929811456 1154413184 12.351 100 36 72 0 1578667472 415172784 9081504
11:52:56 20422 8192000000 10451633360 10927267840 1106527808 11.900 98 21 43 0 1529895472 417178320 6189344
11:54:46 14711 8192000000 10308263312 10788327424 1091222448 11.754 99 25 50 1 1449396560 351510416 6663696
11:56:07 92720 8192000000 10048998640 10493149184 1090500304 11.747 98 8 16 2 1146022128 54241232 1280592
11:58:49 80932 8192000000 10044421056 10494156800 1088838112 11.732 96 14 28 3 1124917008 33527184 2551712
12:01:28 92184 8192000000 10106988032 10534785024 1132748640 12.147 99 24 47 2 1191359200 53747488 4863072
12:04:01 121766 8192000000 10088456144 10561159168 1125096416 12.075 98 6 12 1 1163537632 37209536 1231680
12:07:04 92329 8192000000 10125937408 10560811008 1127806352 12.101 98 39 76 1 1218299536 82445984 8047200
12:09:44 12224 8192000000 10111572800 10583277568 1149043408 12.301 100 22 44 2 1214710112 60616688 5050016
12:11:02 44986 8192000000 10153425504 10596184064 1153864208 12.346 100 38 75 1 1242329152 80470000 7994944
12:12:52 16812 8192000000 10132667120 10577453056 1153833008 12.345 100 40 78 0 1237810256 75660480 8316768
12:14:20 38199 8192000000 10083783984 10536960000 1148721440 12.297 100 13 26 1 1200389152 48988048 2679664
12:15:58 24346 8192000000 10116206080 10579021824 1142848544 12.242 99 7 14 0 1209379024 65140240 1390240
12:18:37 49810 8192000000 10120476416 10583007232 1131713232 12.138 98 14 28 0 1190034304 55401376 2919696
12:20:37 38290 8192000000 10174144112 10611470336 1143997232 12.253 99 38 75 2 1237768224 84887456 8883536
12:22:24 77426 8192000000 10162147376 10637348864 1154026320 12.347 100 22 44 3 1299762224 140758560 4977344
12:24:56 40467 8192000000 10121030576 10568540160 1136014928 12.178 99 25 50 3 1199361632 58256864 5089840
12:26:42 54303 8192000000 10092263248 10523631616 1134227088 12.161 99 29 58 2 1175581312 35977312 5376912
12:28:43 77786 8192000000 10059355232 10519687168 1091365584 11.756 96 17 33 0 1154087520 58909920 3812016
12:32:15 40864 8192000000 10063263040 10515775488 1133966944 12.159 100 27 54 4 1164495664 25118800 5409920
12:33:59 53092 8192000000 10045719376 10493169664 1119175888 12.019 99 7 14 0 1154310224 33769904 1364432
12:35:52 78316 8192000000 10073271792 10551214080 1131967568 12.140 100 12 24 3 1172755456 37955968 2831920
12:38:20 41041 8192000000 10061647856 10517213184 1102908128 11.865 97 23 45 1 1152602944 44351008 5343808
12:40:16 32770 8192000000 10112206432 10544918528 1087540832 11.719 96 28 52 3 1164232160 70954064 5737264
12:41:55 62514 8192000000 10154143584 10579394560 1071647904 11.568 95 28 56 1 1221935568 143475312 6812352
12:44:07 53247 8192000000 10129642720 10598633472 1089335680 11.736 98 15 30 1 1212676896 119645520 3695696
12:46:00 28635 8192000000 10087026976 10514526208 1093649504 11.777 98 40 80 2 1142029632 40092464 8287664
12:47:36 80960 8192000000 10068863296 10511151104 1089765264 11.740 98 17 32 3 1145628912 52349552 3514096
12:49:57 102451 8192000000 10045720272 10522320896 1103446784 11.870 99 19 38 1 1179142560 71471856 4223920
12:52:59 71832 8192000000 10077086288 10540142592 1121903376 12.045 100 8 16 1 1171704720 48441408 1359936
12:55:12 60801 8192000000 10070405760 10520059904 1131272352 12.133 100 23 44 1 1170861216 34581536 5007328
12:57:17 67370 8192000000 10078377600 10524422144 1117478928 12.003 98 15 30 2 1156737584 36264160 2994496
12:59:25 45371 8192000000 10090320064 10559180800 1126289392 12.086 99 18 36 1 1164580576 34382576 3908608
13:01:16 42454 8192000000 10147529216 10598477824 1129979024 12.121 99 31 62 0 1192110736 55142048 6989664
13:02:59 88917 8192000000 10074790544 10536038400 1113951200 11.970 98 5 10 1 1161650048 46884528 814320
13:05:33 129945 8192000000 10111329072 10637008896 1069457520 11.547 94 15 30 1 1151033648 78725264 2850864
13:09:39 59104 8192000000 10072897712 10547470336 1128505744 12.107 100 13 26 0 1186524576 55275264 2743568
13:11:38 56427 8192000000 10069268608 10523652096 1119538752 12.023 99 6 12 0 1158205968 37548448 1118768
13:13:35 66845 8192000000 10039516304 10518278144 1075414432 11.604 95 11 22 0 1101965104 24670336 1880336
13:15:42 37698 8192000000 10085467728 10552836096 1130905792 12.130 100 22 45 0 1177634032 42059232 4669008
13:17:24 18276 8192000000 10047893136 10485788672 1085812592 11.703 96 18 36 2 1124496592 35120544 3563456
13:18:51 34921 8192000000 10054015232 10498871296 1092766352 11.769 98 16 31 2 1127189712 31679952 2743408
13:20:29 84837 8192000000 10073251728 10524164096 1079825520 11.646 97 20 40 1 1143033536 59103120 4104896
13:22:56 55596 8192000000 10060404208 10536206336 1098890656 11.827 98 27 54 2 1200665600 96339952 5434992
13:24:53 70161 8192000000 10066858368 10497212416 1066029920 11.514 96 24 48 1 1118243344 47382528 4830896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2020 13:29:44 to 24-12-2020 13:29:59.

Exceptions occured during test was in run

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

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

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          247448
MON$OLDEST_ACTIVE               247449
MON$OLDEST_SNAPSHOT             247449
MON$NEXT_TRANSACTION            247449
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                       2127488
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             813
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2303 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.2303 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 ---
--- end of diff output ---

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

24.12.20 13:30:03. 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-24 13:30:05 No severe PSQL-related problems occured
24.12.20 13:30:07. End of report.