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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16921556 kB
MemAvailable 31483900 kB
Buffers 0 kB
Cached 14772432 kB
SwapCached 2844 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-05 13:26:55
DTS_END                         2020-11-05 13:26:55.4390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51720
TRN_ID                          230907
ATT_ID                          578
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: 8948.47 28442 1073816
customer order: creation 157.63 454 18915
customer order: refuse 13.79 312 1655
order to supplier: creation 43.55 1345 5226
order to supplier: removal 3.23 126736 388
invoice (draft): creation 42.96 3332 5155
invoice (draft): removal 52.00 1089 6240
invoice accept: apply 39.33 17477 4720
invoice accept: cancel 46.82 896 5618
customer reserve: creation 3678.65 169 441438
customer reserve: removal 4579.20 69 549504
realization accept: apply 78.55 160 9426
realization accept: cancel 90.35 120 10842
payment from customer: creation 56.17 104 6740
payment from customer: removal 5.49 78 659
payment to supplier: creation 46.68 111 5602
payment to supplier: removal 3.52 64 422
service: total inventory turnovers 3.86 3298 463
service: total monetary turnovers 5.80 588 696
service: aggregate perf. data 0.86 13398 103
service: refresh index statistics 0.03 399030 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-11-2020 13:28:32 to 05-11-2020 13:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2054
TEST_TIME 2 2755
TEST_TIME 3 3744
TEST_TIME 4 4029
TEST_TIME 5 4443
TEST_TIME 6 4435
TEST_TIME 7 4473
TEST_TIME 8 4860
TEST_TIME 9 4851
TEST_TIME 10 5353
TEST_TIME 11 5716
TEST_TIME 12 5795
TEST_TIME 13 5962
TEST_TIME 14 6157
TEST_TIME 15 6260
TEST_TIME 16 6636
TEST_TIME 17 6740
TEST_TIME 18 7011
TEST_TIME 19 7658
TEST_TIME 20 7970
TEST_TIME 21 8264
TEST_TIME 22 8610
TEST_TIME 23 8966
TEST_TIME 24 9387
TEST_TIME 25 9694
TEST_TIME 26 10020
TEST_TIME 27 11637
TEST_TIME 28 11777
TEST_TIME 29 11561
TEST_TIME 30 11233
TEST_TIME 31 10940
TEST_TIME 32 10844
TEST_TIME 33 10694
TEST_TIME 34 10605
TEST_TIME 35 10450
TEST_TIME 36 10451
TEST_TIME 37 10304
TEST_TIME 38 10121
TEST_TIME 39 10201
TEST_TIME 40 10250
TEST_TIME 41 10030
TEST_TIME 42 10037
TEST_TIME 43 9932
TEST_TIME 44 9866
TEST_TIME 45 9899
TEST_TIME 46 9926
TEST_TIME 47 9960
TEST_TIME 48 9884
TEST_TIME 49 9849
TEST_TIME 50 10006
TEST_TIME 51 10119
TEST_TIME 52 10021
TEST_TIME 53 10080
TEST_TIME 54 10167
TEST_TIME 55 10183
TEST_TIME 56 10120
TEST_TIME 57 9972
TEST_TIME 58 9876
TEST_TIME 59 9755
TEST_TIME 60 9645
TEST_TIME 61 9608
TEST_TIME 62 9563
TEST_TIME 63 9664
TEST_TIME 64 9594
TEST_TIME 65 9605
TEST_TIME 66 9549
TEST_TIME 67 9553
TEST_TIME 68 9587
TEST_TIME 69 9595
TEST_TIME 70 9558
TEST_TIME 71 9608
TEST_TIME 72 9584
TEST_TIME 73 9695
TEST_TIME 74 9727
TEST_TIME 75 9650
TEST_TIME 76 9553
TEST_TIME 77 9487
TEST_TIME 78 9439
TEST_TIME 79 9388
TEST_TIME 80 9337
TEST_TIME 81 9282
TEST_TIME 82 9364
TEST_TIME 83 9326
TEST_TIME 84 9338
TEST_TIME 85 9296
TEST_TIME 86 9347
TEST_TIME 87 9306
TEST_TIME 88 9300
TEST_TIME 89 9409
TEST_TIME 90 9368
TEST_TIME 91 9408
TEST_TIME 92 9398
TEST_TIME 93 9361
TEST_TIME 94 9287
TEST_TIME 95 9302
TEST_TIME 96 9246
TEST_TIME 97 9178
TEST_TIME 98 9141
TEST_TIME 99 9105
TEST_TIME 100 9151
TEST_TIME 101 9118
TEST_TIME 102 9134
TEST_TIME 103 9122
TEST_TIME 104 9165
TEST_TIME 105 9175
TEST_TIME 106 9189
TEST_TIME 107 9173
TEST_TIME 108 9225
TEST_TIME 109 9238
TEST_TIME 110 9221
TEST_TIME 111 9246
TEST_TIME 112 9239
TEST_TIME 113 9175
TEST_TIME 114 9116
TEST_TIME 115 9088
TEST_TIME 116 9046
TEST_TIME 117 8988
TEST_TIME 118 8953
TEST_TIME 119 8948
TEST_TIME 120 8952

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-11-2020 13:28:36 to 05-11-2020 13:28:39.

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 1483561 1483561 0 0.00 0 20992 6 0 0
doc_list_biud 1483559 1483559 0 0.00 0 26601 33 0 0
sp_add_invoice_to_stock 4786 4720 66 1.37 13 161620 17478 0 66
sp_cancel_adding_invoice 5618 5618 0 0.00 5 66033 896 0 0
sp_cancel_client_order 1655 1655 0 0.00 5 9904 313 0 0
sp_cancel_customer_reserve 549504 549504 0 0.00 1 26604 69 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 2608 78 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 1866 65 0 0
sp_cancel_supplier_invoice 6243 6240 3 0.04 2 24871 1089 0 3
sp_cancel_supplier_order 388 388 0 0.00 6 1280309 126736 0 0
sp_cancel_write_off 10842 10842 0 0.00 1 13262 120 0 0
sp_client_order 18915 18915 0 0.00 4 58858 454 0 0
sp_customer_reserve 441438 441438 0 0.00 0 35141 170 0 0
sp_fill_shopping_cart 29260 29260 0 0.00 1 24084 47 0 0
sp_fill_shopping_cart_clo_res 441431 441431 0 0.00 0 25741 16 0 0
sp_get_clo_for_invoice 4712 4712 0 0.00 0 42574 366 0 0
sp_kill_cost_storno 17541 17541 0 0.00 0 6350 60 0 0
sp_kill_qstorno_ret_qs2qd 1116478 1116478 0 0.00 0 26583 38 0 0
sp_kill_qty_storno 588384 588384 0 0.00 0 26601 81 0 0
sp_lock_selected_doc 19533 19533 0 0.00 0 8593 5 0 0
sp_make_cost_storno 26480 26480 0 0.00 0 20976 50 0 0
sp_multiply_rows_for_pdistr 26479 26479 0 0.00 0 7215 29 0 0
sp_multiply_rows_for_qdistr 18915 18915 0 0.00 0 58807 284 0 0
sp_pay_from_customer 6740 6740 0 0.00 4 7503 105 0 0
sp_pay_to_supplier 5602 5602 0 0.00 5 12439 112 0 0
sp_payment_common 12342 12342 0 0.00 1 7021 20 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 9899 290 0 0
sp_qd_handle_on_invoice_upd_sts 10329 10329 0 0.00 0 22008 378 0 0
sp_qd_handle_on_reserve_upd_sts 9426 9426 0 0.00 0 12537 45 0 0
sp_reserve_write_off 9426 9426 0 0.00 7 15295 160 0 0
sp_supplier_invoice 5155 5155 0 0.00 7 72153 3332 0 0
sp_supplier_order 5226 5226 0 0.00 5 35646 1346 0 0
srv_aggregate_perf_data 103 103 0 0.00 2594 143737 13398 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 860 126916 49393 0 0
srv_find_qd_qs_mism 1029281 1029281 0 0.00 0 10807 5 0 0
srv_make_invnt_saldo 463 463 0 0.00 43 121787 3299 0 0
srv_make_money_saldo 696 696 0 0.00 1 11759 588 0 0
srv_recalc_idx_stat 4 4 0 0.00 216319 587502 399030 0 0
t$perf-abend:sp_add_invoice_to_stock 66 66 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4720 4720 0 0.00 0 8750 176 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 8904 124 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 2171 16 0 0
t$perf-norm:sp_cancel_customer_reserve 604 604 0 0.00 0 1417 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 5443 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 632 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 2793 24 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 18765 1565 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 230 3 0 0
t$perf-norm:sp_client_order 18916 18916 0 0.00 0 6375 15 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 2540 5 0 0
t$perf-norm:sp_pay_from_customer 6740 6740 0 0.00 0 3557 9 0 0
t$perf-norm:sp_pay_to_supplier 5602 5602 0 0.00 0 4916 6 0 0
t$perf-norm:sp_reserve_write_off 9426 9426 0 0.00 0 5004 9 0 0
t$perf-norm:sp_supplier_invoice 5155 5155 0 0.00 0 3599 16 0 0
t$perf-norm:sp_supplier_order 5226 5226 0 0.00 0 4208 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 133 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 142 3 0 0
t$perf-norm:srv_make_invnt_saldo 463 463 0 0.00 0 2550 14 0 0
t$perf-norm:srv_make_money_saldo 696 696 0 0.00 0 453 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9546 9414 132 1.38 0 7848 15 0 132
v_all_customers 34968 34968 0 0.00 0 23 1 0 0
v_all_suppliers 29096 29096 0 0.00 0 18 1 0 0
v_all_wares 37826 37826 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 3570 18 0 0
v_cancel_client_order 3310 3310 0 0.00 0 1461 3 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 1058 6 0 0
v_cancel_customer_reserve 1208 1208 0 0.00 0 740 4 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 404 4 0 0
v_cancel_supplier_invoice 706 700 6 0.84 0 332 10 0 6
v_cancel_supplier_order 776 776 0 0.00 0 3424 9 0 0
v_cancel_write_off 1846 1846 0 0.00 0 3393 17 0 0
v_max_id_clo_ord 5212 5212 0 0.00 0 3360 2 0 0
v_max_id_clo_res 952 952 0 0.00 0 3536 18 0 0
v_max_id_ord_sup 5134 5134 0 0.00 0 4393 6 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 1217 3 0 0
v_max_non_paid_realizn 3400 3400 0 0.00 0 7017 5 0 0
v_min_id_clo_ord 5212 5212 0 0.00 0 5723 9 0 0
v_min_id_clo_res 952 952 0 0.00 0 6828 33 0 0
v_min_id_ord_sup 5134 5134 0 0.00 0 6182 10 0 0
v_min_non_paid_invoice 5602 5602 0 0.00 0 4327 10 0 0
v_min_non_paid_realizn 6740 6740 0 0.00 0 4791 16 0 0
v_our_firm 9538 9538 0 0.00 0 17 1 0 0
v_reserve_write_off 18852 18852 0 0.00 0 4208 6 0 0
x_lock_depdocs_on_canc_invoice 11508 11508 0 0.00 0 21894 108 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 8182 342 0 0
x_make_qstorno_1000_1200 5226 5226 0 0.00 2 35638 1268 0 0
x_make_qstorno_1200_2000 5155 5155 0 0.00 4 64654 3146 0 0
x_make_qstorno_2100_3300 441438 441438 0 0.00 0 28979 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2020 13:28:40 to 05-11-2020 13:28:44.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:59 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:58:00 22940 8192000000 9724474496 10019909632 794532320 8.841 100 88 176 1 822631696 7426128 20673248
10:59:33 19867 8192000000 9765088704 10067779584 834828688 9.248 100 92 184 1 870326608 13524160 21973760
11:01:06 13016 8192000000 9797544720 10115579904 867419360 9.574 100 83 166 1 912836656 26178816 19238480
11:02:21 9534 8192000000 9868994080 10199896064 923723360 10.133 100 81 162 0 976512624 35716816 17072448
11:03:33 6182 8192000000 9927511664 10270896128 952315456 10.414 100 83 164 0 1033545360 64397904 16832000
11:04:43 3751 8192000000 9959132960 10310897664 995066000 10.831 100 87 173 3 1077740496 64985792 17688704
11:05:48 2836 8192000000 10004832528 10367868928 1023486864 11.106 100 93 185 2 1119573680 77257104 18829712
11:06:52 2161 8192000000 10022203680 10393350144 1052154832 11.381 100 78 156 0 1137137280 68970192 16012256
11:07:56 1124 8192000000 10045951088 10420150272 1063068656 11.486 100 92 183 0 1159595536 77758928 18767952
11:08:58 4682 8192000000 9978425584 10376790016 1068574128 11.538 100 29 58 3 1108539088 33118688 6846272
11:10:03 19873 8192000000 9983953056 10397786112 1077527056 11.624 100 42 81 7 1118848912 32617520 8704336
11:11:30 4751 8192000000 10004652144 10416013312 1082287056 11.669 100 28 56 1 1131066000 43240672 5538272
11:12:36 6883 8192000000 10002393376 10405748736 1084200064 11.687 100 29 59 0 1116555168 25683888 6671216
11:13:43 5051 8192000000 10005207808 10411028480 1093339376 11.774 100 34 68 0 1123603392 22664992 7599024
11:14:51 61209 8192000000 9986062928 10403803136 1066902192 11.522 98 42 82 1 1093026176 17287840 8836144
11:16:54 5060 8192000000 9972171120 10360774656 1058766912 11.445 99 42 82 1 1089808544 21059392 9982240
11:18:01 6158 8192000000 9956605840 10342510592 1021085840 11.082 97 35 70 0 1042157216 12914832 8156544
11:19:08 7222 8192000000 9955090000 10354241536 1024593968 11.116 99 24 46 2 1063736944 33626512 5516464
11:20:15 31074 8192000000 9949770064 10338865152 960419104 10.493 93 31 62 1 1001495024 34785920 6290000
11:21:48 9101 8192000000 9914420480 10298576896 979993952 10.684 96 33 66 1 1014036208 26757552 7284704
11:22:57 11870 8192000000 9959672688 10342760448 1026022512 11.130 100 37 73 2 1057054240 22406304 8625424
11:24:10 3597 8192000000 9956744912 10338471936 1042352080 11.287 100 38 75 2 1059972688 8673360 8947248
11:25:14 39984 8192000000 9973611072 10367455232 1053560352 11.395 100 35 71 2 1084323072 22934640 7828080
11:26:54 15121 8192000000 9982391120 10372231168 1062294336 11.478 100 59 117 0 1090445504 14954928 13196240
11:28:46 13448 8192000000 10043620656 10437238784 1084569344 11.691 100 47 95 1 1147127024 51864688 10692992
11:30:04 11786 8192000000 10080576448 10472456192 1103802576 11.874 100 46 92 1 1182830320 69107120 9920624
11:31:31 33374 8192000000 10131878288 10546364416 1117258720 12.001 100 56 112 0 1250912784 120493728 13160336
11:33:07 38518 8192000000 10201499840 10611777536 1128346112 12.106 100 40 78 1 1288256400 151029280 8881008
11:34:49 3740 8192000000 10220960096 10625073152 1141881712 12.233 100 67 135 4 1331496272 174504064 15110496
11:35:56 11638 8192000000 10264819008 10663448576 1146128656 12.273 100 60 117 2 1374293040 214962640 13201744
11:37:12 3000 8192000000 10289685920 10698870784 1165806864 12.458 100 68 136 1 1389238800 208853696 14578240
11:38:20 6663 8192000000 10322902384 10724044800 1165285840 12.453 100 71 142 0 1421411712 240904144 15221728
11:39:34 1205 8192000000 10374124128 10771005440 1171143504 12.508 100 76 151 2 1475140016 287447632 16548880
11:40:40 2306 8192000000 10430753936 10832101376 1186087680 12.647 100 87 172 3 1530581776 325300048 19194048
11:41:47 4236 8192000000 10474054336 10875895808 1177265024 12.565 100 76 150 0 1572795648 377554048 17976576
11:42:58 2524 8192000000 10461927968 10864586752 1160411728 12.407 99 82 162 0 1558101984 378076944 19613312
11:44:05 860 8192000000 10510407664 10903887872 1151392480 12.323 100 91 184 1 1605671712 432859728 21419504
11:45:11 2655 8192000000 10530703280 10932928512 1157371200 12.379 100 83 164 0 1633165312 456397024 19397088
11:46:16 14086 8192000000 10470117040 10912559104 1133223728 12.152 99 44 86 0 1579351536 434014832 12112976
11:47:40 3287 8192000000 10457468432 10877022208 1132347056 12.143 99 51 101 0 1550280368 404836928 13096384
11:48:47 8698 8192000000 10454808512 10933305344 1147719200 12.288 100 48 97 0 1573066592 412624304 12723088
11:49:57 27092 8192000000 10417842176 10908737536 1136969904 12.187 100 26 52 1 1530170624 385734000 7466720
11:51:44 56496 8192000000 10387166800 10867830784 1140381360 12.219 100 18 36 1 1514488512 369002400 5104752
11:53:41 43642 8192000000 10079351440 10590621696 1115183200 11.981 99 9 18 1 1202765152 85529632 2052320
11:55:25 113018 8192000000 10050650512 10492923904 1110951824 11.941 99 21 42 2 1139630000 24005424 4672752
11:58:23 36515 8192000000 10067474176 10512965632 1117889840 12.007 99 33 67 2 1169575392 45272160 6413392
12:00:01 29027 8192000000 10100780416 10543595520 1133663040 12.156 100 42 84 3 1190241040 47902288 8675712
12:01:44 72500 8192000000 10095710448 10547679232 1125244176 12.077 99 16 32 1 1192726304 63829312 3652816
12:03:57 35399 8192000000 10092882416 10541072384 1098732688 11.826 97 16 31 2 1171239184 68819952 3686544
12:05:37 71349 8192000000 10065200800 10558668800 1112459024 11.956 98 18 36 0 1191317808 75427040 3431744
12:08:17 71536 8192000000 10050881536 10571669504 1109953328 11.932 98 10 20 0 1157573664 45963664 1656672
12:10:30 49961 8192000000 10096380112 10574729216 1135848960 12.176 100 14 28 2 1204885856 66467168 2569728
12:12:52 70801 8192000000 10112371312 10560921600 1130725328 12.128 99 19 38 0 1175792032 40560864 4505840
12:15:24 45824 8192000000 10170020496 10643537920 1139926240 12.215 99 31 62 0 1279502368 132895696 6680432
12:17:13 47374 8192000000 10088353360 10559234048 1107377984 11.908 97 15 29 2 1194194352 83374848 3441520
12:19:05 39519 8192000000 10133939808 10566025216 1142747568 12.241 99 43 86 0 1216229824 64600496 8881760
12:20:50 76856 8192000000 10109088560 10553032704 1107235536 11.906 97 30 60 1 1162185168 48993728 5955904
12:23:17 84502 8192000000 10075489376 10526339072 1135838864 12.176 100 15 30 0 1206425408 67503968 3082576
12:25:49 75114 8192000000 10113642880 10575118336 1124786544 12.072 98 11 22 0 1193084944 65671472 2626928
12:28:05 79792 8192000000 10099347888 10584895488 1113122368 11.962 97 15 30 0 1212406768 95775152 3509248
12:30:25 44775 8192000000 10089589536 10537738240 1143750704 12.251 100 16 32 1 1178174704 31048080 3375920
12:32:19 41755 8192000000 10086620576 10514571264 1105862192 11.893 97 28 55 2 1147730320 36427920 5440208
12:34:05 60312 8192000000 10078444032 10516250624 1130345904 12.125 99 13 26 0 1182552560 49300832 2905824
12:36:07 48412 8192000000 10036146992 10470805504 1047905392 11.341 93 22 44 0 1077682096 25712800 4063904
12:37:59 112921 8192000000 10068787776 10580127744 1078890432 11.637 97 12 24 0 1137139536 55964672 2284432
12:40:53 125374 8192000000 10047455504 10516221952 1091800640 11.760 98 14 28 0 1142709584 48173664 2735280
12:46:12 101089 8192000000 10094185840 10539347968 1076330672 11.612 96 15 30 1 1114489440 35116784 3041984
12:49:13 90386 8192000000 10045961888 10495660032 1054730368 11.406 94 27 55 0 1099213760 39158944 5324448
12:51:47 55582 8192000000 10061445360 10543910912 1101096384 11.848 99 24 48 0 1149945024 44227600 4621040
12:53:44 89274 8192000000 10115663440 10598473728 1099529840 11.833 97 17 34 0 1161949168 58796640 3622688
12:56:15 22791 8192000000 10075263680 10497015808 1127849744 12.101 100 32 63 0 1158926416 24770704 6305968
12:57:55 40020 8192000000 10120791696 10592321536 1124460464 12.069 100 16 32 2 1191830848 64132176 3238208
12:59:39 126916 8192000000 10037405744 10501128192 1081940352 11.666 96 16 32 0 1154023824 68516704 3566768
13:02:56 48363 8192000000 10036090880 10498285568 1116530480 11.994 100 7 14 0 1146101808 28073376 1497952
13:04:45 56158 8192000000 10064596448 10508562432 1122526704 12.051 100 14 28 0 1150842704 25336192 2979808
13:06:51 125086 8192000000 10094817600 10542862336 1118592752 12.014 98 16 32 2 1186430800 64670704 3167344
13:10:07 55753 8192000000 10103796096 10589597696 1132233664 12.142 98 20 40 1 1205881808 69509680 4138464
13:12:02 74165 8192000000 10145925536 10609524736 1139091920 12.207 100 13 26 1 1214081936 72385744 2604272
13:14:17 60813 8192000000 10125551808 10583060480 1146415296 12.276 100 28 56 1 1210482608 58233552 5833760
13:16:22 30358 8192000000 10082683792 10509602816 1126321024 12.087 98 37 74 3 1149661520 15693536 7646960
13:17:53 108063 8192000000 10042116864 10492121088 1058550272 11.443 93 9 18 1 1095610304 35462416 1597616
13:21:26 59748 8192000000 10045111920 10496475136 1076060368 11.610 96 7 12 1 1092317264 14692176 1564720
13:23:27 85794 8192000000 10098353472 10552606720 1100716608 11.844 98 11 22 0 1176732624 73274656 2741360
13:26:24 45546 8192000000 9064520896 9346281472 214873888 2.555 19 5 10 0 266528816 50167264 1487664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 66 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2020 13:29:02 to 05-11-2020 13:29:04.

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          231264
MON$OLDEST_ACTIVE               231265
MON$OLDEST_SNAPSHOT             231265
MON$NEXT_TRANSACTION            231265
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                       2089568
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             793
MON$NEXT_STATEMENT              53


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

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