Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16504888 kB
MemAvailable 31392668 kB
Buffers 0 kB
Cached 15108244 kB
SwapCached 96 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-04-08 10:26:57
DTS_END                         2021-04-08 10:26:57.6410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33350
TRN_ID                          245884
ATT_ID                          719
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: 9053.39 22551 1086407
customer order: creation 175.88 385 21106
customer order: refuse 15.80 318 1896
order to supplier: creation 47.60 1269 5712
order to supplier: removal 3.43 125265 412
invoice (draft): creation 47.86 3671 5743
invoice (draft): removal 50.20 981 6024
invoice accept: apply 42.44 16936 5093
invoice accept: cancel 45.48 886 5457
customer reserve: creation 3902.87 167 468344
customer reserve: removal 4411.27 77 529352
realization accept: apply 86.63 180 10395
realization accept: cancel 92.80 126 11136
payment from customer: creation 60.25 129 7230
payment from customer: removal 6.34 68 761
payment to supplier: creation 49.50 126 5940
payment to supplier: removal 3.63 95 435
service: total inventory turnovers 4.18 3258 502
service: total monetary turnovers 6.31 549 757
service: aggregate perf. data 0.90 15107 108
service: refresh index statistics 0.03 281435 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-04-2021 10:27:29 to 08-04-2021 10:27:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3455
TEST_TIME 2 3967
TEST_TIME 3 3637
TEST_TIME 4 4396
TEST_TIME 5 4557
TEST_TIME 6 5015
TEST_TIME 7 4870
TEST_TIME 8 5195
TEST_TIME 9 5465
TEST_TIME 10 5605
TEST_TIME 11 5553
TEST_TIME 12 6442
TEST_TIME 13 6597
TEST_TIME 14 6787
TEST_TIME 15 6874
TEST_TIME 16 6999
TEST_TIME 17 7060
TEST_TIME 18 7240
TEST_TIME 19 7336
TEST_TIME 20 7605
TEST_TIME 21 7881
TEST_TIME 22 8030
TEST_TIME 23 8215
TEST_TIME 24 8504
TEST_TIME 25 8562
TEST_TIME 26 8797
TEST_TIME 27 8992
TEST_TIME 28 8994
TEST_TIME 29 9752
TEST_TIME 30 11422
TEST_TIME 31 11319
TEST_TIME 32 11162
TEST_TIME 33 11196
TEST_TIME 34 11217
TEST_TIME 35 11176
TEST_TIME 36 11071
TEST_TIME 37 10892
TEST_TIME 38 10655
TEST_TIME 39 10562
TEST_TIME 40 10389
TEST_TIME 41 10354
TEST_TIME 42 10219
TEST_TIME 43 10181
TEST_TIME 44 10111
TEST_TIME 45 9990
TEST_TIME 46 9951
TEST_TIME 47 10030
TEST_TIME 48 9929
TEST_TIME 49 9979
TEST_TIME 50 9795
TEST_TIME 51 9653
TEST_TIME 52 9696
TEST_TIME 53 9604
TEST_TIME 54 9522
TEST_TIME 55 9458
TEST_TIME 56 9450
TEST_TIME 57 9420
TEST_TIME 58 9417
TEST_TIME 59 9486
TEST_TIME 60 9597
TEST_TIME 61 9566
TEST_TIME 62 9606
TEST_TIME 63 9611
TEST_TIME 64 9684
TEST_TIME 65 9651
TEST_TIME 66 9683
TEST_TIME 67 9644
TEST_TIME 68 9567
TEST_TIME 69 9483
TEST_TIME 70 9465
TEST_TIME 71 9375
TEST_TIME 72 9311
TEST_TIME 73 9325
TEST_TIME 74 9286
TEST_TIME 75 9281
TEST_TIME 76 9282
TEST_TIME 77 9318
TEST_TIME 78 9279
TEST_TIME 79 9241
TEST_TIME 80 9305
TEST_TIME 81 9343
TEST_TIME 82 9371
TEST_TIME 83 9345
TEST_TIME 84 9298
TEST_TIME 85 9208
TEST_TIME 86 9193
TEST_TIME 87 9134
TEST_TIME 88 9083
TEST_TIME 89 9051
TEST_TIME 90 9035
TEST_TIME 91 9088
TEST_TIME 92 9056
TEST_TIME 93 9055
TEST_TIME 94 9066
TEST_TIME 95 9099
TEST_TIME 96 9128
TEST_TIME 97 9119
TEST_TIME 98 9147
TEST_TIME 99 9152
TEST_TIME 100 9163
TEST_TIME 101 9310
TEST_TIME 102 9263
TEST_TIME 103 9306
TEST_TIME 104 9260
TEST_TIME 105 9177
TEST_TIME 106 9150
TEST_TIME 107 9110
TEST_TIME 108 9073
TEST_TIME 109 9025
TEST_TIME 110 8984
TEST_TIME 111 8975
TEST_TIME 112 8954
TEST_TIME 113 9001
TEST_TIME 114 9016
TEST_TIME 115 8985
TEST_TIME 116 8992
TEST_TIME 117 9068
TEST_TIME 118 9036
TEST_TIME 119 9036
TEST_TIME 120 9060

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2021 10:27:33 to 08-04-2021 10:27:36.

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 1521306 1521306 0 0.00 0 19292 7 0 0
doc_list_biud 1521304 1521304 0 0.00 0 33074 33 0 0
sp_add_invoice_to_stock 5207 5093 114 2.18 23 187887 16937 0 114
sp_cancel_adding_invoice 5457 5457 0 0.00 5 58068 887 0 0
sp_cancel_client_order 1896 1896 0 0.00 5 11025 318 0 0
sp_cancel_customer_reserve 529352 529352 0 0.00 1 27020 78 0 0
sp_cancel_pay_from_customer 761 761 0 0.00 3 5055 68 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 7808 96 0 0
sp_cancel_supplier_invoice 6029 6024 5 0.08 1 16933 982 0 5
sp_cancel_supplier_order 412 412 0 0.00 5 1115321 125266 0 0
sp_cancel_write_off 11136 11136 0 0.00 1 9487 127 0 0
sp_client_order 21106 21106 0 0.00 4 22292 385 0 0
sp_customer_reserve 468344 468344 0 0.00 0 48540 167 0 0
sp_fill_shopping_cart 32556 32556 0 0.00 1 20373 48 0 0
sp_fill_shopping_cart_clo_res 468337 468337 0 0.00 0 21055 17 0 0
sp_get_clo_for_invoice 5085 5085 0 0.00 0 34079 374 0 0
sp_kill_cost_storno 17789 17789 0 0.00 0 6526 66 0 0
sp_kill_qstorno_ret_qs2qd 1076276 1076276 0 0.00 0 22764 41 0 0
sp_kill_qty_storno 569757 569757 0 0.00 0 33074 88 0 0
sp_lock_selected_doc 21594 21594 0 0.00 0 10452 6 0 0
sp_make_cost_storno 28650 28650 0 0.00 0 19247 55 0 0
sp_multiply_rows_for_pdistr 28649 28649 0 0.00 0 8210 34 0 0
sp_multiply_rows_for_qdistr 21106 21106 0 0.00 0 17419 200 0 0
sp_pay_from_customer 7230 7230 0 0.00 4 16256 130 0 0
sp_pay_to_supplier 5940 5940 0 0.00 5 10871 126 0 0
sp_payment_common 13169 13169 0 0.00 1 8172 21 0 0
sp_qd_handle_on_cancel_clo 1896 1896 0 0.00 0 11018 284 0 0
sp_qd_handle_on_invoice_upd_sts 10542 10542 0 0.00 0 33074 378 0 0
sp_qd_handle_on_reserve_upd_sts 10395 10395 0 0.00 0 8591 52 0 0
sp_reserve_write_off 10395 10395 0 0.00 7 16345 181 0 0
sp_supplier_invoice 5743 5743 0 0.00 6 114013 3671 0 0
sp_supplier_order 5712 5712 0 0.00 5 33810 1269 0 0
srv_aggregate_perf_data 108 108 0 0.00 3229 110895 15107 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1647 126649 43643 0 0
srv_find_qd_qs_mism 1038353 1038353 0 0.00 0 16616 5 0 0
srv_make_invnt_saldo 502 502 0 0.00 52 99842 3259 0 0
srv_make_money_saldo 757 757 0 0.00 1 15536 549 0 0
srv_recalc_idx_stat 4 4 0 0.00 176699 402214 281435 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5093 5093 0 0.00 0 28387 168 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 3535 102 0 0
t$perf-norm:sp_cancel_client_order 1896 1896 0 0.00 0 3383 14 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1973 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 761 761 0 0.00 0 4312 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 355 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 3956 26 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 20672 1561 0 0
t$perf-norm:sp_cancel_write_off 1094 1094 0 0.00 0 6758 15 0 0
t$perf-norm:sp_client_order 21106 21106 0 0.00 0 9089 17 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 1052 4 0 0
t$perf-norm:sp_pay_from_customer 7230 7230 0 0.00 0 6840 8 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 3946 8 0 0
t$perf-norm:sp_reserve_write_off 10395 10395 0 0.00 0 7935 11 0 0
t$perf-norm:sp_supplier_invoice 5743 5743 0 0.00 0 4491 14 0 0
t$perf-norm:sp_supplier_order 5712 5712 0 0.00 0 3992 13 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 126 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1669 26 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 1328 14 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 3651 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 35 12 0 0
v_add_invoice_to_stock 10394 10166 228 2.19 0 9080 19 0 228
v_all_customers 38538 38538 0 0.00 0 29 1 0 0
v_all_suppliers 31584 31584 0 0.00 0 29 1 0 0
v_all_wares 42204 42204 0 0.00 0 224 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 981 7 0 0
v_cancel_client_order 3792 3792 0 0.00 0 6415 7 0 0
v_cancel_customer_prepayment 1522 1522 0 0.00 0 1806 6 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 1250 5 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 6487 13 0 0
v_cancel_supplier_invoice 830 820 10 1.20 0 2189 15 0 10
v_cancel_supplier_order 824 824 0 0.00 0 373 3 0 0
v_cancel_write_off 2188 2188 0 0.00 0 6452 14 0 0
v_max_id_clo_ord 5711 5711 0 0.00 0 1242 2 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 1435 13 0 0
v_max_id_ord_sup 5741 5741 0 0.00 0 5254 5 0 0
v_max_non_paid_invoice 1597 1597 0 0.00 0 2906 5 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 2131 4 0 0
v_min_id_clo_ord 5711 5711 0 0.00 0 12115 9 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 2662 9 0 0
v_min_id_ord_sup 5741 5741 0 0.00 0 8073 8 0 0
v_min_non_paid_invoice 5938 5938 0 0.00 0 4051 11 0 0
v_min_non_paid_realizn 7230 7230 0 0.00 0 7408 16 0 0
v_our_firm 10646 10646 0 0.00 0 190 1 0 0
v_reserve_write_off 20790 20790 0 0.00 0 8977 8 0 0
x_lock_depdocs_on_canc_invoice 11071 11071 0 0.00 0 11152 117 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 4533 350 0 0
x_make_qstorno_1000_1200 5712 5712 0 0.00 2 33797 1191 0 0
x_make_qstorno_1200_2000 5743 5743 0 0.00 3 112288 3486 0 0
x_make_qstorno_2100_3300 468344 468344 0 0.00 0 45416 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2021 10:27:37 to 08-04-2021 10:27:41.

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:57:01 9 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:02 30631 8192000000 9717077440 10013884416 798748352 8.884 100 81 162 1 826956720 7966448 20241920
07:59:37 6160 8192000000 9752839200 10060410880 832619248 9.226 100 83 166 1 864815280 12616672 19579360
08:00:52 14945 8192000000 9795952336 10109267968 859102592 9.491 100 83 164 1 897816384 19965600 18748192
08:02:10 10817 8192000000 9869381120 10200854528 911044528 10.008 100 81 162 2 978440416 49509344 17886544
08:03:24 7901 8192000000 9918596656 10260566016 961737184 10.506 100 78 156 0 1022495776 44606800 16151792
08:04:35 3765 8192000000 9968952896 10323369984 996373040 10.843 100 80 160 0 1079279280 66865504 16040736
08:05:41 1754 8192000000 10005358880 10365550592 1023516096 11.106 100 92 181 2 1116983872 74684864 18782912
08:06:44 1669 8192000000 10016897856 10379694080 1048433392 11.346 100 95 190 0 1130693376 62360320 19899664
08:07:47 1854 8192000000 10041459136 10412556288 1063002896 11.485 100 92 185 1 1147530656 66378768 18148992
08:08:51 16805 8192000000 9975867952 10365628416 1068145712 11.534 100 32 64 1 1101588208 26432240 7010256
08:10:09 5449 8192000000 10002643472 10403667968 1079313584 11.641 100 35 70 2 1127187664 41083664 6790416
08:11:16 7851 8192000000 9986944336 10388848640 1088284176 11.726 100 23 46 1 1112283424 19204224 4795024
08:12:26 9598 8192000000 10013844496 10421538816 1091747904 11.759 100 32 64 1 1143077792 44955376 6374512
08:13:36 19003 8192000000 10025200624 10410459136 1095042576 11.791 100 78 157 1 1126907312 14972800 16891936
08:14:57 4262 8192000000 10020928320 10420719616 1092739040 11.769 100 51 98 3 1127652512 23481456 11432016
08:16:07 6858 8192000000 10001648432 10406658048 1085437856 11.699 100 33 67 3 1102842672 10204608 7200208
08:17:17 10781 8192000000 10000535152 10410528768 1071474464 11.566 99 27 55 4 1099411728 21905328 6031936
08:18:32 17420 8192000000 9950057872 10356604928 1015820192 11.032 97 36 70 3 1046016000 21508768 8687040
08:19:53 3382 8192000000 9957273632 10327576576 1011865120 10.993 99 47 93 1 1056003936 32171008 11967808
08:20:57 4814 8192000000 9936118960 10313134080 1005841696 10.935 99 35 70 0 1038769792 24964080 7964016
08:22:04 15263 8192000000 9937895312 10318094336 1010912464 10.984 98 36 73 2 1035696096 16083440 8700192
08:23:23 25529 8192000000 9952897424 10354065408 1033502432 11.202 99 23 45 1 1071126032 32648208 4975392
08:24:49 6134 8192000000 9981046400 10373537792 1058702000 11.444 100 55 110 2 1084976896 13724304 12550592
08:25:56 8899 8192000000 9984438288 10384498688 1067528288 11.528 100 28 56 1 1098034480 24133488 6372704
08:27:13 5652 8192000000 10008514384 10395697152 1081667056 11.663 100 31 62 0 1109110352 21272944 6170352
08:28:27 72939 8192000000 10052714544 10451300352 1108977104 11.923 100 40 77 3 1154716896 37345584 8394208
08:30:49 39297 8192000000 10078621840 10487185408 1125061600 12.075 100 44 86 2 1184094512 49820048 9212864
08:32:31 9844 8192000000 10131699824 10548355072 1136389680 12.182 100 62 124 2 1247237696 97072992 13775024
08:33:46 16115 8192000000 10164722832 10580959232 1144347264 12.256 100 47 94 1 1273758832 118858848 10552720
08:35:09 8696 8192000000 10192078960 10600493056 1138428304 12.201 99 52 100 1 1289728144 139246016 12053824
08:36:23 11950 8192000000 10184876416 10604683264 1153858656 12.346 100 38 76 1 1297044368 134024448 9161264
08:37:38 6461 8192000000 10254128432 10666950656 1161995328 12.422 100 61 121 0 1354534000 178372016 14166656
08:38:55 3542 8192000000 10257542064 10689515520 1173299968 12.528 100 68 136 1 1358607536 169657744 15649824
08:40:01 2040 8192000000 10285256400 10697904128 1178501520 12.576 100 75 150 4 1381682416 186091248 17089648
08:41:06 6909 8192000000 10296844912 10701193216 1161594320 12.418 100 81 158 2 1394241728 216045488 16601920
08:42:16 1646 8192000000 10332786208 10732732416 1168937568 12.487 100 89 175 0 1427133312 239196352 18999392
08:43:24 3963 8192000000 10389995136 10788663296 1158505872 12.389 100 86 174 3 1482295088 303577248 20211968
08:44:35 4871 8192000000 10376954016 10790690816 1139187008 12.208 99 71 141 0 1467393808 311254656 16952144
08:45:51 2531 8192000000 10461247440 10857435136 1161941952 12.421 100 89 179 0 1556088912 372338976 21807984
08:46:59 4306 8192000000 10506955040 10907639808 1171197504 12.508 99 79 156 0 1587128208 396427744 19502960
08:48:07 8309 8192000000 10517423072 10969075712 1191489968 12.697 100 44 88 0 1619138752 416042096 11606688
08:49:19 4029 8192000000 10556683440 10981388288 1186993040 12.655 100 57 113 0 1647347216 446022272 14331904
08:50:30 19083 8192000000 10526593968 10996854784 1166252192 12.462 99 37 74 0 1647464992 471005024 10207776
08:51:52 10843 8192000000 10562143952 11017076736 1170096208 12.498 100 38 76 1 1642301584 462087808 10117568
08:53:11 31765 8192000000 10552609920 11049025536 1149657888 12.306 99 21 42 0 1635708064 480259600 5790576
08:55:39 46293 8192000000 10151948720 10783805440 1113898336 11.969 99 19 38 0 1516039152 396913824 5226992
08:57:26 43299 8192000000 10068295088 10522935296 1104977136 11.885 98 23 46 0 1142125888 32362096 4786656
08:59:19 23932 8192000000 10093635664 10528399360 1104270848 11.878 98 40 80 1 1171424976 58862576 8291552
09:00:49 116646 8192000000 10085213824 10515042304 1089137024 11.734 96 12 24 1 1124918048 33109488 2671536
09:03:52 60788 8192000000 10090800064 10525679616 1128275088 12.105 99 41 82 0 1182202160 44265984 9661088
09:05:54 56316 8192000000 10117680032 10554724352 1147251824 12.284 100 43 86 0 1194127824 38156848 8719152
09:07:59 37928 8192000000 10095358832 10537824256 1129500848 12.117 98 29 59 0 1171844496 35660112 6683536
09:09:37 73854 8192000000 10112551056 10568081408 1151501056 12.324 100 10 21 1 1206472768 52979136 1992576
09:11:52 64203 8192000000 10136335040 10589360128 1157476256 12.380 100 25 50 0 1242483520 79927248 5080016
09:14:05 58259 8192000000 10078615536 10566856704 1108259776 11.916 96 12 24 0 1185263776 74358864 2645136
09:16:16 105143 8192000000 10099891408 10561339392 1134588528 12.165 99 15 31 1 1197610224 59637856 3383840
09:19:02 91542 8192000000 10058806352 10574069760 1117658064 12.005 98 5 10 1 1167411136 48812304 940768
09:22:24 62536 8192000000 10112271568 10574065664 1135869104 12.177 98 25 50 0 1204484848 63367264 5248480
09:24:28 77215 8192000000 10139978672 10616733696 1129572560 12.117 98 25 50 0 1220931664 86055840 5303264
09:27:15 101188 8192000000 10085210144 10564984832 1079555280 11.643 94 11 22 0 1116424752 34806192 2063280
09:29:56 71597 8192000000 10050717184 10483077120 1076001760 11.609 94 18 36 2 1112797088 33091856 3703472
09:32:12 16002 8192000000 10111098416 10537345024 1111243424 11.944 98 59 118 1 1170297264 46164960 12888880
09:33:30 20588 8192000000 10071584688 10507505664 1086852960 11.713 96 31 62 0 1138111120 44941856 6316304
09:34:57 80008 8192000000 10021789520 10472501248 1071527648 11.567 96 27 54 0 1128674336 51846704 5299984
09:37:22 48027 8192000000 10033488080 10477936640 1073504576 11.586 97 6 12 0 1112753136 38000608 1247952
09:39:12 50882 8192000000 10034463664 10472407040 1106783808 11.902 100 11 19 0 1130246656 21621520 1841328
09:41:06 47313 8192000000 10070415008 10520920064 1104102048 11.877 99 28 55 3 1146639776 36745232 5792496
09:42:54 48771 8192000000 10016334672 10463727616 1056858352 11.426 95 16 32 0 1108573408 48143024 3572032
09:44:43 40756 8192000000 10092688720 10536738816 1096992176 11.809 98 37 72 2 1168935600 64641616 7301808
09:47:03 64540 8192000000 10097926320 10558914560 1112982400 11.961 99 12 24 0 1182114288 66588448 2543440
09:49:13 22332 8192000000 10064686960 10494603264 1115597664 11.985 99 27 54 0 1145877552 24271632 6008256
09:50:36 45865 8192000000 10096367776 10532478976 1109636736 11.929 98 49 97 1 1157516016 38296736 9582544
09:53:07 56926 8192000000 10053968368 10533777408 1120483776 12.032 99 9 18 0 1154820496 31881680 2455040
09:55:38 70535 8192000000 10079595888 10536611840 1117032816 11.999 99 16 33 1 1179318288 59009760 3275712
09:57:49 64139 8192000000 10095295040 10552614912 1122615232 12.052 98 18 36 4 1163510736 37535184 3360320
09:59:53 65428 8192000000 10106626608 10551304192 1117392624 12.002 98 16 32 0 1168638512 48169984 3075904
10:02:04 42315 8192000000 10087925728 10529660928 1138493536 12.201 100 25 50 1 1170021136 26500864 5026736
10:03:46 88489 8192000000 10107528064 10586558464 1139269776 12.209 99 16 32 0 1179593888 37024896 3299216
10:06:15 81767 8192000000 10134953808 10675818496 1110351632 11.936 96 13 26 1 1193790976 80008720 3430624
10:08:37 23703 8192000000 10088443552 10519465984 1139357808 12.209 99 36 71 3 1164762320 17304400 8100112
10:10:01 126648 8192000000 10076168416 10524463104 1131554624 12.136 99 17 34 0 1170340704 35449232 3336848
10:13:11 53994 8192000000 10053043040 10515554304 1121136096 12.038 99 14 28 0 1152442096 28437040 2868960
10:15:05 31421 8192000000 10031604400 10485141504 1076778048 11.617 95 13 27 0 1098759936 19356144 2625744
10:16:50 64609 8192000000 10078610176 10545213440 1105819920 11.893 98 13 24 2 1189840672 80763552 3257200
10:19:18 48609 8192000000 10084699376 10554802176 1127575824 12.099 100 20 40 1 1199842912 67370176 4896912
10:21:07 26128 8192000000 10060824208 10484781056 1095466656 11.795 97 36 71 2 1126771760 24325072 6980032
10:22:36 46759 8192000000 10106376512 10559750144 1124577952 12.070 100 37 75 0 1213192640 80762992 7851696
10:24:23 71180 8192000000 10072808000 10534981632 1065013424 11.504 94 11 22 0 1112539120 45243680 2282016
10:26:34 23441 8192000000 10010129824 10463707136 1039932896 11.264 93 22 43 1 1081784656 37940512 3911248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-04-2021 10:27:43 to 08-04-2021 10:27:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-04-2021 10:28:00 to 08-04-2021 10:28: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          246156
MON$OLDEST_ACTIVE               246157
MON$OLDEST_SNAPSHOT             246157
MON$NEXT_TRANSACTION            246157
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                       2121984
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             825
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2416 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2416 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2416 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.2416 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'

08.04.21 10:28: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
2021-04-08 10:28:05 No severe PSQL-related problems occured
08.04.21 10:28:06. End of report.