Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.01.2021 10: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 16639624 kB
MemAvailable 31216372 kB
Buffers 0 kB
Cached 15011692 kB
SwapCached 4868 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 2021-01-31 10:26:55
DTS_END                         2021-01-31 10:26:55.2560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57170
TRN_ID                          237675
ATT_ID                          655
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: 8720.07 20979 1046408
customer order: creation 166.30 436 19956
customer order: refuse 13.65 325 1638
order to supplier: creation 43.89 1280 5267
order to supplier: removal 3.40 115349 408
invoice (draft): creation 43.96 3520 5275
invoice (draft): removal 48.18 1029 5782
invoice accept: apply 40.18 17154 4822
invoice accept: cancel 43.68 900 5242
customer reserve: creation 3761.38 165 451366
customer reserve: removal 4271.12 73 512534
realization accept: apply 79.79 174 9575
realization accept: cancel 78.88 123 9465
payment from customer: creation 57.78 101 6934
payment from customer: removal 5.40 77 648
payment to supplier: creation 47.60 123 5712
payment to supplier: removal 3.37 81 404
service: total inventory turnovers 4.29 2846 515
service: total monetary turnovers 6.36 485 763
service: aggregate perf. data 0.82 16421 98
service: refresh index statistics 0.03 258925 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-01-2021 10:28:12 to 31-01-2021 10:28:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3212
TEST_TIME 2 2876
TEST_TIME 3 3078
TEST_TIME 4 3494
TEST_TIME 5 4072
TEST_TIME 6 4637
TEST_TIME 7 4657
TEST_TIME 8 4671
TEST_TIME 9 5161
TEST_TIME 10 5256
TEST_TIME 11 5604
TEST_TIME 12 5707
TEST_TIME 13 6219
TEST_TIME 14 6580
TEST_TIME 15 6569
TEST_TIME 16 6634
TEST_TIME 17 6978
TEST_TIME 18 7071
TEST_TIME 19 7331
TEST_TIME 20 7478
TEST_TIME 21 7690
TEST_TIME 22 7989
TEST_TIME 23 8583
TEST_TIME 24 8729
TEST_TIME 25 8765
TEST_TIME 26 9895
TEST_TIME 27 10284
TEST_TIME 28 10177
TEST_TIME 29 10239
TEST_TIME 30 10190
TEST_TIME 31 10094
TEST_TIME 32 10314
TEST_TIME 33 10260
TEST_TIME 34 10027
TEST_TIME 35 9822
TEST_TIME 36 9590
TEST_TIME 37 9568
TEST_TIME 38 9495
TEST_TIME 39 9593
TEST_TIME 40 9561
TEST_TIME 41 9523
TEST_TIME 42 9436
TEST_TIME 43 9340
TEST_TIME 44 9288
TEST_TIME 45 9274
TEST_TIME 46 9438
TEST_TIME 47 9414
TEST_TIME 48 9374
TEST_TIME 49 9448
TEST_TIME 50 9553
TEST_TIME 51 9534
TEST_TIME 52 9533
TEST_TIME 53 9642
TEST_TIME 54 9559
TEST_TIME 55 9646
TEST_TIME 56 9589
TEST_TIME 57 9494
TEST_TIME 58 9465
TEST_TIME 59 9379
TEST_TIME 60 9325
TEST_TIME 61 9252
TEST_TIME 62 9231
TEST_TIME 63 9224
TEST_TIME 64 9205
TEST_TIME 65 9202
TEST_TIME 66 9192
TEST_TIME 67 9161
TEST_TIME 68 9263
TEST_TIME 69 9255
TEST_TIME 70 9396
TEST_TIME 71 9329
TEST_TIME 72 9369
TEST_TIME 73 9356
TEST_TIME 74 9410
TEST_TIME 75 9445
TEST_TIME 76 9403
TEST_TIME 77 9294
TEST_TIME 78 9220
TEST_TIME 79 9186
TEST_TIME 80 9140
TEST_TIME 81 9126
TEST_TIME 82 9079
TEST_TIME 83 9043
TEST_TIME 84 9005
TEST_TIME 85 8977
TEST_TIME 86 8947
TEST_TIME 87 8991
TEST_TIME 88 9059
TEST_TIME 89 9058
TEST_TIME 90 9083
TEST_TIME 91 9161
TEST_TIME 92 9140
TEST_TIME 93 9185
TEST_TIME 94 9235
TEST_TIME 95 9157
TEST_TIME 96 9084
TEST_TIME 97 9041
TEST_TIME 98 8986
TEST_TIME 99 8958
TEST_TIME 100 8896
TEST_TIME 101 8909
TEST_TIME 102 8880
TEST_TIME 103 8865
TEST_TIME 104 8833
TEST_TIME 105 8825
TEST_TIME 106 8800
TEST_TIME 107 8805
TEST_TIME 108 8789
TEST_TIME 109 8838
TEST_TIME 110 8815
TEST_TIME 111 8826
TEST_TIME 112 8887
TEST_TIME 113 8855
TEST_TIME 114 8823
TEST_TIME 115 8770
TEST_TIME 116 8739
TEST_TIME 117 8757
TEST_TIME 118 8744
TEST_TIME 119 8720
TEST_TIME 120 8722

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-01-2021 10:28:15 to 31-01-2021 10:28:19.

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 1464747 1464747 0 0.00 0 26324 6 0 0
doc_list_biud 1464746 1464746 0 0.00 0 81157 32 0 0
sp_add_invoice_to_stock 4956 4822 134 2.70 34 172161 17154 0 134
sp_cancel_adding_invoice 5242 5242 0 0.00 4 104342 900 0 0
sp_cancel_client_order 1638 1638 0 0.00 4 22917 325 0 0
sp_cancel_customer_reserve 512534 512534 0 0.00 1 81158 73 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 4159 78 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 3133 81 0 0
sp_cancel_supplier_invoice 5797 5782 15 0.25 2 22768 1029 0 15
sp_cancel_supplier_order 408 408 0 0.00 13 1092339 115349 0 0
sp_cancel_write_off 9465 9465 0 0.00 1 9892 124 0 0
sp_client_order 19956 19956 0 0.00 4 26701 437 0 0
sp_customer_reserve 451366 451366 0 0.00 0 127309 166 0 0
sp_fill_shopping_cart 30492 30492 0 0.00 1 16587 43 0 0
sp_fill_shopping_cart_clo_res 451361 451361 0 0.00 0 25181 17 0 0
sp_get_clo_for_invoice 4817 4817 0 0.00 0 42448 397 0 0
sp_kill_cost_storno 15759 15759 0 0.00 0 6678 55 0 0
sp_kill_qstorno_ret_qs2qd 1040723 1040723 0 0.00 0 81154 40 0 0
sp_kill_qty_storno 549461 549461 0 0.00 0 81155 86 0 0
sp_lock_selected_doc 19911 19911 0 0.00 0 9863 5 0 0
sp_make_cost_storno 27038 27038 0 0.00 0 14290 49 0 0
sp_multiply_rows_for_pdistr 27038 27038 0 0.00 0 6799 30 0 0
sp_multiply_rows_for_qdistr 19956 19956 0 0.00 0 26680 254 0 0
sp_pay_from_customer 6934 6934 0 0.00 4 10999 102 0 0
sp_pay_to_supplier 5712 5712 0 0.00 5 13074 124 0 0
sp_payment_common 12646 12646 0 0.00 1 10814 18 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 22912 306 0 0
sp_qd_handle_on_invoice_upd_sts 10059 10059 0 0.00 0 26281 371 0 0
sp_qd_handle_on_reserve_upd_sts 9575 9575 0 0.00 0 14301 67 0 0
sp_reserve_write_off 9575 9575 0 0.00 6 20006 175 0 0
sp_supplier_invoice 5275 5275 0 0.00 6 87060 3521 0 0
sp_supplier_order 5267 5267 0 0.00 5 24437 1280 0 0
srv_aggregate_perf_data 98 98 0 0.00 2946 317304 16422 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1940 145857 44373 0 0
srv_find_qd_qs_mism 1000259 1000259 0 0.00 0 26275 6 0 0
srv_make_invnt_saldo 515 515 0 0.00 39 84529 2847 0 0
srv_make_money_saldo 763 763 0 0.00 1 14262 486 0 0
srv_recalc_idx_stat 4 4 0 0.00 106224 404861 258925 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4822 4822 0 0.00 0 37540 193 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 3964 107 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 6570 16 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 1347 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 6442 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 21619 68 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1212 17 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 18090 1459 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 1487 5 0 0
t$perf-norm:sp_client_order 19956 19956 0 0.00 0 17439 19 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 3693 8 0 0
t$perf-norm:sp_pay_from_customer 6934 6934 0 0.00 0 7259 16 0 0
t$perf-norm:sp_pay_to_supplier 5712 5712 0 0.00 0 28681 18 0 0
t$perf-norm:sp_reserve_write_off 9575 9575 0 0.00 0 6862 13 0 0
t$perf-norm:sp_supplier_invoice 5275 5275 0 0.00 0 3814 15 0 0
t$perf-norm:sp_supplier_order 5267 5267 0 0.00 0 6496 14 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 490 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 329 8 0 0
t$perf-norm:srv_make_invnt_saldo 515 515 0 0.00 0 5934 25 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 3422 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 21 9 0 0
v_add_invoice_to_stock 9902 9634 268 2.70 0 5068 15 0 268
v_all_customers 36346 36346 0 0.00 0 19 1 0 0
v_all_suppliers 29082 29082 0 0.00 0 151 1 0 0
v_all_wares 39912 39912 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 2269 12 0 0
v_cancel_client_order 3276 3276 0 0.00 0 2270 4 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1872 7 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 1789 6 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2531 8 0 0
v_cancel_supplier_invoice 826 796 30 3.63 0 537 13 0 30
v_cancel_supplier_order 816 816 0 0.00 0 2829 7 0 0
v_cancel_write_off 1944 1944 0 0.00 0 2695 12 0 0
v_max_id_clo_ord 5264 5264 0 0.00 0 4468 2 0 0
v_max_id_clo_res 978 978 0 0.00 0 3244 17 0 0
v_max_id_ord_sup 5272 5272 0 0.00 0 7036 7 0 0
v_max_non_paid_invoice 1707 1707 0 0.00 0 271 2 0 0
v_max_non_paid_realizn 3673 3673 0 0.00 0 4283 5 0 0
v_min_id_clo_ord 5264 5264 0 0.00 0 2259 4 0 0
v_min_id_clo_res 978 978 0 0.00 0 4580 6 0 0
v_min_id_ord_sup 5272 5272 0 0.00 0 4884 8 0 0
v_min_non_paid_invoice 5712 5712 0 0.00 0 5904 13 0 0
v_min_non_paid_realizn 6934 6934 0 0.00 0 3097 10 0 0
v_our_firm 10088 10088 0 0.00 0 23 1 0 0
v_reserve_write_off 19150 19150 0 0.00 0 3124 4 0 0
x_lock_depdocs_on_canc_invoice 10626 10626 0 0.00 0 19310 111 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 10829 326 0 0
x_make_qstorno_1000_1200 5267 5267 0 0.00 2 24405 1211 0 0
x_make_qstorno_1200_2000 5275 5275 0 0.00 2 87053 3343 0 0
x_make_qstorno_2100_3300 451366 451366 0 0.00 0 127273 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-01-2021 10:28:20 to 31-01-2021 10:28:24.

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:59 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
07:58:00 22433 8192000000 9723678176 10017669120 794049264 8.836 100 80 160 2 821948416 7822992 20076160
07:59:27 26142 8192000000 9774545376 10079526912 844242816 9.342 100 90 180 0 878153200 12928672 20981712
08:00:56 18018 8192000000 9806356448 10123100160 863697056 9.537 100 82 161 2 909481248 27500640 18283552
08:02:17 12245 8192000000 9876558496 10211041280 921351648 10.109 100 79 154 2 980564464 42638528 16574288
08:03:33 7151 8192000000 9909420432 10258173952 962240432 10.511 100 69 138 1 1021510080 44890368 14379280
08:04:46 5830 8192000000 9969484976 10320175104 992893488 10.810 100 88 176 0 1081859744 71353600 17612656
08:05:54 5961 8192000000 9980103040 10346799104 1014760816 11.021 100 78 156 0 1106555056 76369024 15425216
08:07:02 2243 8192000000 10003333344 10368704512 1044396816 11.307 100 90 180 1 1108960320 45984912 18578592
08:08:05 2183 8192000000 10008717152 10396057600 1064906144 11.503 100 74 147 1 1139503472 59425248 15172080
08:09:07 8215 8192000000 10020177808 10405167104 1069726128 11.549 100 42 85 1 1139925392 61966336 8232928
08:10:16 5860 8192000000 9995037056 10402721792 1075240960 11.602 100 26 52 2 1110805744 30726592 4838192
08:11:23 5935 8192000000 10005556496 10397454336 1084088096 11.686 100 42 83 3 1112473056 19946880 8438080
08:12:31 65771 8192000000 10029183120 10423574528 1090011408 11.743 100 59 116 4 1133101744 30748160 12342176
08:14:37 7725 8192000000 10030432768 10418356224 1091207760 11.754 100 48 95 1 1128156256 26321568 10626928
08:15:47 4014 8192000000 9998678656 10402545664 1081245024 11.659 99 23 46 1 1106438512 20276288 4917200
08:16:52 5302 8192000000 9975613424 10358460416 1054195520 11.401 99 38 75 2 1078947968 16155792 8596656
08:17:58 43376 8192000000 9944078624 10320216064 983877792 10.722 94 36 71 3 1004871664 12641984 8351888
08:19:47 7224 8192000000 9939552992 10323587072 1013356944 11.008 99 35 69 3 1054390416 33857664 7175808
08:20:59 6637 8192000000 9941297776 10319503360 1020538000 11.077 100 42 85 0 1065375904 35744800 9093104
08:22:06 5342 8192000000 9942937296 10331029504 1049507056 11.356 100 39 72 2 1079618096 21355552 8755488
08:23:14 25752 8192000000 9967938784 10369671168 1052447360 11.384 100 23 43 0 1081822320 24972944 4402016
08:24:41 4802 8192000000 9980285136 10358759424 1058657040 11.444 100 56 112 0 1080343760 8453600 13233120
08:25:49 7424 8192000000 9980735040 10382974976 1063369600 11.489 100 34 66 2 1085236080 13761520 8104960
08:26:58 28711 8192000000 10004408224 10402766848 1086609392 11.710 100 30 60 1 1114016416 21337424 6069600
08:28:30 46388 8192000000 10050484352 10459615232 1105114704 11.886 100 35 68 2 1161190768 48909040 7167024
08:30:20 5267 8192000000 10084746000 10477023232 1119235984 12.020 100 61 119 1 1190020160 58337024 12447152
08:31:29 11148 8192000000 10144477088 10544586752 1130068464 12.122 100 54 108 2 1249754944 108520416 11166064
08:32:47 6255 8192000000 10149724096 10553061376 1149637792 12.306 100 49 98 1 1260034672 99814832 10582048
08:33:56 12383 8192000000 10190862816 10596515840 1151129408 12.320 100 56 110 0 1292988944 130409584 11449952
08:35:15 5926 8192000000 10213752560 10635354112 1153352336 12.341 100 57 114 0 1314395216 148469056 12573824
08:36:26 21132 8192000000 10202942544 10613673984 1149762976 12.307 100 42 84 2 1303389504 143412752 10213776
08:37:52 4026 8192000000 10256187184 10659938304 1163553024 12.437 100 74 145 1 1359503664 179872464 16078176
08:39:02 13551 8192000000 10251383536 10679054336 1154593440 12.353 100 46 90 0 1363456704 197592288 11270976
08:40:20 3878 8192000000 10234468992 10646892544 1146773152 12.279 99 73 146 0 1327337504 164007792 16556560
08:41:30 2882 8192000000 10254490032 10659201024 1146135824 12.273 99 65 129 2 1346863200 185476320 15251056
08:42:38 2252 8192000000 10322114368 10728239104 1171691888 12.513 100 69 137 0 1421726912 234284080 15750944
08:43:44 1938 8192000000 10343573744 10744938496 1167860688 12.477 100 86 167 3 1442096096 255057600 19177808
08:44:53 3224 8192000000 10365967104 10766561280 1166386528 12.463 100 67 134 2 1463030672 280820080 15824064
08:46:03 7387 8192000000 10415295984 10829074432 1165708800 12.457 99 46 90 1 1501036352 323606896 11720656
08:47:17 2750 8192000000 10439252752 10845728768 1155423040 12.360 100 75 148 3 1536827968 362656400 18748528
08:48:27 17474 8192000000 10398210624 10861895680 1139617904 12.212 99 40 80 0 1523420912 373245408 10557600
08:49:49 20864 8192000000 10336194784 10754158592 1096522720 11.805 96 33 66 1 1411215552 306198272 8494560
08:51:19 9087 8192000000 10347497440 10786631680 1113178464 11.962 100 36 72 1 1441708816 319250144 9280208
08:52:31 24942 8192000000 10181228816 10717310976 1103250608 11.868 99 19 38 1 1332520400 224002592 5267200
08:53:56 23441 8192000000 10059137776 10487971840 1092944384 11.771 98 14 26 1 1124892624 29565840 2382400
08:55:20 70602 8192000000 10076298288 10509017088 1114005376 11.970 99 33 66 1 1171934592 49610304 8318912
08:57:37 45519 8192000000 10136998544 10590285824 1117715808 12.005 99 17 34 0 1195584432 74305056 3563568
08:59:26 80595 8192000000 10042422560 10479767552 1096002928 11.800 97 14 28 2 1128636112 29670272 2962912
09:02:15 39710 8192000000 10077940064 10504458240 1121184224 12.038 100 40 79 1 1168835680 39667824 7983632
09:03:56 29889 8192000000 10124036208 10560847872 1136474752 12.182 100 38 76 2 1225931744 81483808 7973184
09:05:37 43670 8192000000 10107366848 10594693120 1113509744 11.966 98 15 31 0 1179967952 63052432 3405776
09:07:24 145857 8192000000 10098765536 10543431680 1114106544 11.971 98 9 18 0 1154365248 38053072 2205632
09:10:55 60393 8192000000 10134456368 10613993472 1128203904 12.104 99 10 20 1 1248025632 117428064 2393664
09:12:55 74321 8192000000 10085688368 10534916096 1118935168 12.017 98 13 26 0 1165340304 43395376 3009760
09:15:15 51286 8192000000 10116021232 10554712064 1144307600 12.256 100 19 36 2 1196139216 48325456 3506160
09:17:08 72116 8192000000 10099016656 10576117760 1144208800 12.255 100 22 44 0 1182686704 34146736 4331168
09:19:21 84888 8192000000 10090645216 10535309312 1125793024 12.082 98 8 16 0 1175005504 47583392 1629088
09:21:46 124929 8192000000 10104703072 10577731584 1083073280 11.677 95 10 20 0 1135068320 50113888 1881152
09:25:45 45206 8192000000 10078588976 10542628864 1143326320 12.247 100 11 22 1 1190824160 45498912 1998928
09:27:30 36013 8192000000 10083313040 10570297344 1135130304 12.170 100 9 18 0 1181848192 45043120 1674768
09:29:16 69983 8192000000 10070781408 10553667584 1116693584 11.996 98 15 30 0 1156134224 36721728 2718912
09:31:38 77732 8192000000 10116454416 10563067904 1108838112 11.921 96 14 27 2 1189308656 77288672 3181872
09:33:57 36370 8192000000 10147456448 10601369600 1145607920 12.268 100 21 41 0 1233228208 83263088 4357200
09:35:34 29518 8192000000 10129828016 10549542912 1139252624 12.208 99 54 106 3 1208491568 57935040 11303904
09:37:08 57563 8192000000 10072188176 10496737280 1096600576 11.805 97 20 40 0 1142774224 42611920 3561728
09:39:08 63005 8192000000 10095263984 10566410240 1085212672 11.697 97 15 30 0 1172415680 83945184 3257824
09:41:15 42158 8192000000 10082373168 10532626432 1072154832 11.573 97 39 78 1 1123377504 43051024 8171648
09:42:58 98992 8192000000 10044176752 10464133120 1079129360 11.639 97 24 46 1 1136992800 53170336 4693104
09:45:37 38713 8192000000 10040617760 10502230016 1088821552 11.731 99 7 14 0 1141568736 51304304 1442880
09:47:21 43152 8192000000 10028981552 10513260544 1094716064 11.787 100 6 12 0 1130503584 34770368 1017152
09:49:17 46252 8192000000 10062321504 10500366336 1103431312 11.870 99 15 30 0 1143801456 37255312 3114832
09:51:03 36262 8192000000 10088672240 10516119552 1092040400 11.762 98 31 60 0 1179261536 80398976 6822160
09:52:42 45988 8192000000 10131840960 10607075328 1096612464 11.805 98 32 64 0 1203248224 99031616 7604144
09:54:30 79913 8192000000 10106523072 10534178816 1094303920 11.784 98 21 42 0 1191179392 91734000 5141472
09:56:50 39169 8192000000 10099502528 10540621824 1113194144 11.963 99 12 24 0 1194645632 78916480 2535008
09:58:32 40732 8192000000 10091866128 10538622976 1107228784 11.906 98 25 50 0 1145553888 33376544 4948560
10:00:16 47556 8192000000 10069443904 10552795136 1120580512 12.032 98 19 38 0 1176674736 51958608 4135616
10:02:06 67450 8192000000 10051807808 10507800576 1135865968 12.177 100 11 22 0 1171314032 32925136 2522928
10:04:13 43136 8192000000 10071967072 10518528000 1142429408 12.238 100 15 29 2 1177882176 31725856 3726912
10:05:57 94175 8192000000 10074267920 10553831424 1145202960 12.264 100 5 10 0 1183063840 36828256 1032624
10:08:34 40669 8192000000 10082631216 10528681984 1150098320 12.310 100 24 48 0 1184236176 28750976 5386880
10:10:17 53576 8192000000 10088267824 10528432128 1126150736 12.085 98 8 16 0 1161065008 33303872 1610400
10:12:11 46840 8192000000 10133620400 10572902400 1152669840 12.335 100 29 58 1 1223868736 64740048 6458848
10:14:00 49492 8192000000 10156417536 10613870592 1144079536 12.254 99 34 68 0 1243257120 91744240 7433344
10:15:59 56068 8192000000 10150065360 10587611136 1147957552 12.290 100 29 58 0 1215616896 61780144 5879200
10:18:04 67342 8192000000 10081236800 10518839296 1122922608 12.055 98 19 38 1 1155174304 28307376 3944320
10:20:21 123065 8192000000 10100618848 10553344000 1139010720 12.206 100 27 54 3 1172407168 27707888 5688560
10:23:24 54857 8192000000 10067951616 10562113536 1130362048 12.125 99 8 16 0 1182012000 50126048 1523904
10:25:26 58887 8192000000 10094032576 10533650432 1110967328 11.942 97 20 40 4 1168418000 53341904 4108768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-01-2021 10:28:26 to 31-01-2021 10:28:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-01-2021 10:28:42 to 31-01-2021 10:28:44.

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          234495
MON$OLDEST_ACTIVE               237795
MON$OLDEST_SNAPSHOT             237795
MON$NEXT_TRANSACTION            237795
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                       2092416
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2353 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.2353 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 ---
:51: 
:52: mscs-test-161.budzdorov.ru    Sun Jan 31 10:27:00 2021
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Sun Jan 31 10:27:00 2021
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
--- end of diff output ---

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

31.01.21 10:28:45. 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
2021-01-31 10:28:47 No severe PSQL-related problems occured
31.01.21 10:28:49. End of report.