Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.01.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 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 16632848 kB
MemAvailable 31277108 kB
Buffers 0 kB
Cached 14979964 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-15 13:26:56
DTS_END                         2021-01-15 13:26:56.6900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39300
TRN_ID                          242085
ATT_ID                          621
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: 8832.28 26658 1059873
customer order: creation 170.61 391 20473
customer order: refuse 15.20 351 1824
order to supplier: creation 45.47 1365 5456
order to supplier: removal 3.31 118752 397
invoice (draft): creation 46.52 3776 5582
invoice (draft): removal 47.87 1074 5744
invoice accept: apply 41.98 18849 5038
invoice accept: cancel 43.08 948 5170
customer reserve: creation 3854.76 185 462571
customer reserve: removal 4276.28 72 513154
realization accept: apply 84.65 182 10158
realization accept: cancel 71.59 138 8591
payment from customer: creation 60.23 113 7228
payment from customer: removal 6.23 79 748
payment to supplier: creation 49.48 134 5937
payment to supplier: removal 3.30 88 396
service: total inventory turnovers 4.31 3095 517
service: total monetary turnovers 6.34 737 761
service: aggregate perf. data 1.03 16331 124
service: refresh index statistics 0.03 366500 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2343
TEST_TIME 2 3774
TEST_TIME 3 5316
TEST_TIME 4 5734
TEST_TIME 5 6182
TEST_TIME 6 6275
TEST_TIME 7 6271
TEST_TIME 8 6456
TEST_TIME 9 6337
TEST_TIME 10 6561
TEST_TIME 11 6382
TEST_TIME 12 6520
TEST_TIME 13 6668
TEST_TIME 14 7144
TEST_TIME 15 7319
TEST_TIME 16 7511
TEST_TIME 17 7607
TEST_TIME 18 7812
TEST_TIME 19 8149
TEST_TIME 20 8159
TEST_TIME 21 8186
TEST_TIME 22 8104
TEST_TIME 23 8212
TEST_TIME 24 8890
TEST_TIME 25 9012
TEST_TIME 26 9150
TEST_TIME 27 9599
TEST_TIME 28 9679
TEST_TIME 29 11077
TEST_TIME 30 11653
TEST_TIME 31 11367
TEST_TIME 32 11250
TEST_TIME 33 10942
TEST_TIME 34 10751
TEST_TIME 35 10700
TEST_TIME 36 10593
TEST_TIME 37 10613
TEST_TIME 38 10447
TEST_TIME 39 10471
TEST_TIME 40 10367
TEST_TIME 41 10276
TEST_TIME 42 10130
TEST_TIME 43 9924
TEST_TIME 44 9804
TEST_TIME 45 9696
TEST_TIME 46 9606
TEST_TIME 47 9601
TEST_TIME 48 9535
TEST_TIME 49 9488
TEST_TIME 50 9496
TEST_TIME 51 9423
TEST_TIME 52 9476
TEST_TIME 53 9432
TEST_TIME 54 9470
TEST_TIME 55 9439
TEST_TIME 56 9413
TEST_TIME 57 9476
TEST_TIME 58 9440
TEST_TIME 59 9431
TEST_TIME 60 9395
TEST_TIME 61 9407
TEST_TIME 62 9395
TEST_TIME 63 9496
TEST_TIME 64 9386
TEST_TIME 65 9267
TEST_TIME 66 9214
TEST_TIME 67 9162
TEST_TIME 68 9085
TEST_TIME 69 9044
TEST_TIME 70 9015
TEST_TIME 71 9032
TEST_TIME 72 8985
TEST_TIME 73 8970
TEST_TIME 74 8982
TEST_TIME 75 9093
TEST_TIME 76 9127
TEST_TIME 77 9129
TEST_TIME 78 9074
TEST_TIME 79 9139
TEST_TIME 80 9159
TEST_TIME 81 9129
TEST_TIME 82 9197
TEST_TIME 83 9234
TEST_TIME 84 9186
TEST_TIME 85 9085
TEST_TIME 86 9061
TEST_TIME 87 8994
TEST_TIME 88 8936
TEST_TIME 89 8911
TEST_TIME 90 8941
TEST_TIME 91 8936
TEST_TIME 92 8939
TEST_TIME 93 8936
TEST_TIME 94 8943
TEST_TIME 95 8915
TEST_TIME 96 8909
TEST_TIME 97 8927
TEST_TIME 98 8979
TEST_TIME 99 8980
TEST_TIME 100 8958
TEST_TIME 101 8971
TEST_TIME 102 9008
TEST_TIME 103 8954
TEST_TIME 104 8900
TEST_TIME 105 8863
TEST_TIME 106 8858
TEST_TIME 107 8829
TEST_TIME 108 8851
TEST_TIME 109 8851
TEST_TIME 110 8837
TEST_TIME 111 8852
TEST_TIME 112 8850
TEST_TIME 113 8837
TEST_TIME 114 8839
TEST_TIME 115 8872
TEST_TIME 116 8881
TEST_TIME 117 8922
TEST_TIME 118 8941
TEST_TIME 119 8887
TEST_TIME 120 8836
TEST_TIME 121 8763

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-01-2021 13:29:11 to 15-01-2021 13:29:15.

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 1487925 1487925 0 0.00 0 17967 7 0 0
doc_list_biud 1487918 1487918 0 0.00 0 54315 32 0 0
sp_add_invoice_to_stock 5127 5038 89 1.73 34 141888 18850 0 89
sp_cancel_adding_invoice 5170 5170 0 0.00 6 83336 949 0 0
sp_cancel_client_order 1824 1824 0 0.00 5 13520 351 0 0
sp_cancel_customer_reserve 513154 513154 0 0.00 1 54317 72 0 0
sp_cancel_pay_from_customer 748 748 0 0.00 3 3921 79 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 7837 88 0 0
sp_cancel_supplier_invoice 5752 5744 8 0.13 3 19077 1075 0 8
sp_cancel_supplier_order 397 397 0 0.00 5 1378092 118753 0 0
sp_cancel_write_off 8591 8591 0 0.00 1 7538 138 0 0
sp_client_order 20473 20473 0 0.00 4 52543 392 0 0
sp_customer_reserve 462571 462571 0 0.00 0 51314 185 0 0
sp_fill_shopping_cart 31505 31505 0 0.00 1 19015 50 0 0
sp_fill_shopping_cart_clo_res 462569 462569 0 0.00 0 26871 18 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 35149 455 0 0
sp_kill_cost_storno 14905 14905 0 0.00 0 12161 64 0 0
sp_kill_qstorno_ret_qs2qd 1041040 1041040 0 0.00 0 54311 39 0 0
sp_kill_qty_storno 550062 550062 0 0.00 0 54315 85 0 0
sp_lock_selected_doc 21121 21121 0 0.00 0 9760 5 0 0
sp_make_cost_storno 28354 28354 0 0.00 0 10610 58 0 0
sp_multiply_rows_for_pdistr 28347 28347 0 0.00 0 10162 33 0 0
sp_multiply_rows_for_qdistr 20473 20473 0 0.00 0 52535 210 0 0
sp_pay_from_customer 7228 7228 0 0.00 4 12912 113 0 0
sp_pay_to_supplier 5937 5937 0 0.00 5 19411 135 0 0
sp_payment_common 13165 13165 0 0.00 1 11743 20 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 13515 320 0 0
sp_qd_handle_on_invoice_upd_sts 10197 10197 0 0.00 0 29382 409 0 0
sp_qd_handle_on_reserve_upd_sts 10155 10155 0 0.00 0 14291 57 0 0
sp_reserve_write_off 10158 10158 0 0.00 6 14339 183 0 0
sp_supplier_invoice 5582 5582 0 0.00 5 91726 3776 0 0
sp_supplier_order 5456 5456 0 0.00 5 37758 1365 0 0
srv_aggregate_perf_data 124 124 0 0.00 2225 425838 16331 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1193 144024 48548 0 0
srv_find_qd_qs_mism 1011839 1011839 0 0.00 0 17966 6 0 0
srv_make_invnt_saldo 517 517 0 0.00 38 90670 3095 0 0
srv_make_money_saldo 761 761 0 0.00 1 58703 737 0 0
srv_recalc_idx_stat 4 4 0 0.00 152577 574180 366501 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5038 5038 0 0.00 0 20438 188 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 3128 87 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 1334 9 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 1436 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 748 748 0 0.00 0 1305 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 13649 40 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 2217 18 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 29795 1481 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 1508 9 0 0
t$perf-norm:sp_client_order 20473 20473 0 0.00 0 29994 19 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 1692 9 0 0
t$perf-norm:sp_pay_from_customer 7228 7228 0 0.00 0 14862 10 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 4701 11 0 0
t$perf-norm:sp_reserve_write_off 10158 10158 0 0.00 0 20135 11 0 0
t$perf-norm:sp_supplier_invoice 5582 5582 0 0.00 0 10754 20 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 6522 16 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 622 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 71 3 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 1549 15 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 980 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 26 8 0 0
v_add_invoice_to_stock 10230 10052 178 1.73 0 3838 16 0 178
v_all_customers 37318 37318 0 0.00 0 25 1 0 0
v_all_suppliers 30720 30720 0 0.00 0 23 1 0 0
v_all_wares 40942 40942 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 667 7 0 0
v_cancel_client_order 3648 3648 0 0.00 0 2618 5 0 0
v_cancel_customer_prepayment 1496 1496 0 0.00 0 668 6 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 645 3 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2507 8 0 0
v_cancel_supplier_invoice 830 814 16 1.92 0 2333 18 0 16
v_cancel_supplier_order 794 794 0 0.00 0 933 5 0 0
v_cancel_write_off 2088 2088 0 0.00 0 4801 17 0 0
v_max_id_clo_ord 5454 5454 0 0.00 0 485 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 18174 29 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 6513 5 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 358 2 0 0
v_max_non_paid_realizn 3924 3924 0 0.00 0 2255 3 0 0
v_min_id_clo_ord 5454 5454 0 0.00 0 5091 7 0 0
v_min_id_clo_res 973 973 0 0.00 0 8887 33 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 8660 9 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 5441 12 0 0
v_min_non_paid_realizn 7228 7228 0 0.00 0 11734 14 0 0
v_our_firm 10232 10232 0 0.00 0 17 1 0 0
v_reserve_write_off 20310 20310 0 0.00 0 3631 5 0 0
x_lock_depdocs_on_canc_invoice 10507 10507 0 0.00 0 12456 125 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3691 367 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 2 37037 1283 0 0
x_make_qstorno_1200_2000 5582 5582 0 0.00 3 91695 3583 0 0
x_make_qstorno_2100_3300 462571 462571 0 0.00 0 51313 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-01-2021 13:29:16 to 15-01-2021 13:29:20.

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:00 7 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
10:58:01 46160 8192000000 9743429600 10042433536 813813328 9.036 100 87 174 0 842755744 8140016 20802400
10:59:55 13264 8192000000 9784257232 10092531712 854345536 9.444 100 93 186 0 889817632 13627680 21844416
11:01:17 22759 8192000000 9828548592 10150035456 887410800 9.773 100 83 166 0 932300672 25822992 19066880
11:02:48 11241 8192000000 9893710928 10230476800 932167136 10.216 100 81 162 1 998859104 48739920 17952048
11:04:06 6822 8192000000 9941198896 10284560384 971258896 10.599 100 93 186 0 1050900688 59975152 19666640
11:05:15 4338 8192000000 9979021600 10337796096 1012708656 11.002 100 84 166 2 1082936112 53324800 16902656
11:06:21 5843 8192000000 10006958480 10375389184 1038447344 11.250 100 72 144 0 1123488944 71130736 13910864
11:07:30 1235 8192000000 10041284704 10411331584 1060247152 11.459 100 89 178 1 1154900784 77193696 17459936
11:08:32 37331 8192000000 9985153120 10385915904 1066989424 11.523 100 41 83 1 1117792496 42055808 8747264
11:10:10 5595 8192000000 9983624784 10377547776 1074108848 11.591 100 40 83 1 1099388272 16187712 9091712
11:11:21 10770 8192000000 10001598144 10410496000 1079677216 11.644 100 16 32 1 1107803200 25055296 3070688
11:12:34 63088 8192000000 10005362080 10404216832 1089561568 11.738 100 51 103 1 1123707472 22219008 11926896
11:14:40 7880 8192000000 10018967376 10421178368 1091641104 11.758 100 51 99 3 1126753792 23695712 11416976
11:15:48 6363 8192000000 10005479472 10413989888 1098900432 11.827 100 41 82 0 1124631552 16718032 9013088
11:16:56 8784 8192000000 9983866400 10391957504 1070056016 11.553 100 21 43 0 1091283008 16866640 4360352
11:18:05 26602 8192000000 9964772464 10361765888 1037910560 11.245 97 21 41 1 1061218752 18538016 4770176
11:19:32 7009 8192000000 9945104480 10326831104 995771136 10.838 96 52 103 1 1022776320 16168160 10837024
11:20:40 9405 8192000000 9942429040 10319634432 998945344 10.868 98 34 68 1 1037720704 31276720 7498640
11:21:52 61663 8192000000 9941038752 10349203456 1007065312 10.947 97 33 66 1 1026694704 12688320 6941072
11:23:56 8192 8192000000 9966542144 10366115840 1046921216 11.331 100 31 62 1 1068465360 14922608 6621536
11:25:05 9226 8192000000 9981728000 10387767296 1062297984 11.478 100 34 66 1 1098681552 29248512 7135056
11:26:15 6469 8192000000 9994599760 10392461312 1070013744 11.552 100 43 84 2 1102299936 23013824 9272368
11:27:25 36954 8192000000 10006387600 10406244352 1082323088 11.670 100 44 89 1 1117422224 25807872 9291264
11:29:16 13649 8192000000 10073566208 10469269504 1098912368 11.827 100 62 124 0 1176341312 64763504 12665440
11:30:34 2187 8192000000 10080767744 10477727744 1115980000 11.989 100 68 136 1 1197093152 67239344 13873808
11:31:38 1860 8192000000 10105614896 10504437760 1134381040 12.163 100 66 132 1 1221264896 73229840 13654016
11:32:44 10921 8192000000 10145602624 10554716160 1142600976 12.240 100 46 92 1 1253172032 100500864 10070192
11:34:06 10076 8192000000 10172047760 10587488256 1133287616 12.152 99 54 106 0 1266807616 122391136 11128864
11:35:20 16794 8192000000 10182659840 10596249600 1152329712 12.331 100 49 98 3 1310106208 146718208 11058288
11:36:46 4414 8192000000 10218817392 10640441344 1128275632 12.105 98 63 126 1 1319503008 177772528 13454848
11:37:54 6785 8192000000 10253944608 10647842816 1139038048 12.206 98 72 144 0 1357944576 203944528 14962000
11:39:10 2862 8192000000 10273989776 10670903296 1158986560 12.394 100 76 148 2 1369724688 193372080 17366048
11:40:16 3023 8192000000 10319740688 10720620544 1156450256 12.370 100 75 148 0 1418556960 244758720 17347984
11:41:28 4672 8192000000 10309756608 10713489408 1123625968 12.061 98 78 156 1 1405318192 264746240 16945984
11:42:39 7854 8192000000 10319960688 10731585536 1165059824 12.451 100 53 106 0 1422261248 244593168 12608256
11:43:51 4098 8192000000 10351875728 10752638976 1147347024 12.285 100 78 155 0 1450519776 285681168 17491584
11:45:01 1784 8192000000 10388092896 10803716096 1163520512 12.436 100 72 145 0 1481141744 300585728 17035504
11:46:08 6664 8192000000 10426572448 10844672000 1180014304 12.590 100 51 102 0 1519548624 327091200 12443120
11:47:23 5524 8192000000 10460099648 10872877056 1165593888 12.456 99 57 115 1 1558910336 379799248 13517200
11:48:31 1191 8192000000 10536833600 10938388480 1182611856 12.615 100 95 190 1 1632996752 427487584 22897312
11:49:36 4623 8192000000 10568505360 10985385984 1174864608 12.542 100 60 122 0 1660928736 470550624 15513504
11:50:46 6555 8192000000 10524671984 10984488960 1176971360 12.562 100 41 82 0 1613865552 426410624 10483568
11:52:04 16064 8192000000 10515065888 10955431936 1168266480 12.481 100 38 75 1 1612484720 434546384 9671856
11:53:22 31161 8192000000 10497562800 10988552192 1155540720 12.361 99 32 62 2 1642413616 478367968 8504928
11:55:18 19453 8192000000 10342056032 10823372800 1144478480 12.258 100 32 64 0 1515937184 362913520 8545184
11:56:38 94992 8192000000 10055492832 10528997376 1105350720 11.888 97 22 44 0 1186441488 76099520 4991248
11:59:58 49301 8192000000 10150453232 10601250816 1134243648 12.161 99 33 67 0 1227735264 86732576 6759040
12:02:00 92147 8192000000 10117335712 10591072256 1116828992 11.997 98 22 43 1 1213236976 91286416 5121568
12:04:33 52654 8192000000 10103452128 10543558656 1087063680 11.715 97 15 30 0 1178134352 87521088 3549584
12:06:28 91195 8192000000 10034085824 10476367872 1086735808 11.712 97 20 41 0 1111572432 20388720 4447904
12:09:07 70924 8192000000 10035418256 10472394752 1076290496 11.612 97 25 51 1 1103051728 21733664 5027568
12:11:50 63261 8192000000 10114655360 10578812928 1130091984 12.122 100 28 56 1 1215340496 78973040 6275472
12:14:00 77191 8192000000 10092706912 10566967296 1098407600 11.823 97 15 30 0 1176410272 74621920 3380752
12:16:17 71105 8192000000 10083638272 10529284096 1118965712 12.017 99 10 20 0 1170550384 49452000 2132672
12:18:38 64272 8192000000 10065770080 10517942272 1120742784 12.034 99 17 34 1 1153482032 28988640 3750608
12:20:46 97582 8192000000 10058783216 10516938752 1048752960 11.349 92 13 26 0 1084368672 33104432 2511280
12:23:24 87509 8192000000 10052837104 10511405056 1084363456 11.689 96 11 22 1 1117726576 31225472 2137648
12:25:52 70181 8192000000 10068154336 10492854272 1077801744 11.627 96 27 54 0 1120713920 37699424 5212752
12:28:36 105894 8192000000 10042333104 10493825024 1086908960 11.713 97 11 22 0 1169429008 80165920 2354128
12:31:27 66618 8192000000 10028762864 10481590272 1094215168 11.783 98 36 72 1 1125586656 23632528 7738960
12:33:37 98904 8192000000 10041075696 10498818048 1058175056 11.439 95 9 18 0 1095893088 35256192 2461840
12:36:22 62507 8192000000 10025039024 10495578112 1076186880 11.611 97 14 29 0 1115157360 36397536 2572944
12:38:25 62124 8192000000 10062621456 10502692864 1076094912 11.610 96 27 54 0 1133483056 51479072 5909072
12:40:28 40965 8192000000 10104264368 10550468608 1100115360 11.839 98 21 42 0 1192822240 87816512 4890368
12:42:27 62455 8192000000 10056016032 10523590656 1124495040 12.069 100 16 32 0 1154370912 26634624 3241248
12:44:40 64454 8192000000 10095304512 10559877120 1120921040 12.036 99 35 70 1 1202256736 72994432 8341264
12:46:48 29950 8192000000 10084784608 10517106688 1112113984 11.952 98 35 70 0 1159702960 40456384 7132592
12:48:18 84290 8192000000 10081110928 10565226496 1115109616 11.981 98 28 56 0 1192939728 72273840 5556272
12:50:43 144023 8192000000 10070923936 10533343232 1128761056 12.110 99 27 54 1 1184337040 49620912 5955072
12:55:13 64974 8192000000 10064635200 10575060992 1131969648 12.140 100 12 24 1 1220190272 85386576 2834048
12:57:28 71467 8192000000 10062041232 10499514368 1083381984 11.680 95 17 34 0 1122251520 35175584 3693952
12:59:42 41125 8192000000 10069607920 10531225600 1119236544 12.020 99 20 40 0 1163768448 39971104 4560800
13:01:32 50054 8192000000 10117279392 10559156224 1115249488 11.982 98 29 58 1 1185309616 63504992 6555136
13:03:33 78065 8192000000 10074171712 10546950144 1106624560 11.900 97 18 36 1 1179324928 69214336 3486032
13:05:57 28790 8192000000 10065944464 10504040448 1113673408 11.967 98 26 50 0 1147139760 28723760 4742592
13:07:29 54785 8192000000 10126705824 10651639808 1105683360 11.892 98 43 86 3 1220999632 106831920 8484352
13:09:27 84788 8192000000 10067466576 10545315840 1094710720 11.787 97 8 16 0 1151860144 55721760 1427664
13:11:53 49739 8192000000 10075504256 10548973568 1127714704 12.100 100 8 16 2 1191519616 62067584 1737328
13:13:49 104406 8192000000 10085624192 10559070208 1092068048 11.762 96 13 26 1 1149690928 54900592 2722288
13:16:54 74124 8192000000 10072653808 10543546368 1096306608 11.803 97 26 52 0 1157784992 56219824 5258560
13:19:09 53739 8192000000 10118390464 10555506688 1090137040 11.744 96 26 52 3 1146786528 51364432 5285056
13:21:14 103580 8192000000 10084695664 10534793216 1062890064 11.484 94 21 42 1 1135037104 67851184 4295856
13:23:58 52577 8192000000 10093403296 10552004608 1131486464 12.135 100 19 38 1 1182945120 47709616 3749040
13:25:51 74004 8192000000 9062857648 9330163712 220175344 2.617 20 3 6 0 248198336 27610784 412208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-01-2021 13:29:22 to 15-01-2021 13:29:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-01-2021 13:29:37 to 15-01-2021 13:29:39.

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          240432
MON$OLDEST_ACTIVE               242361
MON$OLDEST_SNAPSHOT             242361
MON$NEXT_TRANSACTION            242361
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                       2099008
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             811
MON$NEXT_STATEMENT              53


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

15.01.21 13:29:41. 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-15 13:29:43 No severe PSQL-related problems occured
15.01.21 13:29:44. End of report.