Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.2021 04: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 16680128 kB
MemAvailable 31262384 kB
Buffers 20 kB
Cached 14880148 kB
SwapCached 4864 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-01-13 04:26:55
DTS_END                         2021-01-13 04:26:55.9280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35810
TRN_ID                          223370
ATT_ID                          591
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: 8677.33 29214 1041279
customer order: creation 154.30 465 18516
customer order: refuse 13.45 370 1614
order to supplier: creation 42.66 1393 5119
order to supplier: removal 3.25 145470 390
invoice (draft): creation 42.00 3819 5040
invoice (draft): removal 49.86 1179 5983
invoice accept: apply 38.13 19293 4576
invoice accept: cancel 45.13 1029 5415
customer reserve: creation 3574.26 185 428911
customer reserve: removal 4445.34 84 533441
realization accept: apply 74.51 204 8941
realization accept: cancel 76.89 146 9227
payment from customer: creation 54.36 102 6523
payment from customer: removal 5.56 87 667
payment to supplier: creation 44.75 121 5370
payment to supplier: removal 3.10 120 372
service: total inventory turnovers 3.53 4114 423
service: total monetary turnovers 5.40 740 648
service: aggregate perf. data 0.83 17845 99
service: refresh index statistics 0.03 387513 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2021 04:28:33 to 13-01-2021 04:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1615
TEST_TIME 2 1860
TEST_TIME 3 2944
TEST_TIME 4 3473
TEST_TIME 5 3730
TEST_TIME 6 4439
TEST_TIME 7 4628
TEST_TIME 8 4892
TEST_TIME 9 4904
TEST_TIME 10 4944
TEST_TIME 11 5062
TEST_TIME 12 5039
TEST_TIME 13 5232
TEST_TIME 14 5468
TEST_TIME 15 5557
TEST_TIME 16 5761
TEST_TIME 17 5846
TEST_TIME 18 6071
TEST_TIME 19 6270
TEST_TIME 20 6544
TEST_TIME 21 6723
TEST_TIME 22 7042
TEST_TIME 23 7335
TEST_TIME 24 7238
TEST_TIME 25 7420
TEST_TIME 26 7439
TEST_TIME 27 7953
TEST_TIME 28 7913
TEST_TIME 29 8752
TEST_TIME 30 9835
TEST_TIME 31 10936
TEST_TIME 32 10815
TEST_TIME 33 10591
TEST_TIME 34 10361
TEST_TIME 35 10206
TEST_TIME 36 10235
TEST_TIME 37 10195
TEST_TIME 38 10112
TEST_TIME 39 9938
TEST_TIME 40 9800
TEST_TIME 41 9731
TEST_TIME 42 9638
TEST_TIME 43 9645
TEST_TIME 44 9630
TEST_TIME 45 9733
TEST_TIME 46 9725
TEST_TIME 47 9695
TEST_TIME 48 9763
TEST_TIME 49 9780
TEST_TIME 50 9704
TEST_TIME 51 9581
TEST_TIME 52 9560
TEST_TIME 53 9435
TEST_TIME 54 9352
TEST_TIME 55 9225
TEST_TIME 56 9210
TEST_TIME 57 9129
TEST_TIME 58 9068
TEST_TIME 59 9027
TEST_TIME 60 9007
TEST_TIME 61 8922
TEST_TIME 62 8928
TEST_TIME 63 8908
TEST_TIME 64 8843
TEST_TIME 65 8804
TEST_TIME 66 8832
TEST_TIME 67 8876
TEST_TIME 68 8847
TEST_TIME 69 8869
TEST_TIME 70 8970
TEST_TIME 71 8938
TEST_TIME 72 8916
TEST_TIME 73 9053
TEST_TIME 74 9064
TEST_TIME 75 9026
TEST_TIME 76 9083
TEST_TIME 77 9109
TEST_TIME 78 9105
TEST_TIME 79 9032
TEST_TIME 80 8956
TEST_TIME 81 8958
TEST_TIME 82 8892
TEST_TIME 83 8847
TEST_TIME 84 8778
TEST_TIME 85 8760
TEST_TIME 86 8724
TEST_TIME 87 8687
TEST_TIME 88 8752
TEST_TIME 89 8727
TEST_TIME 90 8701
TEST_TIME 91 8735
TEST_TIME 92 8832
TEST_TIME 93 8826
TEST_TIME 94 8883
TEST_TIME 95 8916
TEST_TIME 96 8952
TEST_TIME 97 8904
TEST_TIME 98 8907
TEST_TIME 99 8835
TEST_TIME 100 8788
TEST_TIME 101 8761
TEST_TIME 102 8727
TEST_TIME 103 8719
TEST_TIME 104 8714
TEST_TIME 105 8700
TEST_TIME 106 8680
TEST_TIME 107 8678
TEST_TIME 108 8686
TEST_TIME 109 8717
TEST_TIME 110 8752
TEST_TIME 111 8770
TEST_TIME 112 8796
TEST_TIME 113 8780
TEST_TIME 114 8750
TEST_TIME 115 8882
TEST_TIME 116 8808
TEST_TIME 117 8742
TEST_TIME 118 8717
TEST_TIME 119 8691
TEST_TIME 120 8678

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-01-2021 04:28:36 to 13-01-2021 04:28:40.

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 1437841 1437841 0 0.00 0 25346 7 0 0
doc_list_biud 1437841 1437841 0 0.00 0 47054 39 0 0
sp_add_invoice_to_stock 4677 4576 101 2.15 36 190153 19293 0 101
sp_cancel_adding_invoice 5415 5415 0 0.00 6 93461 1030 0 0
sp_cancel_client_order 1614 1614 0 0.00 5 10029 371 0 0
sp_cancel_customer_reserve 533441 533441 0 0.00 1 44889 84 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 4577 88 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 6111 121 0 0
sp_cancel_supplier_invoice 5990 5983 7 0.11 2 33913 1179 0 7
sp_cancel_supplier_order 390 390 0 0.00 6 1437681 145471 0 0
sp_cancel_write_off 9227 9227 0 0.00 1 10197 147 0 0
sp_client_order 18516 18516 0 0.00 4 35220 466 0 0
sp_customer_reserve 428911 428911 0 0.00 0 61956 185 0 0
sp_fill_shopping_cart 28663 28663 0 0.00 1 18126 47 0 0
sp_fill_shopping_cart_clo_res 428910 428910 0 0.00 0 33483 21 0 0
sp_get_clo_for_invoice 4570 4570 0 0.00 0 38398 456 0 0
sp_kill_cost_storno 15681 15681 0 0.00 0 10107 74 0 0
sp_kill_qstorno_ret_qs2qd 1082482 1082482 0 0.00 0 40122 45 0 0
sp_kill_qty_storno 569580 569580 0 0.00 0 47054 97 0 0
sp_lock_selected_doc 18812 18812 0 0.00 0 7668 4 0 0
sp_make_cost_storno 25403 25403 0 0.00 0 10007 54 0 0
sp_multiply_rows_for_pdistr 25403 25403 0 0.00 0 7286 34 0 0
sp_multiply_rows_for_qdistr 18516 18516 0 0.00 0 22808 249 0 0
sp_pay_from_customer 6523 6523 0 0.00 4 13691 103 0 0
sp_pay_to_supplier 5370 5370 0 0.00 4 10150 122 0 0
sp_payment_common 11893 11893 0 0.00 1 13676 24 0 0
sp_qd_handle_on_cancel_clo 1614 1614 0 0.00 0 10020 347 0 0
sp_qd_handle_on_invoice_upd_sts 9984 9984 0 0.00 0 47054 453 0 0
sp_qd_handle_on_reserve_upd_sts 8941 8941 0 0.00 0 31937 72 0 0
sp_reserve_write_off 8941 8941 0 0.00 6 31958 204 0 0
sp_supplier_invoice 5040 5040 0 0.00 8 96592 3820 0 0
sp_supplier_order 5119 5119 0 0.00 5 41900 1393 0 0
srv_aggregate_perf_data 99 99 0 0.00 2558 326360 17846 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1268 149173 49424 0 0
srv_find_qd_qs_mism 997249 997249 0 0.00 0 25345 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 68 142235 4114 0 0
srv_make_money_saldo 648 648 0 0.00 1 15181 740 0 0
srv_recalc_idx_stat 4 4 0 0.00 218800 596398 387514 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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 4576 4576 0 0.00 0 10278 174 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 3759 99 0 0
t$perf-norm:sp_cancel_client_order 1614 1614 0 0.00 0 1156 9 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 5086 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 3581 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 3287 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 699 13 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 26993 2033 0 0
t$perf-norm:sp_cancel_write_off 863 863 0 0.00 0 2677 6 0 0
t$perf-norm:sp_client_order 18516 18516 0 0.00 0 7561 19 0 0
t$perf-norm:sp_customer_reserve 913 913 0 0.00 0 3183 12 0 0
t$perf-norm:sp_pay_from_customer 6523 6523 0 0.00 0 8658 9 0 0
t$perf-norm:sp_pay_to_supplier 5370 5370 0 0.00 0 2776 8 0 0
t$perf-norm:sp_reserve_write_off 8941 8941 0 0.00 0 4706 10 0 0
t$perf-norm:sp_supplier_invoice 5040 5040 0 0.00 0 5534 20 0 0
t$perf-norm:sp_supplier_order 5119 5119 0 0.00 0 4722 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 150 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2009 34 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 2273 10 0 0
t$perf-norm:srv_make_money_saldo 648 648 0 0.00 0 490 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 55 17 0 0
v_add_invoice_to_stock 9340 9138 202 2.16 0 4210 16 0 202
v_all_customers 34040 34040 0 0.00 0 19 1 0 0
v_all_suppliers 28278 28278 0 0.00 0 18 1 0 0
v_all_wares 37026 37026 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1050 8 0 0
v_cancel_client_order 3228 3228 0 0.00 0 613 3 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 2640 9 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1039 6 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 3276 21 0 0
v_cancel_supplier_invoice 782 768 14 1.79 0 2703 14 0 14
v_cancel_supplier_order 780 780 0 0.00 0 320 4 0 0
v_cancel_write_off 1726 1726 0 0.00 0 2659 14 0 0
v_max_id_clo_ord 5115 5115 0 0.00 0 667 2 0 0
v_max_id_clo_res 913 913 0 0.00 0 1375 9 0 0
v_max_id_ord_sup 5034 5034 0 0.00 0 4356 6 0 0
v_max_non_paid_invoice 1378 1378 0 0.00 0 2888 5 0 0
v_max_non_paid_realizn 3423 3423 0 0.00 0 13136 7 0 0
v_min_id_clo_ord 5115 5115 0 0.00 0 5201 9 0 0
v_min_id_clo_res 913 913 0 0.00 0 2484 6 0 0
v_min_id_ord_sup 5034 5034 0 0.00 0 5718 14 0 0
v_min_non_paid_invoice 5370 5370 0 0.00 0 6709 16 0 0
v_min_non_paid_realizn 6523 6523 0 0.00 0 6439 14 0 0
v_our_firm 9186 9186 0 0.00 0 12 1 0 0
v_reserve_write_off 17880 17880 0 0.00 0 6814 6 0 0
x_lock_depdocs_on_canc_invoice 11014 11014 0 0.00 0 22321 122 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 10310 419 0 0
x_make_qstorno_1000_1200 5119 5119 0 0.00 2 41891 1307 0 0
x_make_qstorno_1200_2000 5040 5040 0 0.00 4 91155 3642 0 0
x_make_qstorno_2100_3300 428911 428911 0 0.00 0 61952 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2021 04:28:41 to 13-01-2021 04:28:45.

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
01:56:59 10 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
01:58:00 24288 8192000000 9718631376 10012057600 796235344 8.858 100 85 170 1 823789424 7260272 20293808
01:59:35 24564 8192000000 9764848272 10070966272 836683280 9.266 100 88 176 0 869139360 11636672 20819408
02:01:07 5559 8192000000 9791654864 10104610816 864211520 9.542 100 86 172 1 903223328 19366048 19645760
02:02:17 15173 8192000000 9843498960 10169376768 900016016 9.898 100 80 160 0 950201344 32181312 18004016
02:03:38 9456 8192000000 9895619616 10229866496 940429120 10.297 100 85 170 1 1007804480 49360544 18014816
02:04:50 9585 8192000000 9955357696 10299981824 976772064 10.653 100 89 176 3 1057828976 62883424 18173488
02:06:02 3484 8192000000 9985357408 10341961728 1014539232 11.019 100 86 168 2 1095878992 64596928 16742832
02:07:07 2255 8192000000 10020674512 10384195584 1034363344 11.210 100 82 165 0 1131846272 81038880 16444048
02:08:11 2007 8192000000 10039825008 10409340928 1064538528 11.500 100 92 184 0 1153234688 69874880 18821280
02:09:14 16506 8192000000 10007070512 10387886080 1071380464 11.565 100 61 123 2 1123878448 39967440 12530544
02:10:32 6460 8192000000 9998728624 10385403904 1081233248 11.659 100 37 70 2 1118708368 29426784 8048336
02:11:40 6740 8192000000 10007309824 10406133760 1086030928 11.705 100 34 68 0 1127726624 34451888 7243808
02:12:49 7451 8192000000 10013685104 10421379072 1092741680 11.769 100 39 79 0 1137022832 35722416 8558736
02:13:59 10562 8192000000 10027399216 10417451008 1095234016 11.792 100 63 128 2 1120600352 11304720 14061616
02:15:10 6343 8192000000 10006756464 10408828928 1084674688 11.692 99 38 77 1 1124644784 32370752 7599344
02:16:21 3542 8192000000 10017715456 10427625472 1099056880 11.829 100 35 70 0 1131706064 25428432 7220752
02:17:28 38822 8192000000 9997408864 10403921920 1080999952 11.657 99 42 84 2 1107753488 17687536 9066000
02:19:26 4874 8192000000 10015712720 10410672128 1078229200 11.631 100 39 78 3 1126170352 39806624 8134528
02:20:31 12254 8192000000 9938571136 10330071040 983766512 10.721 95 19 36 3 1005434096 17559376 4108208
02:21:47 6897 8192000000 9945560720 10324029440 1018429968 11.057 100 32 63 2 1050338800 24815168 7093664
02:22:58 10617 8192000000 9943289504 10312896512 1019169488 11.064 100 54 108 1 1046149056 16026640 10952928
02:24:11 10095 8192000000 9958608512 10353025024 1037973696 11.245 100 33 66 1 1069256480 24194304 7088480
02:25:22 4605 8192000000 9963434256 10356703232 1054117424 11.400 100 48 96 3 1083436944 18579552 10739968
02:26:30 5802 8192000000 9984287248 10380292096 1067045328 11.524 100 44 88 0 1091370784 14770448 9555008
02:27:37 46826 8192000000 10012406560 10406027264 1086209968 11.707 100 44 87 1 1116691072 21622800 8858304
02:29:30 18543 8192000000 10070872192 10472435712 1104408288 11.879 100 41 82 0 1181259520 68448336 8402896
02:30:52 23615 8192000000 10104975216 10507968512 1118239216 12.010 100 56 110 1 1212475232 82589888 11646128
02:32:22 5810 8192000000 10130305312 10534207488 1140658224 12.222 100 57 111 1 1241719632 88896592 12164816
02:33:32 22148 8192000000 10158303872 10567090176 1138247648 12.199 99 51 99 0 1271001904 121796064 10958192
02:35:04 10256 8192000000 10185304240 10591744000 1158670016 12.391 100 60 119 0 1291636528 119676528 13289984
02:36:28 2537 8192000000 10224616720 10624303104 1167847168 12.477 100 70 138 3 1320032144 136069904 16115072
02:37:35 1369 8192000000 10280386096 10682523648 1179124560 12.582 100 75 148 1 1381299488 185076576 17098352
02:38:41 9638 8192000000 10313552848 10722037760 1188580160 12.670 100 66 131 0 1410623952 206817904 15225888
02:39:57 3235 8192000000 10367799344 10781204480 1186506944 12.651 100 74 148 0 1472362352 268482816 17372592
02:41:07 7184 8192000000 10381420848 10810798080 1185306528 12.640 99 65 130 0 1492418304 292021296 15090480
02:42:24 5547 8192000000 10408803584 10829492224 1176371456 12.556 99 69 138 1 1507885552 315171264 16342832
02:43:46 3459 8192000000 10441898448 10843918336 1166787472 12.467 99 88 175 1 1543010976 355357232 20866272
02:44:59 1267 8192000000 10472672224 10883371008 1180074176 12.591 100 92 184 0 1568221728 366174160 21973392
02:46:07 2525 8192000000 10526105760 10930249728 1185666352 12.643 100 86 173 0 1620382208 414414544 20301312
02:47:16 7202 8192000000 10516899856 10952769536 1169024848 12.488 100 47 94 1 1620347968 438895696 12427424
02:48:32 2632 8192000000 10580386496 10984034304 1180116512 12.591 100 88 177 1 1680831168 479651664 21062992
02:49:37 2206 8192000000 10517447296 10926018560 1167801408 12.476 99 67 134 0 1604090128 419819504 16469216
02:50:53 13515 8192000000 10592022944 11025731584 1167353152 12.472 100 43 86 1 1689632976 510523904 11755920
02:52:22 11894 8192000000 10606117488 11059802112 1135311552 12.171 99 38 76 0 1693022128 546374320 11336256
02:53:57 12357 8192000000 10584742032 11024023552 1104580368 11.881 98 36 72 0 1669801808 555318448 9902992
02:55:18 12223 8192000000 10594023024 11093364736 1117388288 12.002 100 25 50 0 1687019968 562073664 7558016
02:56:35 19422 8192000000 10267816768 10736062464 1110203440 11.934 99 29 58 0 1362485152 245623040 6658672
02:57:55 44895 8192000000 10041494432 10464935936 1096701024 11.806 97 18 33 2 1142314080 42491328 3121728
02:59:42 116474 8192000000 10109731920 10547535872 1119293696 12.020 98 35 70 0 1198127856 71598704 7235456
03:02:44 25644 8192000000 10099737936 10621927424 1144375072 12.257 100 12 24 0 1214462608 67311040 2776496
03:04:11 59825 8192000000 10108821008 10596274176 1120170144 12.029 98 9 18 0 1208036448 85798528 2067776
03:06:22 64357 8192000000 10172521056 10611789824 1116894272 11.998 98 20 40 0 1250818208 129582160 4341776
03:08:51 102470 8192000000 10158719296 10642259968 1145996304 12.272 100 24 48 0 1289167824 137706608 5464912
03:12:06 83754 8192000000 10102925040 10569678848 1125046368 12.075 98 19 38 0 1168822592 39992176 3784048
03:14:31 149173 8192000000 10085109376 10557591552 1122270640 12.048 98 5 10 1 1165587504 42545328 771536
03:18:11 46855 8192000000 10120336384 10567872512 1117837296 12.007 97 23 46 0 1196070432 73763248 4469888
03:19:59 137217 8192000000 10091724880 10544832512 1145355552 12.266 100 17 34 0 1186388720 37331472 3701696
03:23:23 99625 8192000000 10083522880 10563911680 1138856848 12.205 99 11 22 1 1178856880 37699296 2300736
03:26:06 45772 8192000000 10113627136 10577559552 1147570800 12.287 99 20 40 0 1206811824 55167360 4073664
03:27:52 50896 8192000000 10087628624 10523181056 1100878784 11.846 95 21 40 1 1161365808 55951360 4535664
03:29:54 76416 8192000000 10073507824 10558492672 1143601968 12.249 100 16 32 1 1179838336 33271424 2964944
03:32:10 86509 8192000000 10067654912 10562772992 1109360496 11.926 97 9 18 1 1172948976 61815632 1772848
03:34:46 52019 8192000000 10134197072 10583560192 1133308336 12.153 99 35 68 1 1197569872 56178016 8083520
03:36:47 76102 8192000000 10104093280 10538557440 1138609808 12.202 99 28 56 3 1183575120 39004688 5960624
03:39:05 104844 8192000000 10067008576 10530988032 1118884112 12.016 98 3 6 0 1170228432 50635200 709120
03:41:51 37092 8192000000 10118048448 10552426496 1114216384 11.972 98 40 80 2 1175836608 52637296 8982928
03:43:28 80162 8192000000 10068702848 10535567360 1096458528 11.804 96 19 37 2 1146306144 46087408 3760208
03:45:49 104042 8192000000 10066327600 10552147968 1127044336 12.093 100 27 53 0 1163755024 30748576 5962112
03:48:44 38035 8192000000 10036364816 10507182080 1101308240 11.850 98 4 8 0 1126340576 24266704 765632
03:50:27 88512 8192000000 10086564608 10546962432 1092518624 11.767 97 14 28 0 1152907456 57237184 3151648
03:52:59 67784 8192000000 10104019488 10565554176 1097178528 11.811 98 18 36 0 1180749696 79670272 3900896
03:55:08 45717 8192000000 10109685104 10545926144 1111409056 11.946 99 28 56 1 1174960288 57395920 6155312
03:57:11 77205 8192000000 10041932480 10534391808 1088697248 11.730 96 23 46 0 1191834864 98029920 5107696
03:59:34 53343 8192000000 10119818736 10567266304 1114781120 11.978 98 24 48 0 1177086496 57674048 4631328
04:01:36 46751 8192000000 10064451408 10502217728 1095076848 11.791 97 19 37 1 1136302608 37550272 3675488
04:03:23 34227 8192000000 10107490528 10535436288 1131849792 12.139 100 45 89 0 1175049808 33687616 9512400
04:04:58 104839 8192000000 10054652768 10503364608 1115473984 11.984 99 33 66 0 1169726272 47519760 6732528
04:07:45 48313 8192000000 10062964272 10522882048 1131126912 12.132 100 5 10 0 1162783712 30681584 975216
04:09:44 78655 8192000000 10087957296 10550763520 1134807136 12.167 100 29 55 0 1177254544 36245392 6202016
04:12:19 48624 8192000000 10143977632 10597220352 1153052464 12.338 100 33 66 0 1234402880 74235584 7114832
04:14:16 96436 8192000000 10111485664 10545983488 1092050912 11.762 95 21 42 3 1151572624 55069984 4451728
04:16:54 62353 8192000000 10076748128 10522558464 1116002368 11.989 98 21 42 0 1181931712 61605312 4324032
04:19:06 82377 8192000000 10128269152 10566533120 1124199024 12.067 98 30 60 0 1179481664 48875504 6407136
04:21:31 87092 8192000000 10065906464 10617208832 1126068432 12.084 99 5 10 1 1171037520 44008736 960352
04:24:05 57541 8192000000 10060719088 10546823168 1117684480 12.005 98 15 30 0 1159932672 38766240 3481952
04:26:02 56826 8192000000 9860085968 10284969984 925259984 10.148 81 16 29 2 977940704 49566672 3114048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-01-2021 04:28:47 to 13-01-2021 04:29:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 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 13-01-2021 04:29:03 to 13-01-2021 04:29:05.

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          223582
MON$OLDEST_ACTIVE               223583
MON$OLDEST_SNAPSHOT             223583
MON$NEXT_TRANSACTION            223583
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                       2091200
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             775
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 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.2324 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

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