Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.06.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16824288 kB
MemAvailable 31456464 kB
Buffers 0 kB
Cached 14861644 kB
SwapCached 4 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.1
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-06-12 13:27:00
DTS_END                         2021-06-12 13:27:00.6830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44820
TRN_ID                          227256
ATT_ID                          611
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: 8794.08 22336 1055290
customer order: creation 158.43 447 19011
customer order: refuse 13.22 336 1586
order to supplier: creation 43.43 1398 5212
order to supplier: removal 3.28 114893 393
invoice (draft): creation 42.29 3564 5075
invoice (draft): removal 51.20 898 6144
invoice accept: apply 39.17 18701 4701
invoice accept: cancel 46.29 749 5555
customer reserve: creation 3629.14 182 435497
customer reserve: removal 4490.93 66 538911
realization accept: apply 77.93 197 9351
realization accept: cancel 79.04 122 9485
payment from customer: creation 55.02 122 6602
payment from customer: removal 5.22 135 626
payment to supplier: creation 45.83 146 5500
payment to supplier: removal 3.40 65 408
service: total inventory turnovers 3.49 3427 419
service: total monetary turnovers 5.82 553 698
service: aggregate perf. data 0.93 13804 111
service: refresh index statistics 0.04 286917 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-06-2021 13:28:54 to 12-06-2021 13:28:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5553
TEST_TIME 2 6084
TEST_TIME 3 6097
TEST_TIME 4 5558
TEST_TIME 5 6030
TEST_TIME 6 5929
TEST_TIME 7 6383
TEST_TIME 8 6112
TEST_TIME 9 6115
TEST_TIME 10 6161
TEST_TIME 11 6478
TEST_TIME 12 6513
TEST_TIME 13 6825
TEST_TIME 14 7289
TEST_TIME 15 7457
TEST_TIME 16 8166
TEST_TIME 17 8436
TEST_TIME 18 8551
TEST_TIME 19 8638
TEST_TIME 20 9138
TEST_TIME 21 9173
TEST_TIME 22 9443
TEST_TIME 23 9531
TEST_TIME 24 9705
TEST_TIME 25 9858
TEST_TIME 26 10471
TEST_TIME 27 11973
TEST_TIME 28 11733
TEST_TIME 29 11373
TEST_TIME 30 11424
TEST_TIME 31 11292
TEST_TIME 32 11161
TEST_TIME 33 11045
TEST_TIME 34 10829
TEST_TIME 35 10627
TEST_TIME 36 10608
TEST_TIME 37 10584
TEST_TIME 38 10475
TEST_TIME 39 10342
TEST_TIME 40 10310
TEST_TIME 41 10111
TEST_TIME 42 9984
TEST_TIME 43 9897
TEST_TIME 44 9757
TEST_TIME 45 9742
TEST_TIME 46 9670
TEST_TIME 47 9631
TEST_TIME 48 9531
TEST_TIME 49 9436
TEST_TIME 50 9506
TEST_TIME 51 9561
TEST_TIME 52 9577
TEST_TIME 53 9635
TEST_TIME 54 9609
TEST_TIME 55 9650
TEST_TIME 56 9582
TEST_TIME 57 9575
TEST_TIME 58 9576
TEST_TIME 59 9668
TEST_TIME 60 9598
TEST_TIME 61 9728
TEST_TIME 62 9681
TEST_TIME 63 9530
TEST_TIME 64 9416
TEST_TIME 65 9379
TEST_TIME 66 9336
TEST_TIME 67 9269
TEST_TIME 68 9252
TEST_TIME 69 9233
TEST_TIME 70 9240
TEST_TIME 71 9206
TEST_TIME 72 9210
TEST_TIME 73 9213
TEST_TIME 74 9181
TEST_TIME 75 9249
TEST_TIME 76 9204
TEST_TIME 77 9293
TEST_TIME 78 9301
TEST_TIME 79 9264
TEST_TIME 80 9255
TEST_TIME 81 9188
TEST_TIME 82 9082
TEST_TIME 83 9132
TEST_TIME 84 9057
TEST_TIME 85 8977
TEST_TIME 86 9000
TEST_TIME 87 9019
TEST_TIME 88 8981
TEST_TIME 89 9028
TEST_TIME 90 9002
TEST_TIME 91 8996
TEST_TIME 92 9016
TEST_TIME 93 8989
TEST_TIME 94 9038
TEST_TIME 95 9059
TEST_TIME 96 9112
TEST_TIME 97 9087
TEST_TIME 98 9099
TEST_TIME 99 9045
TEST_TIME 100 8958
TEST_TIME 101 8967
TEST_TIME 102 8922
TEST_TIME 103 8888
TEST_TIME 104 8867
TEST_TIME 105 8887
TEST_TIME 106 8865
TEST_TIME 107 8905
TEST_TIME 108 8864
TEST_TIME 109 8878
TEST_TIME 110 8870
TEST_TIME 111 8867
TEST_TIME 112 8879
TEST_TIME 113 8920
TEST_TIME 114 8971
TEST_TIME 115 9022
TEST_TIME 116 8992
TEST_TIME 117 8925
TEST_TIME 118 8902
TEST_TIME 119 8857
TEST_TIME 120 8795

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-06-2021 13:28:58 to 12-06-2021 13:29:02.

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 1458496 1458496 0 0.00 0 14892 6 0 0
doc_list_biud 1458493 1458493 0 0.00 0 66607 31 0 0
sp_add_invoice_to_stock 4770 4701 69 1.44 33 191118 18702 0 69
sp_cancel_adding_invoice 5555 5555 0 0.00 4 79596 750 0 0
sp_cancel_client_order 1586 1586 0 0.00 4 13664 336 0 0
sp_cancel_customer_reserve 538911 538911 0 0.00 1 66609 66 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 12119 136 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 2366 66 0 0
sp_cancel_supplier_invoice 6153 6144 9 0.14 1 33801 898 0 9
sp_cancel_supplier_order 393 393 0 0.00 13 939140 114894 0 0
sp_cancel_write_off 9485 9485 0 0.00 1 9688 123 0 0
sp_client_order 19011 19011 0 0.00 4 64982 447 0 0
sp_customer_reserve 435497 435497 0 0.00 0 90368 182 0 0
sp_fill_shopping_cart 29278 29278 0 0.00 1 40789 48 0 0
sp_fill_shopping_cart_clo_res 435496 435496 0 0.00 0 23896 20 0 0
sp_get_clo_for_invoice 4700 4700 0 0.00 0 35692 550 0 0
sp_kill_cost_storno 16074 16074 0 0.00 0 7151 59 0 0
sp_kill_qstorno_ret_qs2qd 1093844 1093844 0 0.00 0 66586 36 0 0
sp_kill_qty_storno 576124 576124 0 0.00 0 66606 77 0 0
sp_lock_selected_doc 19362 19362 0 0.00 0 8213 6 0 0
sp_make_cost_storno 26153 26153 0 0.00 0 11381 57 0 0
sp_multiply_rows_for_pdistr 26152 26152 0 0.00 0 7739 35 0 0
sp_multiply_rows_for_qdistr 19011 19011 0 0.00 0 64974 264 0 0
sp_pay_from_customer 6602 6602 0 0.00 4 12390 123 0 0
sp_pay_to_supplier 5500 5500 0 0.00 5 12408 147 0 0
sp_payment_common 12102 12102 0 0.00 1 5606 24 0 0
sp_qd_handle_on_cancel_clo 1586 1586 0 0.00 0 13657 289 0 0
sp_qd_handle_on_invoice_upd_sts 10255 10255 0 0.00 0 21047 366 0 0
sp_qd_handle_on_reserve_upd_sts 9350 9350 0 0.00 0 12937 62 0 0
sp_reserve_write_off 9351 9351 0 0.00 7 12952 197 0 0
sp_supplier_invoice 5075 5075 0 0.00 6 93932 3564 0 0
sp_supplier_order 5212 5212 0 0.00 6 44408 1398 0 0
srv_aggregate_perf_data 111 111 0 0.00 2175 326953 13804 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 850 118898 48389 0 0
srv_find_qd_qs_mism 1010751 1010751 0 0.00 0 14892 5 0 0
srv_make_invnt_saldo 419 419 0 0.00 41 88395 3428 0 0
srv_make_money_saldo 698 698 0 0.00 1 15245 553 0 0
srv_recalc_idx_stat 5 5 0 0.00 126810 489093 286917 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4701 4701 0 0.00 0 37019 168 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 2566 95 0 0
t$perf-norm:sp_cancel_client_order 1586 1586 0 0.00 0 8088 16 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 596 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 1651 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1485 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 5199 29 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 27621 1401 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 545 4 0 0
t$perf-norm:sp_client_order 19011 19011 0 0.00 0 18866 19 0 0
t$perf-norm:sp_customer_reserve 904 904 0 0.00 0 2144 7 0 0
t$perf-norm:sp_pay_from_customer 6602 6602 0 0.00 0 4013 7 0 0
t$perf-norm:sp_pay_to_supplier 5500 5500 0 0.00 0 2426 7 0 0
t$perf-norm:sp_reserve_write_off 9351 9351 0 0.00 0 4521 9 0 0
t$perf-norm:sp_supplier_invoice 5075 5075 0 0.00 0 29326 20 0 0
t$perf-norm:sp_supplier_order 5212 5212 0 0.00 0 3951 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 355 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 15727 255 0 0
t$perf-norm:srv_make_invnt_saldo 419 419 0 0.00 0 675 8 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 675 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9538 9400 138 1.44 0 9857 19 0 138
v_all_customers 34654 34654 0 0.00 0 184 1 0 0
v_all_suppliers 28502 28502 0 0.00 0 35 1 0 0
v_all_wares 38018 38018 0 0.00 0 186 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 6139 16 0 0
v_cancel_client_order 3172 3172 0 0.00 0 6188 10 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 2514 10 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 1515 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2320 6 0 0
v_cancel_supplier_invoice 736 718 18 2.44 0 2701 21 0 18
v_cancel_supplier_order 786 786 0 0.00 0 3222 11 0 0
v_cancel_write_off 1894 1894 0 0.00 0 7101 21 0 0
v_max_id_clo_ord 5211 5211 0 0.00 0 1658 2 0 0
v_max_id_clo_res 904 904 0 0.00 0 3161 14 0 0
v_max_id_ord_sup 5058 5058 0 0.00 0 3845 4 0 0
v_max_non_paid_invoice 1518 1518 0 0.00 0 1177 4 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 5497 4 0 0
v_min_id_clo_ord 5211 5211 0 0.00 0 5675 8 0 0
v_min_id_clo_res 904 904 0 0.00 0 6370 17 0 0
v_min_id_ord_sup 5058 5058 0 0.00 0 6160 12 0 0
v_min_non_paid_invoice 5500 5500 0 0.00 0 5496 17 0 0
v_min_non_paid_realizn 6602 6602 0 0.00 0 5602 15 0 0
v_our_firm 9536 9536 0 0.00 0 15 1 0 0
v_reserve_write_off 18700 18700 0 0.00 0 8367 7 0 0
x_lock_depdocs_on_canc_invoice 11340 11340 0 0.00 0 15020 100 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 11687 353 0 0
x_make_qstorno_1000_1200 5212 5212 0 0.00 2 44351 1315 0 0
x_make_qstorno_1200_2000 5075 5075 0 0.00 2 87634 3376 0 0
x_make_qstorno_2100_3300 435497 435497 0 0.00 0 90364 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-06-2021 13:29:03 to 12-06-2021 13:29:07.

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:57:04 5 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:05 30331 8192000000 9720277424 10018127872 795927312 8.855 100 83 164 1 823381504 7534032 19920160
10:59:41 3002 8192000000 9751092624 10056216576 831246336 9.212 100 84 168 0 863534080 12311920 19975824
11:00:49 22849 8192000000 9794774736 10110472192 854776912 9.448 100 88 176 1 901546944 26112816 20657216
11:02:16 12380 8192000000 9859117728 10192879616 909522016 9.993 100 73 146 1 967692128 41648944 16521168
11:03:30 8986 8192000000 9903182000 10249871360 951138144 10.402 100 81 161 1 1020195856 51198816 17858896
11:04:42 6197 8192000000 9956340352 10312359936 988330512 10.765 100 71 142 3 1069756944 66696208 14730224
11:05:51 3379 8192000000 9990730704 10352971776 1020878304 11.080 100 83 166 1 1106342240 69134928 16329008
11:06:55 2732 8192000000 10020082272 10389544960 1042359568 11.287 100 89 178 0 1132472016 72448288 17664160
11:07:58 1372 8192000000 10015914320 10395492352 1063316400 11.488 100 63 126 0 1133978160 57268544 13393216
11:09:02 11129 8192000000 10005761792 10414907392 1072532352 11.576 100 44 88 0 1124685568 43080592 9072624
11:10:18 11945 8192000000 9982099488 10380275712 1077344640 11.622 100 37 75 1 1102180784 16690896 8145248
11:11:31 7002 8192000000 10021211488 10420854784 1084442480 11.690 100 33 66 1 1129172272 38089408 6640384
11:12:39 6109 8192000000 10011433760 10410147840 1094725824 11.788 100 39 78 4 1125359792 21843296 8790672
11:13:48 4254 8192000000 10022692784 10420154368 1099675936 11.835 100 42 85 1 1130854816 21988928 9189952
11:14:53 36511 8192000000 10008423824 10404134912 1091253184 11.755 99 48 97 1 1117679904 15046704 11380016
11:16:32 6853 8192000000 10025687648 10414878720 1091997920 11.762 100 44 84 3 1131445696 30164688 9283088
11:17:40 6020 8192000000 9999441424 10398031872 1072034384 11.572 98 42 83 1 1097626064 16692192 8899488
11:18:47 11196 8192000000 9963194496 10362261504 1016491776 11.038 96 23 46 0 1048310896 26616848 5202272
11:19:59 30366 8192000000 9948080912 10339332096 998069920 10.860 96 49 100 4 1021325008 12339792 10915296
11:21:35 6576 8192000000 9957996592 10342387712 1011985920 10.995 97 41 80 3 1039954272 18228112 9740240
11:22:43 5897 8192000000 9970165840 10353606656 1051207616 11.372 100 48 95 4 1087299696 24914080 11178000
11:23:50 11138 8192000000 9984300928 10382876672 1050970096 11.370 99 29 57 2 1084099616 26974640 6154880
11:25:02 39975 8192000000 9973741792 10385625088 1068768064 11.540 100 33 65 2 1100272544 24627792 6876688
11:26:42 7065 8192000000 9980542576 10385739776 1071938320 11.571 100 35 71 2 1097866336 18039776 7888240
11:27:56 37221 8192000000 10043726064 10443640832 1098729392 11.826 100 36 72 0 1136251552 29976160 7546000
11:29:39 13030 8192000000 10112753824 10519318528 1124173120 12.066 100 61 121 1 1226862192 89696384 12992688
11:30:56 67427 8192000000 10140335840 10565910528 1133596800 12.155 100 50 100 2 1250614224 106900384 10117040
11:33:08 7963 8192000000 10160939536 10568732672 1144148688 12.255 100 51 100 0 1256376176 100795824 11431664
11:34:20 16299 8192000000 10196388384 10615087104 1156431712 12.370 100 60 120 1 1295700368 126476560 12792096
11:35:50 5418 8192000000 10202534928 10613407744 1169855120 12.495 100 65 128 2 1307106464 123175120 14076224
11:36:58 5353 8192000000 10259810448 10680078336 1188462432 12.669 100 57 110 2 1367921680 167208144 12251104
11:38:06 3362 8192000000 10323485488 10733858816 1173457824 12.529 99 74 148 2 1419923184 229421152 17044208
11:39:15 2248 8192000000 10340087936 10746191872 1173889904 12.533 100 78 155 0 1442464720 250903440 17671376
11:40:22 6589 8192000000 10387855088 10803548160 1183751328 12.625 100 65 126 0 1487888544 288704800 15432416
11:41:32 847 8192000000 10389859568 10791968768 1172205936 12.517 100 88 174 0 1486653136 294834304 19612896
11:42:38 1981 8192000000 10379076896 10818596864 1146354496 12.275 100 81 161 0 1482799712 317822176 18623040
11:43:45 3022 8192000000 10376192400 10789400576 1163545424 12.436 100 80 159 0 1478346656 296119760 18681472
11:44:51 13336 8192000000 10343788976 10764664832 1150666224 12.316 100 48 96 0 1445772736 282744576 12361936
11:46:14 4807 8192000000 10401207936 10804940800 1152265088 12.331 100 67 133 1 1495095584 327130240 15700256
11:47:28 11268 8192000000 10353326496 10793885696 1163843552 12.439 100 39 77 2 1499559488 325765360 9950576
11:48:49 14828 8192000000 10397578160 10820358144 1142123824 12.236 98 33 66 2 1474034064 323042544 8867696
11:50:16 13214 8192000000 10383658352 10813775872 1139307808 12.209 99 35 70 0 1490969728 342177280 9484640
11:51:44 15533 8192000000 10397597712 10851250176 1112520720 11.956 98 30 59 1 1493440288 372881856 8037712
11:53:07 36207 8192000000 10390943568 10901729280 1126114912 12.085 100 22 44 2 1509359728 377043696 6201120
11:55:00 91553 8192000000 10090896848 10537259008 1120206544 12.029 99 29 58 0 1197018144 69333024 7478576
11:57:45 47198 8192000000 10097213024 10554990592 1132792112 12.148 100 14 29 1 1205547120 69575088 3179920
11:59:46 69234 8192000000 10085815472 10610298880 1104463456 11.880 98 9 18 0 1170454848 65037328 954064
12:02:03 63897 8192000000 10102679536 10544283648 1111252048 11.944 98 25 51 1 1173420208 56912400 5255760
12:04:18 53116 8192000000 10098151200 10564366336 1110525856 11.937 98 24 48 0 1200125840 84769488 4830496
12:06:33 88722 8192000000 10064323360 10542706688 1131304608 12.134 100 20 41 1 1205654016 70021712 4327696
12:09:29 38285 8192000000 10085142288 10542657536 1139696384 12.213 100 25 50 0 1188910912 43804784 5409744
12:11:19 82237 8192000000 10109338544 10595409920 1140515152 12.220 100 24 48 4 1227143376 81093824 5534400
12:13:53 40335 8192000000 10112962064 10584289280 1144913152 12.262 100 14 27 0 1228155312 80171952 3070208
12:15:36 94348 8192000000 10117088784 10613313536 1136626768 12.184 99 13 25 1 1247019184 107600832 2791584
12:18:14 66719 8192000000 10123753504 10581688320 1138642496 12.203 99 24 48 0 1239020368 95114336 5263536
12:21:54 59136 8192000000 10082851504 10536005632 1151297232 12.322 100 18 36 2 1219959920 64850512 3812176
12:24:00 23723 8192000000 10104257600 10567782400 1146106832 12.273 99 44 88 1 1203113584 47479872 9526880
12:25:46 105155 8192000000 10084023808 10559709184 1109653056 11.929 96 21 42 0 1166115264 52282448 4179760
12:28:31 97285 8192000000 10103742208 10594426880 1152471936 12.333 100 14 28 0 1242656624 87252688 2932000
12:31:29 52942 8192000000 10092717824 10552242176 1118646976 12.014 97 6 12 0 1165909296 46146640 1115680
12:33:22 27908 8192000000 10057141120 10569793536 1127870688 12.101 99 2 4 0 1158112688 30166512 75488
12:34:51 27878 8192000000 10057726624 10550267904 1120363344 12.030 99 7 14 0 1155072784 33596336 1113104
12:36:19 38087 8192000000 10059169360 10502512640 1117539072 12.004 98 13 26 0 1146791376 26676064 2576240
12:38:03 58929 8192000000 10077832336 10510790656 1085707488 11.702 95 29 57 1 1136514528 44082896 6724144
12:40:02 44931 8192000000 10096115456 10542817280 1110133184 11.934 98 28 56 0 1174585568 58853200 5599184
12:41:48 90707 8192000000 10079862160 10548355072 1091782416 11.760 96 14 28 1 1168029424 73477408 2769600
12:44:19 50660 8192000000 10084568384 10563039232 1116078912 11.990 99 19 38 0 1168531936 48266320 4186704
12:46:18 27055 8192000000 10064254240 10496802816 1119893760 12.026 99 36 70 2 1162840832 36038688 6908384
12:47:50 42826 8192000000 10092972544 10522591232 1110773568 11.940 98 37 74 2 1154027472 35751264 7502640
12:49:52 83556 8192000000 10072671616 10564685824 1107784640 11.911 98 27 55 0 1198268112 84323632 6159840
12:52:16 51777 8192000000 10101228768 10586791936 1111041152 11.942 99 9 18 1 1184715584 71436736 2237696
12:54:09 47421 8192000000 10036709664 10506477568 1082115024 11.668 96 19 37 0 1120833376 34606864 4111488
12:55:57 106186 8192000000 10040796000 10490224640 1075565280 11.605 96 17 34 1 1134113872 54313184 4235408
12:58:44 95125 8192000000 10072815856 10538381312 1086429600 11.709 97 30 60 0 1152988032 60004544 6553888
13:01:21 54579 8192000000 10093735760 10559324160 1107413136 11.908 99 26 52 0 1173174992 60785968 4975888
13:03:23 88678 8192000000 10064950656 10531164160 1070102720 11.553 95 11 22 1 1110200208 38106480 1991008
13:05:53 118898 8192000000 10095508608 10549538816 1136046480 12.178 100 26 52 1 1195364880 53770800 5547600
13:08:59 58484 8192000000 10065727184 10530779136 1126003152 12.084 99 6 12 0 1162063888 34933168 1127568
13:11:00 91172 8192000000 10095654912 10592743424 1123370304 12.059 99 9 18 0 1155147280 30023328 1753648
13:13:32 49302 8192000000 10130098624 10582458368 1137785248 12.195 99 25 51 1 1196672704 53963152 4924304
13:15:21 31810 8192000000 10075129728 10510028800 1119418016 12.021 98 32 64 0 1144446048 18315104 6712928
13:16:53 58800 8192000000 10116353712 10576531456 1149713472 12.307 100 18 36 0 1207486336 54201552 3571312
13:18:57 83490 8192000000 10085325344 10598088704 1121426912 12.040 98 14 28 1 1196806064 72688080 2691072
13:21:21 41510 8192000000 10127789264 10620489728 1136079856 12.179 99 11 22 1 1201697840 63429088 2188896
13:23:04 117195 8192000000 10046232992 10494160896 1115015168 11.980 98 20 40 1 1133395744 14197792 4182784
13:26:08 59279 8192000000 9753226336 10172592128 845574480 9.356 75 6 12 0 874686480 28121328 990672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-06-2021 13:29:08 to 12-06-2021 13:29:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-06-2021 13:29:24 to 12-06-2021 13:29:26.

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          227257
MON$OLDEST_ACTIVE               227417
MON$OLDEST_SNAPSHOT             227417
MON$NEXT_TRANSACTION            227417
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                       2094016
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2515 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2515 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2515 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2515 Firebird 4.0/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 ---
:20: 
:21: mscs-test-161.budzdorov.ru    Sat Jun 12 13:27:11 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Jun 12 13:27:17 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

12.06.21 13:29:27. 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-06-12 13:29:30 No severe PSQL-related problems occured
12.06.21 13:29:31. End of report.