Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.2020 13:28
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 16950628 kB
MemAvailable 31535620 kB
Buffers 0 kB
Cached 14674696 kB
SwapCached 1964 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-05 13:26:53
DTS_END                         2020-12-05 13:26:53.5590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49912
TRN_ID                          232454
ATT_ID                          651
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: 8971.07 29526 1076528
customer order: creation 157.32 397 18878
customer order: refuse 14.04 301 1685
order to supplier: creation 42.35 1315 5082
order to supplier: removal 3.33 122897 400
invoice (draft): creation 44.47 3631 5336
invoice (draft): removal 52.23 959 6267
invoice accept: apply 40.16 18745 4819
invoice accept: cancel 46.53 904 5583
customer reserve: creation 3735.71 181 448285
customer reserve: removal 4550.72 70 546086
realization accept: apply 79.91 189 9589
realization accept: cancel 84.13 114 10095
payment from customer: creation 55.17 121 6620
payment from customer: removal 5.38 91 646
payment to supplier: creation 45.77 160 5492
payment to supplier: removal 3.30 90 396
service: total inventory turnovers 3.77 3391 452
service: total monetary turnovers 5.93 565 711
service: aggregate perf. data 0.84 19664 101
service: refresh index statistics 0.04 416742 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2020 13:29:17 to 05-12-2020 13:29:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3691
TEST_TIME 2 4393
TEST_TIME 3 4727
TEST_TIME 4 5244
TEST_TIME 5 6226
TEST_TIME 6 6577
TEST_TIME 7 7278
TEST_TIME 8 7649
TEST_TIME 9 7602
TEST_TIME 10 7583
TEST_TIME 11 7700
TEST_TIME 12 7801
TEST_TIME 13 7782
TEST_TIME 14 7964
TEST_TIME 15 8450
TEST_TIME 16 8469
TEST_TIME 17 8453
TEST_TIME 18 8352
TEST_TIME 19 8666
TEST_TIME 20 9048
TEST_TIME 21 8918
TEST_TIME 22 9141
TEST_TIME 23 9019
TEST_TIME 24 9135
TEST_TIME 25 9175
TEST_TIME 26 10014
TEST_TIME 27 10751
TEST_TIME 28 11227
TEST_TIME 29 11544
TEST_TIME 30 11301
TEST_TIME 31 11001
TEST_TIME 32 10875
TEST_TIME 33 10688
TEST_TIME 34 10476
TEST_TIME 35 10338
TEST_TIME 36 10181
TEST_TIME 37 10137
TEST_TIME 38 9966
TEST_TIME 39 9828
TEST_TIME 40 9822
TEST_TIME 41 9989
TEST_TIME 42 9901
TEST_TIME 43 9816
TEST_TIME 44 9806
TEST_TIME 45 9665
TEST_TIME 46 9612
TEST_TIME 47 9698
TEST_TIME 48 9675
TEST_TIME 49 9606
TEST_TIME 50 9517
TEST_TIME 51 9662
TEST_TIME 52 9614
TEST_TIME 53 9602
TEST_TIME 54 9765
TEST_TIME 55 9661
TEST_TIME 56 9654
TEST_TIME 57 9652
TEST_TIME 58 9693
TEST_TIME 59 9702
TEST_TIME 60 9589
TEST_TIME 61 9474
TEST_TIME 62 9366
TEST_TIME 63 9260
TEST_TIME 64 9179
TEST_TIME 65 9183
TEST_TIME 66 9199
TEST_TIME 67 9160
TEST_TIME 68 9094
TEST_TIME 69 9064
TEST_TIME 70 9143
TEST_TIME 71 9124
TEST_TIME 72 9268
TEST_TIME 73 9266
TEST_TIME 74 9327
TEST_TIME 75 9299
TEST_TIME 76 9334
TEST_TIME 77 9366
TEST_TIME 78 9353
TEST_TIME 79 9267
TEST_TIME 80 9191
TEST_TIME 81 9231
TEST_TIME 82 9146
TEST_TIME 83 9115
TEST_TIME 84 9103
TEST_TIME 85 9084
TEST_TIME 86 9043
TEST_TIME 87 9039
TEST_TIME 88 9005
TEST_TIME 89 8990
TEST_TIME 90 9044
TEST_TIME 91 9066
TEST_TIME 92 9018
TEST_TIME 93 8999
TEST_TIME 94 9181
TEST_TIME 95 9215
TEST_TIME 96 9201
TEST_TIME 97 9187
TEST_TIME 98 9098
TEST_TIME 99 9074
TEST_TIME 100 9012
TEST_TIME 101 8954
TEST_TIME 102 8988
TEST_TIME 103 8987
TEST_TIME 104 8982
TEST_TIME 105 8967
TEST_TIME 106 8967
TEST_TIME 107 8981
TEST_TIME 108 9003
TEST_TIME 109 9022
TEST_TIME 110 9011
TEST_TIME 111 9070
TEST_TIME 112 9047
TEST_TIME 113 9066
TEST_TIME 114 9094
TEST_TIME 115 9089
TEST_TIME 116 9086
TEST_TIME 117 9120
TEST_TIME 118 9051
TEST_TIME 119 8995
TEST_TIME 120 8973

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-12-2020 13:29:21 to 05-12-2020 13:29:24.

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 1489879 1489879 0 0.00 0 44910 7 0 0
doc_list_biud 1489879 1489879 0 0.00 0 38768 32 0 0
sp_add_invoice_to_stock 4956 4819 137 2.76 40 163838 18745 0 137
sp_cancel_adding_invoice 5583 5583 0 0.00 4 72717 905 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 14936 301 0 0
sp_cancel_customer_reserve 546086 546086 0 0.00 1 23145 70 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 2 4168 92 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 3646 90 0 0
sp_cancel_supplier_invoice 6281 6267 14 0.22 2 30254 960 0 14
sp_cancel_supplier_order 400 400 0 0.00 8 918526 122897 0 0
sp_cancel_write_off 10095 10095 0 0.00 1 19841 114 0 0
sp_client_order 18878 18878 0 0.00 4 29412 398 0 0
sp_customer_reserve 448285 448285 0 0.00 0 92529 181 0 0
sp_fill_shopping_cart 29277 29277 0 0.00 1 10812 44 0 0
sp_fill_shopping_cart_clo_res 448280 448280 0 0.00 0 21497 18 0 0
sp_get_clo_for_invoice 4813 4813 0 0.00 0 43768 461 0 0
sp_kill_cost_storno 16720 16720 0 0.00 0 8134 57 0 0
sp_kill_qstorno_ret_qs2qd 1108934 1108934 0 0.00 0 30247 38 0 0
sp_kill_qty_storno 584518 584518 0 0.00 0 38768 81 0 0
sp_lock_selected_doc 19860 19860 0 0.00 0 8992 6 0 0
sp_make_cost_storno 26514 26514 0 0.00 0 44524 65 0 0
sp_multiply_rows_for_pdistr 26514 26514 0 0.00 0 9441 32 0 0
sp_multiply_rows_for_qdistr 18878 18878 0 0.00 0 17311 223 0 0
sp_pay_from_customer 6620 6620 0 0.00 4 20169 122 0 0
sp_pay_to_supplier 5492 5492 0 0.00 4 25200 160 0 0
sp_payment_common 12112 12112 0 0.00 2 23110 33 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 10461 269 0 0
sp_qd_handle_on_invoice_upd_sts 10396 10396 0 0.00 0 38767 387 0 0
sp_qd_handle_on_reserve_upd_sts 9589 9589 0 0.00 0 14852 50 0 0
sp_reserve_write_off 9589 9589 0 0.00 6 22419 190 0 0
sp_supplier_invoice 5336 5336 0 0.00 6 94842 3631 0 0
sp_supplier_order 5082 5082 0 0.00 5 59952 1316 0 0
srv_aggregate_perf_data 101 101 0 0.00 2803 445932 19664 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1294 165155 51168 0 0
srv_find_qd_qs_mism 1030632 1030632 0 0.00 0 39016 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 64 86244 3392 0 0
srv_make_money_saldo 711 711 0 0.00 1 10804 566 0 0
srv_recalc_idx_stat 5 5 0 0.00 261304 608302 416742 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4819 4819 0 0.00 0 19188 169 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 3296 89 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 2325 11 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 2486 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 1237 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 5155 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 592 13 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 20412 1537 0 0
t$perf-norm:sp_cancel_write_off 880 880 0 0.00 0 1264 7 0 0
t$perf-norm:sp_client_order 18878 18878 0 0.00 0 4178 15 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 3239 6 0 0
t$perf-norm:sp_pay_from_customer 6620 6620 0 0.00 0 2657 7 0 0
t$perf-norm:sp_pay_to_supplier 5492 5492 0 0.00 0 4815 8 0 0
t$perf-norm:sp_reserve_write_off 9589 9589 0 0.00 0 9532 9 0 0
t$perf-norm:sp_supplier_invoice 5336 5336 0 0.00 0 2196 13 0 0
t$perf-norm:sp_supplier_order 5082 5082 0 0.00 0 2348 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 3580 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 901 20 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 4697 18 0 0
t$perf-norm:srv_make_money_saldo 711 711 0 0.00 0 2602 14 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 9 3 0 0
v_add_invoice_to_stock 9900 9626 274 2.76 0 9134 19 0 274
v_all_customers 33996 33996 0 0.00 0 59 1 0 0
v_all_suppliers 28844 28844 0 0.00 0 19 1 0 0
v_all_wares 37752 37752 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 3290 13 0 0
v_cancel_client_order 3370 3370 0 0.00 0 4462 9 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 3033 10 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1973 6 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2741 11 0 0
v_cancel_supplier_invoice 810 782 28 3.45 0 3408 21 0 28
v_cancel_supplier_order 800 800 0 0.00 0 6683 17 0 0
v_cancel_write_off 1760 1760 0 0.00 0 3216 17 0 0
v_max_id_clo_ord 5080 5080 0 0.00 0 988 2 0 0
v_max_id_clo_res 968 968 0 0.00 0 3706 17 0 0
v_max_id_ord_sup 5321 5321 0 0.00 0 4375 5 0 0
v_max_non_paid_invoice 1471 1471 0 0.00 0 331 3 0 0
v_max_non_paid_realizn 3674 3674 0 0.00 0 5418 6 0 0
v_min_id_clo_ord 5080 5080 0 0.00 0 4174 8 0 0
v_min_id_clo_res 968 968 0 0.00 0 7716 19 0 0
v_min_id_ord_sup 5321 5321 0 0.00 0 6441 11 0 0
v_min_non_paid_invoice 5492 5492 0 0.00 0 22897 30 0 0
v_min_non_paid_realizn 6620 6620 0 0.00 0 17866 19 0 0
v_our_firm 9648 9648 0 0.00 0 19 1 0 0
v_reserve_write_off 19178 19178 0 0.00 0 5906 8 0 0
x_lock_depdocs_on_canc_invoice 11459 11459 0 0.00 0 25405 143 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 23751 559 0 0
x_make_qstorno_1000_1200 5082 5082 0 0.00 2 59922 1237 0 0
x_make_qstorno_1200_2000 5336 5336 0 0.00 2 92780 3467 0 0
x_make_qstorno_2100_3300 448285 448285 0 0.00 0 92529 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2020 13:29:25 to 05-12-2020 13:29:29.

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:57 5 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:57 25010 8192000000 9717048640 10015301632 797744992 8.873 100 91 182 0 828366384 8577520 22043872
10:59:28 16107 8192000000 9765749536 10072518656 839199072 9.292 100 93 186 0 872904160 12025824 21679264
11:00:48 19095 8192000000 9801044464 10115305472 868674912 9.587 100 87 174 1 908553952 19705200 20173840
11:02:13 10246 8192000000 9861772304 10189803520 906978992 9.967 100 84 167 3 973818096 48583312 18255792
11:03:26 10488 8192000000 9916421712 10257428480 954593424 10.436 100 83 166 2 1021204256 48772512 17838320
11:04:39 5802 8192000000 9979520016 10335797248 995909904 10.839 100 84 165 2 1086905344 74378368 16617072
11:05:48 1956 8192000000 9989122096 10350112768 1023297888 11.104 100 85 168 0 1107926352 67509664 17118800
11:06:52 2002 8192000000 10033165072 10400866304 1052984464 11.389 100 88 174 4 1147692976 75988496 18720016
11:07:54 1208 8192000000 10024180032 10395004928 1065063376 11.505 100 94 190 1 1132740032 47744704 19931952
11:08:56 20868 8192000000 9995675008 10373165056 1071071664 11.562 100 61 122 0 1101368464 16761984 13534816
11:10:18 8866 8192000000 9998203408 10402435072 1082686176 11.673 100 27 54 3 1112129952 23280928 6162848
11:11:27 8745 8192000000 10007877696 10409414656 1086267824 11.707 100 29 55 1 1115499584 23901888 5329872
11:12:37 7639 8192000000 9994334432 10402131968 1089330656 11.736 100 28 56 2 1112837920 16859424 6647840
11:13:46 33725 8192000000 10016048320 10414866432 1093045040 11.772 100 61 122 4 1122879632 16558896 13275696
11:15:26 7226 8192000000 10010935744 10422726656 1087337424 11.717 100 40 80 4 1129644000 34014336 8292240
11:16:35 4167 8192000000 9999288576 10396831744 1085147296 11.696 100 55 108 2 1108786736 12161392 11478048
11:17:45 10499 8192000000 9978663168 10385170432 1055987456 11.418 99 35 70 4 1090488240 26849712 7651072
11:18:57 49198 8192000000 9933609184 10335657984 963480080 10.523 92 29 59 0 986908736 16898576 6530080
11:20:47 8350 8192000000 9948085824 10341023744 1029701984 11.166 99 38 75 2 1062872848 24222224 8948640
11:21:57 7115 8192000000 9958708608 10347864064 1025038544 11.121 99 40 80 1 1050153264 15988576 9126144
11:23:05 10796 8192000000 9976723920 10372661248 1049909488 11.360 100 29 58 1 1076606704 19577232 7119984
11:24:20 45187 8192000000 9951899184 10358538240 1047979648 11.341 99 29 58 1 1072745824 18306880 6459296
11:26:10 4908 8192000000 9978889216 10383892480 1065261008 11.507 100 40 79 3 1100178800 25057488 9860304
11:27:17 19488 8192000000 10015810800 10409820160 1088368112 11.727 100 52 104 2 1120139776 19837008 11934656
11:28:41 78756 8192000000 10079163408 10491121664 1105398128 11.889 100 36 71 0 1184291872 71655040 7238704
11:31:06 19010 8192000000 10116282464 10528165888 1119338352 12.021 100 57 114 1 1228576048 97217472 12020224
11:32:28 11805 8192000000 10114594640 10527637504 1126431824 12.088 100 59 118 0 1215452288 76386864 12633600
11:33:43 5865 8192000000 10147568784 10554507264 1136577888 12.183 100 73 142 1 1256866112 104983360 15304864
11:34:56 9702 8192000000 10150168496 10556895232 1121671120 12.043 99 61 122 0 1249857232 115671040 12515072
11:36:13 1977 8192000000 10154476448 10548379648 1133345552 12.153 100 83 167 1 1257394736 104998528 19050656
11:37:20 3086 8192000000 10184856480 10578542592 1144419392 12.257 100 91 176 0 1288809952 124702480 19688080
11:38:28 4660 8192000000 10222151120 10620194816 1141938064 12.234 99 71 140 1 1319629904 161955296 15736544
11:39:38 4748 8192000000 10276187680 10675531776 1152872256 12.336 100 73 146 2 1372902336 204068304 15961776
11:40:47 3171 8192000000 10267196832 10664972288 1137634368 12.193 98 64 128 1 1354463776 202092944 14736464
11:41:54 7615 8192000000 10291435920 10712182784 1167305424 12.472 100 60 120 1 1397131296 215795424 14030448
11:43:05 1292 8192000000 10342649344 10742452224 1189243008 12.676 100 87 173 2 1443868608 235006736 19618864
11:44:09 4204 8192000000 10408972736 10817658880 1184590064 12.633 100 64 128 0 1504932608 304856192 15486352
11:45:19 2672 8192000000 10459386960 10861121536 1166188064 12.461 99 87 172 4 1545186288 358224448 20773776
11:46:26 13748 8192000000 10364821120 10817708032 1182757232 12.616 100 44 88 3 1506886640 312073424 12055984
11:47:56 1917 8192000000 10439603776 10844991488 1192940800 12.711 100 81 159 1 1534661744 323040880 18680064
11:48:59 10122 8192000000 10434895632 10874957824 1181914352 12.608 100 38 76 1 1540036928 348163616 9958960
11:50:22 14602 8192000000 10461283120 10911711232 1163533440 12.436 99 32 64 0 1553095168 380686272 8875456
11:51:38 11818 8192000000 10448578704 10918088704 1138039648 12.197 99 30 60 1 1545406736 398578320 8788768
11:52:56 39973 8192000000 10206054560 10684051456 1125630208 12.080 99 12 24 1 1325426240 196582048 3213984
11:54:44 34518 8192000000 10122692912 10608476160 1115424096 11.984 98 14 26 2 1211985056 92463728 4097232
11:56:21 92276 8192000000 10051822560 10511032320 1081373424 11.661 96 28 57 1 1123004752 36141984 5489344
11:58:58 133185 8192000000 10066794352 10529923072 1051953280 11.379 94 16 32 0 1098657008 43563664 3140064
12:02:17 52764 8192000000 10107242624 10560741376 1114953904 11.979 100 18 37 1 1191675072 72603584 4117584
12:04:12 130515 8192000000 10190038496 10636414976 1112085216 11.952 98 23 46 0 1215265760 97728496 5452048
12:08:19 85308 8192000000 10093382288 10568421376 1121834080 12.044 99 17 34 2 1192379024 67127152 3417792
12:10:46 93324 8192000000 10107831968 10568331264 1140225296 12.218 100 14 28 1 1188906688 45559888 3121504
12:13:20 84358 8192000000 10063204064 10541862912 1127028976 12.093 99 11 22 0 1177598176 48447632 2121568
12:15:53 62661 8192000000 10134887344 10575892480 1149668432 12.306 99 20 40 1 1212254240 58813552 3772256
12:18:00 114832 8192000000 10169675040 10668081152 1157467408 12.380 99 20 39 2 1261674224 99812160 4394656
12:20:55 38109 8192000000 10094533072 10520961024 1156182928 12.367 100 42 84 0 1190805712 24425440 10197344
12:22:33 43521 8192000000 10095281024 10526695424 1113671056 11.967 97 23 46 1 1162932480 44659264 4602160
12:24:19 71835 8192000000 10089440544 10567184384 1087302640 11.717 95 23 46 0 1175015840 83134160 4579040
12:26:33 132528 8192000000 10070815280 10523656192 1124198784 12.067 99 17 33 0 1161961232 34436592 3325856
12:30:00 63863 8192000000 10098289600 10549510144 1132979600 12.149 99 13 25 2 1187531168 51790608 2760960
12:32:17 80711 8192000000 10084782928 10538323968 1134296224 12.162 99 9 18 0 1175816736 39784528 1735984
12:34:38 46807 8192000000 10127432784 10568495104 1125074912 12.075 98 21 42 0 1191690176 61813328 4801936
12:36:25 66702 8192000000 10137215680 10573938688 1121855856 12.045 98 17 34 0 1234985936 109350320 3779760
12:38:38 86141 8192000000 10120909568 10587234304 1102486640 11.861 96 9 19 0 1175479456 71308208 1684608
12:41:12 25661 8192000000 10073960544 10511978496 1122641472 12.052 99 30 60 0 1160051536 32071088 5338976
12:42:39 81234 8192000000 10101949184 10582487040 1082203472 11.668 95 24 48 2 1162726576 75901488 4621616
12:45:19 165155 8192000000 10063722736 10563084288 1108750448 11.921 98 31 62 0 1178839360 63967184 6121728
12:49:05 26416 8192000000 10082109520 10562293760 1122131008 12.047 100 5 10 0 1189279664 66214736 933920
12:50:31 70451 8192000000 10043823504 10524057600 1109959328 11.932 98 8 16 1 1147955344 36307680 1688336
12:52:48 53453 8192000000 10086322304 10535976960 1103731232 11.873 97 20 39 2 1139055216 31317152 4006832
12:54:54 69058 8192000000 10088625296 10542067712 1090319760 11.746 97 29 58 0 1178230000 81374960 6535280
12:57:07 59546 8192000000 10102562992 10577584128 1103428416 11.870 98 35 70 2 1177102096 66480496 7193184
12:59:16 82866 8192000000 10103320800 10645708800 1103905168 11.875 99 20 40 1 1257755936 148951952 4898816
13:01:42 83532 8192000000 10062701872 10555011072 1094856848 11.789 99 6 12 0 1143907296 48138560 911888
13:04:06 99093 8192000000 10032340432 10503569408 1108028848 11.914 99 20 40 1 1134586736 22454016 4103872
13:08:39 60896 8192000000 10060364672 10568765440 1102007344 11.857 98 10 20 0 1160196464 56779184 1409936
13:10:46 56704 8192000000 10080719104 10522664960 1099428912 11.832 97 24 48 0 1151629120 47378112 4822096
13:12:51 52101 8192000000 10115809168 10556321792 1145882944 12.271 100 28 56 0 1213962352 62138736 5940672
13:14:45 58914 8192000000 10080088096 10536800256 1078392176 11.632 95 20 39 0 1130099440 47649104 4058160
13:16:46 40326 8192000000 10100787760 10559930368 1129679792 12.118 100 42 84 2 1197667616 59219504 8768320
13:18:29 16741 8192000000 10099584368 10523332608 1128736752 12.109 99 44 87 0 1176650288 38857632 9055904
13:19:51 86476 8192000000 10092462720 10523148288 1112255504 11.954 97 20 40 1 1175393792 58534304 4603984
13:22:19 6625 8192000000 10097017408 10506608640 1132776992 12.148 99 60 115 3 1166054304 21463600 11813712
13:23:26 115704 8192000000 10064746944 10503245824 1104100704 11.877 96 15 30 0 1139487792 32181648 3205440
13:26:42 26038 8192000000 9739247456 10150219776 845762384 9.358 74 3 5 0 865072736 18943984 366368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-12-2020 13:29:31 to 05-12-2020 13:29:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-12-2020 13:29:47 to 05-12-2020 13:29:48.

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          232638
MON$OLDEST_ACTIVE               232639
MON$OLDEST_SNAPSHOT             232639
MON$NEXT_TRANSACTION            232639
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                       2090240
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             790
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:16: 
:17: mscs-test-161.budzdorov.ru    Sat Dec  5 13:27:29 2020
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Dec  5 13:27:29 2020
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

05.12.20 13:29:50. 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-05 13:29:52 No severe PSQL-related problems occured
05.12.20 13:29:53. End of report.