Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.02.2021 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16878584 kB
MemAvailable 31174400 kB
Buffers 0 kB
Cached 14779980 kB
SwapCached 4876 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-02-08 10:26:57
DTS_END                         2021-02-08 10:26:57.7460
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/40290
TRN_ID                          224819
ATT_ID                          614
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: 8566.55 28608 1027986
customer order: creation 163.31 388 19597
customer order: refuse 13.51 340 1621
order to supplier: creation 42.73 1445 5128
order to supplier: removal 2.96 127458 355
invoice (draft): creation 43.83 3383 5260
invoice (draft): removal 46.45 947 5574
invoice accept: apply 39.58 18512 4749
invoice accept: cancel 42.01 952 5041
customer reserve: creation 3701.10 179 444132
customer reserve: removal 4197.11 72 503653
realization accept: apply 78.88 154 9465
realization accept: cancel 72.97 138 8756
payment from customer: creation 56.00 94 6720
payment from customer: removal 5.41 94 649
payment to supplier: creation 46.81 116 5617
payment to supplier: removal 3.23 82 388
service: total inventory turnovers 3.99 3159 479
service: total monetary turnovers 5.77 565 692
service: aggregate perf. data 0.88 13451 106
service: refresh index statistics 0.03 400629 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-02-2021 10:28:13 to 08-02-2021 10:28:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1600
TEST_TIME 2 2385
TEST_TIME 3 4083
TEST_TIME 4 4369
TEST_TIME 5 4235
TEST_TIME 6 3973
TEST_TIME 7 4471
TEST_TIME 8 4503
TEST_TIME 9 4623
TEST_TIME 10 4884
TEST_TIME 11 4913
TEST_TIME 12 5214
TEST_TIME 13 5185
TEST_TIME 14 5335
TEST_TIME 15 5411
TEST_TIME 16 6361
TEST_TIME 17 6762
TEST_TIME 18 6936
TEST_TIME 19 7429
TEST_TIME 20 7722
TEST_TIME 21 8027
TEST_TIME 22 8709
TEST_TIME 23 8932
TEST_TIME 24 9387
TEST_TIME 25 9824
TEST_TIME 26 10109
TEST_TIME 27 10231
TEST_TIME 28 10441
TEST_TIME 29 10089
TEST_TIME 30 9847
TEST_TIME 31 9833
TEST_TIME 32 9636
TEST_TIME 33 9685
TEST_TIME 34 9706
TEST_TIME 35 9689
TEST_TIME 36 9683
TEST_TIME 37 9593
TEST_TIME 38 9429
TEST_TIME 39 9296
TEST_TIME 40 9361
TEST_TIME 41 9391
TEST_TIME 42 9338
TEST_TIME 43 9293
TEST_TIME 44 9277
TEST_TIME 45 9252
TEST_TIME 46 9257
TEST_TIME 47 9312
TEST_TIME 48 9227
TEST_TIME 49 9174
TEST_TIME 50 9326
TEST_TIME 51 9337
TEST_TIME 52 9401
TEST_TIME 53 9387
TEST_TIME 54 9330
TEST_TIME 55 9198
TEST_TIME 56 9054
TEST_TIME 57 9047
TEST_TIME 58 9106
TEST_TIME 59 9008
TEST_TIME 60 8993
TEST_TIME 61 9004
TEST_TIME 62 8986
TEST_TIME 63 8937
TEST_TIME 64 8972
TEST_TIME 65 8955
TEST_TIME 66 8941
TEST_TIME 67 8964
TEST_TIME 68 9085
TEST_TIME 69 9080
TEST_TIME 70 9048
TEST_TIME 71 9064
TEST_TIME 72 9069
TEST_TIME 73 9147
TEST_TIME 74 9153
TEST_TIME 75 9120
TEST_TIME 76 9024
TEST_TIME 77 8985
TEST_TIME 78 8941
TEST_TIME 79 8855
TEST_TIME 80 8796
TEST_TIME 81 8789
TEST_TIME 82 8767
TEST_TIME 83 8735
TEST_TIME 84 8728
TEST_TIME 85 8706
TEST_TIME 86 8703
TEST_TIME 87 8790
TEST_TIME 88 8812
TEST_TIME 89 8798
TEST_TIME 90 8797
TEST_TIME 91 8813
TEST_TIME 92 8868
TEST_TIME 93 8945
TEST_TIME 94 8906
TEST_TIME 95 8828
TEST_TIME 96 8773
TEST_TIME 97 8757
TEST_TIME 98 8800
TEST_TIME 99 8751
TEST_TIME 100 8755
TEST_TIME 101 8724
TEST_TIME 102 8689
TEST_TIME 103 8718
TEST_TIME 104 8766
TEST_TIME 105 8790
TEST_TIME 106 8870
TEST_TIME 107 8864
TEST_TIME 108 8852
TEST_TIME 109 8839
TEST_TIME 110 8873
TEST_TIME 111 8814
TEST_TIME 112 8755
TEST_TIME 113 8723
TEST_TIME 114 8685
TEST_TIME 115 8652
TEST_TIME 116 8607
TEST_TIME 117 8592
TEST_TIME 118 8587
TEST_TIME 119 8577
TEST_TIME 120 8573

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2021 10:28:16 to 08-02-2021 10:28:20.

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 1438141 1438141 0 0.00 0 18028 7 0 0
doc_list_biud 1438140 1438140 0 0.00 0 37045 32 0 0
sp_add_invoice_to_stock 4859 4749 110 2.26 51 180095 18512 0 110
sp_cancel_adding_invoice 5041 5041 0 0.00 5 92152 952 0 0
sp_cancel_client_order 1621 1621 0 0.00 5 11617 341 0 0
sp_cancel_customer_reserve 503653 503653 0 0.00 1 20954 73 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 8710 94 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 10084 83 0 0
sp_cancel_supplier_invoice 5581 5574 7 0.12 2 37053 948 0 7
sp_cancel_supplier_order 355 355 0 0.00 9 1234568 127458 0 0
sp_cancel_write_off 8756 8756 0 0.00 1 14175 138 0 0
sp_client_order 19597 19597 0 0.00 4 36460 388 0 0
sp_customer_reserve 444132 444132 0 0.00 0 110786 180 0 0
sp_fill_shopping_cart 29976 29976 0 0.00 1 22809 50 0 0
sp_fill_shopping_cart_clo_res 444121 444121 0 0.00 0 26450 18 0 0
sp_get_clo_for_invoice 4736 4736 0 0.00 0 27845 408 0 0
sp_kill_cost_storno 14834 14834 0 0.00 0 10008 62 0 0
sp_kill_qstorno_ret_qs2qd 1021991 1021991 0 0.00 0 37043 39 0 0
sp_kill_qty_storno 539201 539201 0 0.00 0 37045 84 0 0
sp_lock_selected_doc 19595 19595 0 0.00 0 10145 6 0 0
sp_make_cost_storno 26538 26538 0 0.00 0 17998 51 0 0
sp_multiply_rows_for_pdistr 26538 26538 0 0.00 0 10014 30 0 0
sp_multiply_rows_for_qdistr 19597 19597 0 0.00 0 16286 211 0 0
sp_pay_from_customer 6720 6720 0 0.00 4 12806 95 0 0
sp_pay_to_supplier 5617 5617 0 0.00 5 8477 116 0 0
sp_payment_common 12337 12337 0 0.00 1 7605 17 0 0
sp_qd_handle_on_cancel_clo 1621 1621 0 0.00 0 11611 308 0 0
sp_qd_handle_on_invoice_upd_sts 9777 9777 0 0.00 0 26970 382 0 0
sp_qd_handle_on_reserve_upd_sts 9465 9465 0 0.00 0 11188 44 0 0
sp_reserve_write_off 9465 9465 0 0.00 7 11661 154 0 0
sp_supplier_invoice 5260 5260 0 0.00 7 105258 3384 0 0
sp_supplier_order 5128 5128 0 0.00 5 44206 1445 0 0
srv_aggregate_perf_data 106 106 0 0.00 2396 202420 13451 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2024 136514 48064 0 0
srv_find_qd_qs_mism 981990 981990 0 0.00 0 9456 6 0 0
srv_make_invnt_saldo 479 479 0 0.00 34 127037 3159 0 0
srv_make_money_saldo 692 692 0 0.00 1 19458 565 0 0
srv_recalc_idx_stat 4 4 0 0.00 190849 694881 400630 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4749 4749 0 0.00 0 44395 163 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 7498 109 0 0
t$perf-norm:sp_cancel_client_order 1621 1621 0 0.00 0 12854 23 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 4396 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1439 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1513 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 4616 25 0 0
t$perf-norm:sp_cancel_supplier_order 355 355 0 0.00 0 17043 1492 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 12784 34 0 0
t$perf-norm:sp_client_order 19597 19597 0 0.00 0 6242 18 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 4761 12 0 0
t$perf-norm:sp_pay_from_customer 6720 6720 0 0.00 0 11501 10 0 0
t$perf-norm:sp_pay_to_supplier 5617 5617 0 0.00 0 6087 7 0 0
t$perf-norm:sp_reserve_write_off 9465 9465 0 0.00 0 5527 11 0 0
t$perf-norm:sp_supplier_invoice 5260 5260 0 0.00 0 8302 21 0 0
t$perf-norm:sp_supplier_order 5128 5128 0 0.00 0 5652 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 18 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 610 11 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 680 8 0 0
t$perf-norm:srv_make_money_saldo 692 692 0 0.00 0 422 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9676 9456 220 2.27 0 3575 15 0 220
v_all_customers 35540 35540 0 0.00 0 89 1 0 0
v_all_suppliers 28962 28962 0 0.00 0 55 1 0 0
v_all_wares 39194 39194 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 335 6 0 0
v_cancel_client_order 3242 3242 0 0.00 0 2904 4 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2240 8 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 5955 11 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 3172 8 0 0
v_cancel_supplier_invoice 772 758 14 1.81 0 1810 17 0 14
v_cancel_supplier_order 710 710 0 0.00 0 745 6 0 0
v_cancel_write_off 1944 1944 0 0.00 0 1718 13 0 0
v_max_id_clo_ord 5126 5126 0 0.00 0 468 1 0 0
v_max_id_clo_res 951 951 0 0.00 0 4379 31 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 5505 8 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 73 2 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 2260 3 0 0
v_min_id_clo_ord 5126 5126 0 0.00 0 4371 8 0 0
v_min_id_clo_res 951 951 0 0.00 0 1137 3 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 6334 11 0 0
v_min_non_paid_invoice 5617 5617 0 0.00 0 7599 12 0 0
v_min_non_paid_realizn 6720 6720 0 0.00 0 3898 10 0 0
v_our_firm 9944 9944 0 0.00 0 16 1 0 0
v_reserve_write_off 18930 18930 0 0.00 0 2839 5 0 0
x_lock_depdocs_on_canc_invoice 10236 10236 0 0.00 0 17043 118 0 0
x_lock_depdocs_on_canc_sup_ord 355 355 0 0.00 0 9430 351 0 0
x_make_qstorno_1000_1200 5128 5128 0 0.00 2 44197 1370 0 0
x_make_qstorno_1200_2000 5260 5260 0 0.00 3 98105 3178 0 0
x_make_qstorno_2100_3300 444132 444132 0 0.00 0 110785 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2021 10:28:21 to 08-02-2021 10:28:25.

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 4 8192000000 8800065472 8811831296 5690176 0.069 2 2 3 0 5855440 103904 61360
07:58:02 32764 8192000000 9724620000 10022981632 796723120 8.863 100 86 172 1 825314816 7411984 21179712
07:59:37 23813 8192000000 9766671840 10073657344 836223056 9.262 100 86 172 0 868339440 11489408 20626976
08:01:10 16644 8192000000 9806682448 10124148736 874177808 9.642 100 83 166 1 912635696 19281104 19176784
08:02:33 8957 8192000000 9866851760 10200985600 916815904 10.065 100 73 145 0 973071216 40037568 16217744
08:04:00 9397 8192000000 9926835760 10274611200 974204112 10.628 100 78 157 0 1043819616 53582368 16033136
08:05:12 8093 8192000000 9967260656 10319552512 999192384 10.871 100 85 170 0 1084279440 67516672 17570384
08:06:23 3926 8192000000 9976882912 10343915520 1026633504 11.136 100 81 161 1 1098120272 55153520 16333248
08:07:29 2517 8192000000 10028965728 10396065792 1041881872 11.283 100 93 183 2 1139288608 78953856 18452880
08:08:33 2088 8192000000 10039470384 10407358464 1060535296 11.462 100 90 179 1 1146022144 67344416 18142432
08:09:37 1723 8192000000 10044019216 10416381952 1072987984 11.581 100 89 178 0 1151227584 59759744 18479856
08:10:39 10240 8192000000 9993143328 10372349952 1072642688 11.577 100 77 155 1 1103655824 13835456 17177680
08:11:50 6336 8192000000 9975166240 10382553088 1077040128 11.619 100 31 62 1 1101686320 17132048 7514144
08:12:56 10293 8192000000 9982806336 10391474176 1084300416 11.688 100 22 44 4 1108329344 18325232 5703696
08:14:09 17029 8192000000 9993971232 10404921344 1093531648 11.776 100 12 24 0 1118970176 23098176 2340352
08:15:28 9404 8192000000 10016731680 10427686912 1100295440 11.840 100 27 54 1 1130484080 24166928 6021712
08:16:40 14492 8192000000 10049182048 10436075520 1106333600 11.898 100 92 185 2 1146692032 19883792 20474640
08:17:55 7376 8192000000 10011593904 10420645888 1097955536 11.818 100 45 92 2 1140904416 33747136 9201744
08:19:04 9441 8192000000 9987940544 10392129536 1057049056 11.428 99 36 72 0 1085743280 20444352 8249872
08:20:15 74389 8192000000 9950640288 10347945984 990113680 10.783 94 19 38 1 1019252816 24766976 4372160
08:22:30 5904 8192000000 9933278368 10318737408 996192176 10.842 96 40 81 1 1014152720 10080912 7879632
08:23:41 4805 8192000000 9954228448 10337083392 1028036736 11.150 99 47 94 2 1048333760 10208048 10088976
08:24:47 72296 8192000000 9976470736 10355322880 1041458592 11.279 99 44 87 0 1064068320 12461168 10148560
08:27:02 28894 8192000000 9987351792 10378067968 1061676480 11.473 100 53 105 1 1092378416 19498624 11203312
08:28:41 23976 8192000000 10078787856 10467373056 1089825200 11.741 100 63 124 1 1167324816 65015968 12483648
08:30:10 52694 8192000000 10099592896 10505416704 1115224640 11.982 100 47 94 1 1201422720 75393200 10804880
08:32:25 16822 8192000000 10153133792 10558095360 1129919264 12.121 100 45 90 0 1254077232 114555536 9602432
08:33:54 14335 8192000000 10197121072 10610520064 1141901888 12.233 100 47 94 1 1297843552 144971008 10970656
08:35:17 6090 8192000000 10245527008 10654982144 1155567920 12.362 100 59 116 2 1350891552 181855488 13468144
08:36:29 7486 8192000000 10280519760 10689581056 1167877024 12.477 100 68 135 1 1393813232 209643024 16293184
08:37:38 13537 8192000000 10349773200 10763952128 1178728208 12.578 100 57 114 1 1455136912 263121520 13287184
08:38:59 7417 8192000000 10351122752 10764013568 1177582224 12.568 100 64 128 2 1450290544 257907280 14801040
08:40:16 2020 8192000000 10414569232 10817421312 1180463616 12.595 100 88 176 1 1513877616 312566192 20847808
08:41:25 4701 8192000000 10435949776 10849845248 1179070544 12.582 100 79 158 3 1547379952 348507728 19801680
08:42:34 2036 8192000000 10435038336 10858520576 1171836272 12.514 100 93 183 0 1522520304 329061808 21622224
08:43:39 11229 8192000000 10379577456 10801229824 1157071456 12.376 100 45 90 2 1478703296 309364624 12267216
08:44:56 3036 8192000000 10418824912 10835148800 1164170128 12.442 100 70 140 1 1519133232 337044176 17918928
08:46:03 15092 8192000000 10382908304 10836586496 1168887120 12.486 100 30 58 0 1491440576 314495184 8058272
08:47:24 11361 8192000000 10391480544 10830704640 1133117392 12.151 98 37 74 0 1472370992 329114800 10138800
08:48:42 20987 8192000000 10300322192 10749734912 1140820944 12.223 100 26 52 0 1415054672 266891728 7342000
08:50:08 29230 8192000000 10244322960 10710786048 1075781088 11.607 94 28 54 2 1319809728 237345920 6682720
08:51:52 62619 8192000000 10087396896 10538823680 1077448992 11.623 97 8 16 0 1162802144 83373792 1979360
08:53:56 53993 8192000000 10078456624 10579742720 1092443616 11.766 97 9 18 0 1146577872 52474848 1659408
08:56:31 136514 8192000000 10101500032 10528948224 1052231744 11.382 93 41 81 0 1118276384 57585872 8458768
09:00:00 56983 8192000000 10076697296 10544009216 1096774784 11.807 98 13 26 0 1202908272 103609456 2524032
09:01:58 31624 8192000000 10043320048 10533879808 1105239440 11.887 99 16 31 0 1169521248 61095104 3186704
09:03:32 41640 8192000000 10057755888 10504138752 1094918896 11.789 97 22 44 0 1135294448 36077312 4298240
09:06:56 66672 8192000000 10048145760 10603253760 1098582416 11.824 98 6 12 0 1145729184 46055344 1091424
09:09:03 66450 8192000000 10056740944 10478841856 1080638528 11.654 96 33 66 0 1119108096 31699088 6770480
09:11:11 58626 8192000000 10074095424 10530017280 1115543792 11.985 99 17 34 0 1167995392 48418960 4032640
09:13:10 63453 8192000000 10076049536 10513469440 1127231264 12.095 100 13 26 0 1171249536 41263984 2754288
09:15:22 54726 8192000000 10131451408 10592641024 1127449968 12.097 99 29 59 0 1223582432 90168256 5964208
09:17:33 43672 8192000000 10108035984 10559430656 1133936656 12.158 99 30 60 0 1192521600 52391504 6193440
09:19:17 128038 8192000000 10089384080 10569670656 1131320272 12.134 99 19 38 1 1180369968 45466016 3583680
09:22:40 21018 8192000000 10130370592 10565246976 1147374208 12.285 100 28 56 0 1224796480 71584864 5837408
09:24:02 49260 8192000000 10091429008 10588647424 1129013040 12.112 99 18 36 1 1229903680 96883376 4007264
09:25:56 56271 8192000000 10103672736 10576441344 1119812128 12.025 98 14 28 0 1207741152 84566560 3362464
09:28:02 93543 8192000000 10088909728 10525405184 1121795568 12.044 98 21 39 1 1187720832 61682880 4242384
09:30:38 49754 8192000000 10105693952 10558369792 1116793920 11.997 97 21 42 1 1162630976 41645120 4191936
09:32:34 29056 8192000000 10141851760 10578132992 1146494832 12.277 99 37 73 2 1234128000 79293504 8339664
09:34:14 40175 8192000000 10067982992 10516963328 1125812064 12.082 98 13 26 1 1170227216 41524000 2891152
09:35:55 35800 8192000000 10059944320 10518462464 1092367264 11.765 97 27 52 3 1143165632 45673328 5125040
09:37:47 38474 8192000000 10089615424 10551672832 1115430448 11.984 99 33 66 2 1181220592 59136192 6653952
09:39:32 95550 8192000000 10051064384 10501722112 1080797056 11.655 96 13 26 0 1144164032 60147840 3219136
09:42:10 132915 8192000000 10025824576 10489610240 1081841536 11.665 97 13 26 0 1144314048 59780656 2691856
09:45:36 55125 8192000000 10051531888 10495385600 1101753024 11.854 99 10 20 0 1144800832 40388816 2658992
09:47:39 31994 8192000000 10008864416 10455478272 1049358864 11.355 96 7 14 0 1078462640 27245264 1858512
09:49:13 23101 8192000000 10041491056 10469031936 1094517040 11.786 99 19 38 0 1125042480 26245888 4279552
09:50:42 28891 8192000000 10088739536 10510376960 1077746512 11.626 97 24 48 2 1142478672 59807856 4924304
09:52:18 37013 8192000000 10123375056 10563170304 1112548960 11.957 100 28 56 0 1225407296 106793440 6064896
09:54:01 47779 8192000000 10068805088 10534588416 1113785232 11.968 100 31 61 2 1186049296 66237520 6026544
09:55:49 43751 8192000000 10124762000 10570293248 1116543344 11.994 99 25 49 1 1179516752 57664160 5309248
09:57:41 67232 8192000000 10084621152 10574520320 1056008080 11.418 94 19 38 0 1154883984 94366160 4509744
09:59:49 104211 8192000000 10036745168 10477486080 1096410560 11.804 98 17 34 0 1141572832 41756640 3405632
10:02:40 69966 8192000000 10063116528 10538160128 1112676816 11.958 99 6 12 2 1177700192 63807296 1216080
10:05:16 100131 8192000000 10080840464 10523230208 1097906512 11.818 97 22 44 0 1131902896 29467728 4528656
10:07:56 82342 8192000000 10153369968 10613522432 1147420560 12.285 100 18 37 0 1223877424 72460880 3995984
10:10:22 94276 8192000000 10122103056 10602172416 1129490064 12.117 99 16 32 0 1181841696 48957232 3394400
10:12:57 71853 8192000000 10081855840 10574782464 1119184832 12.019 98 5 10 0 1170089552 50090944 813776
10:15:09 58210 8192000000 10111782768 10552823808 1129000496 12.112 99 34 69 0 1167763328 32103984 6658848
10:18:32 43461 8192000000 10054522384 10522046464 1129833808 12.120 99 18 35 0 1151678304 17601392 4243104
10:20:28 71909 8192000000 10067338880 10530816000 1149032096 12.300 100 15 30 1 1171406080 18716464 3657520
10:22:46 79920 8192000000 10115811904 10594406400 1129649792 12.118 98 12 24 1 1184212528 51727424 2835312
10:26:18 42919 8192000000 9708424320 10100379648 762978160 8.520 66 24 48 1 838759328 70566848 5214320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-02-2021 10:28:27 to 08-02-2021 10:28:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          221540
MON$OLDEST_ACTIVE               225018
MON$OLDEST_SNAPSHOT             225018
MON$NEXT_TRANSACTION            225018
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                       2060320
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:107: 
:108: mscs-test-161.budzdorov.ru    Mon Feb  8 10:27:13 2021
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
--- end of diff output ---

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

08.02.21 10:28:45. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-02-08 10:28:48 No severe PSQL-related problems occured
08.02.21 10:28:49. End of report.