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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16783140 kB
MemAvailable 31242912 kB
Buffers 0 kB
Cached 14863220 kB
SwapCached 4868 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-27 07:26:56
DTS_END                         2021-01-27 07:26:56.1660
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/53094
TRN_ID                          230929
ATT_ID                          599
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: 9119.15 21514 1094298
customer order: creation 167.18 409 20062
customer order: refuse 14.07 316 1688
order to supplier: creation 45.36 1452 5443
order to supplier: removal 3.39 129135 407
invoice (draft): creation 43.69 3230 5243
invoice (draft): removal 50.72 1003 6086
invoice accept: apply 41.07 17524 4928
invoice accept: cancel 46.19 812 5543
customer reserve: creation 3861.73 169 463408
customer reserve: removal 4560.78 76 547294
realization accept: apply 82.65 164 9918
realization accept: cancel 78.14 134 9377
payment from customer: creation 57.50 106 6900
payment from customer: removal 4.97 88 596
payment to supplier: creation 47.63 120 5716
payment to supplier: removal 3.37 77 404
service: total inventory turnovers 4.03 3042 484
service: total monetary turnovers 5.69 461 683
service: aggregate perf. data 0.95 13402 114
service: refresh index statistics 0.03 258566 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-01-2021 07:28:00 to 27-01-2021 07:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2803
TEST_TIME 2 3427
TEST_TIME 3 4198
TEST_TIME 4 4454
TEST_TIME 5 4374
TEST_TIME 6 4613
TEST_TIME 7 4553
TEST_TIME 8 4622
TEST_TIME 9 4736
TEST_TIME 10 5030
TEST_TIME 11 5151
TEST_TIME 12 5500
TEST_TIME 13 5448
TEST_TIME 14 5820
TEST_TIME 15 6059
TEST_TIME 16 6341
TEST_TIME 17 6411
TEST_TIME 18 6680
TEST_TIME 19 6973
TEST_TIME 20 7172
TEST_TIME 21 7245
TEST_TIME 22 7978
TEST_TIME 23 8026
TEST_TIME 24 9051
TEST_TIME 25 9074
TEST_TIME 26 10930
TEST_TIME 27 11272
TEST_TIME 28 11021
TEST_TIME 29 10719
TEST_TIME 30 10445
TEST_TIME 31 10352
TEST_TIME 32 10317
TEST_TIME 33 10341
TEST_TIME 34 10254
TEST_TIME 35 10117
TEST_TIME 36 9918
TEST_TIME 37 9845
TEST_TIME 38 9710
TEST_TIME 39 9808
TEST_TIME 40 9890
TEST_TIME 41 9845
TEST_TIME 42 9877
TEST_TIME 43 9802
TEST_TIME 44 9828
TEST_TIME 45 9815
TEST_TIME 46 9716
TEST_TIME 47 9741
TEST_TIME 48 9911
TEST_TIME 49 9939
TEST_TIME 50 9834
TEST_TIME 51 9785
TEST_TIME 52 9681
TEST_TIME 53 9625
TEST_TIME 54 9537
TEST_TIME 55 9500
TEST_TIME 56 9413
TEST_TIME 57 9426
TEST_TIME 58 9410
TEST_TIME 59 9320
TEST_TIME 60 9349
TEST_TIME 61 9396
TEST_TIME 62 9405
TEST_TIME 63 9453
TEST_TIME 64 9403
TEST_TIME 65 9528
TEST_TIME 66 9459
TEST_TIME 67 9512
TEST_TIME 68 9580
TEST_TIME 69 9455
TEST_TIME 70 9435
TEST_TIME 71 9363
TEST_TIME 72 9327
TEST_TIME 73 9275
TEST_TIME 74 9238
TEST_TIME 75 9199
TEST_TIME 76 9188
TEST_TIME 77 9193
TEST_TIME 78 9132
TEST_TIME 79 9242
TEST_TIME 80 9239
TEST_TIME 81 9330
TEST_TIME 82 9303
TEST_TIME 83 9375
TEST_TIME 84 9424
TEST_TIME 85 9339
TEST_TIME 86 9241
TEST_TIME 87 9255
TEST_TIME 88 9221
TEST_TIME 89 9214
TEST_TIME 90 9171
TEST_TIME 91 9205
TEST_TIME 92 9191
TEST_TIME 93 9205
TEST_TIME 94 9198
TEST_TIME 95 9194
TEST_TIME 96 9206
TEST_TIME 97 9187
TEST_TIME 98 9199
TEST_TIME 99 9250
TEST_TIME 100 9279
TEST_TIME 101 9318
TEST_TIME 102 9309
TEST_TIME 103 9333
TEST_TIME 104 9304
TEST_TIME 105 9234
TEST_TIME 106 9175
TEST_TIME 107 9178
TEST_TIME 108 9147
TEST_TIME 109 9120
TEST_TIME 110 9074
TEST_TIME 111 9133
TEST_TIME 112 9088
TEST_TIME 113 9122
TEST_TIME 114 9086
TEST_TIME 115 9067
TEST_TIME 116 9095
TEST_TIME 117 9091
TEST_TIME 118 9075
TEST_TIME 119 9113
TEST_TIME 120 9123

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-01-2021 07:28:04 to 27-01-2021 07:28:07.

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 1522354 1522354 0 0.00 0 15522 6 0 0
doc_list_biud 1522350 1522350 0 0.00 0 70160 34 0 0
sp_add_invoice_to_stock 5077 4928 149 2.93 33 177763 17524 0 149
sp_cancel_adding_invoice 5543 5543 0 0.00 5 96077 813 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 10147 316 0 0
sp_cancel_customer_reserve 547294 547294 0 0.00 1 70161 76 0 0
sp_cancel_pay_from_customer 596 596 0 0.00 2 5633 88 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 2430 77 0 0
sp_cancel_supplier_invoice 6095 6086 9 0.14 2 30896 1004 0 9
sp_cancel_supplier_order 407 407 0 0.00 9 1189603 129135 0 0
sp_cancel_write_off 9377 9377 0 0.00 1 13806 135 0 0
sp_client_order 20062 20062 0 0.00 4 74427 409 0 0
sp_customer_reserve 463408 463408 0 0.00 0 117215 169 0 0
sp_fill_shopping_cart 30742 30742 0 0.00 1 13273 40 0 0
sp_fill_shopping_cart_clo_res 463403 463403 0 0.00 0 24869 19 0 0
sp_get_clo_for_invoice 4921 4921 0 0.00 0 40314 397 0 0
sp_kill_cost_storno 15920 15920 0 0.00 0 13803 64 0 0
sp_kill_qstorno_ret_qs2qd 1110458 1110458 0 0.00 0 70145 41 0 0
sp_kill_qty_storno 585232 585232 0 0.00 0 70160 87 0 0
sp_lock_selected_doc 20374 20374 0 0.00 0 7831 5 0 0
sp_make_cost_storno 27455 27455 0 0.00 0 15510 54 0 0
sp_multiply_rows_for_pdistr 27454 27454 0 0.00 0 8399 33 0 0
sp_multiply_rows_for_qdistr 20062 20062 0 0.00 0 74415 229 0 0
sp_pay_from_customer 6900 6900 0 0.00 4 9986 106 0 0
sp_pay_to_supplier 5716 5716 0 0.00 4 11623 120 0 0
sp_payment_common 12616 12616 0 0.00 1 8003 21 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 10135 286 0 0
sp_qd_handle_on_invoice_upd_sts 10462 10462 0 0.00 0 24749 366 0 0
sp_qd_handle_on_reserve_upd_sts 9918 9918 0 0.00 0 14294 46 0 0
sp_reserve_write_off 9918 9918 0 0.00 6 14428 165 0 0
sp_supplier_invoice 5243 5243 0 0.00 7 94146 3230 0 0
sp_supplier_order 5443 5443 0 0.00 6 39963 1453 0 0
srv_aggregate_perf_data 114 114 0 0.00 2401 216317 13403 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1040 134155 48746 0 0
srv_find_qd_qs_mism 1046989 1046989 0 0.00 0 11290 5 0 0
srv_make_invnt_saldo 484 484 0 0.00 40 107948 3042 0 0
srv_make_money_saldo 683 683 0 0.00 1 11274 461 0 0
srv_recalc_idx_stat 4 4 0 0.00 222851 295600 258566 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4928 4928 0 0.00 0 9321 141 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 2598 51 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 2676 11 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 7942 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 596 596 0 0.00 0 380 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 3454 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1585 16 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 22512 1527 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 2445 10 0 0
t$perf-norm:sp_client_order 20062 20062 0 0.00 0 7531 17 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 559 3 0 0
t$perf-norm:sp_pay_from_customer 6900 6900 0 0.00 0 4466 7 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 5721 9 0 0
t$perf-norm:sp_reserve_write_off 9918 9918 0 0.00 0 5886 10 0 0
t$perf-norm:sp_supplier_invoice 5243 5243 0 0.00 0 7300 16 0 0
t$perf-norm:sp_supplier_order 5443 5443 0 0.00 0 4489 15 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 2584 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 142 3 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 931 6 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 947 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 187 48 0 0
v_add_invoice_to_stock 10136 9838 298 2.94 0 4918 15 0 298
v_all_customers 37088 37088 0 0.00 0 68 1 0 0
v_all_suppliers 29740 29740 0 0.00 0 33 1 0 0
v_all_wares 40124 40124 0 0.00 0 60 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1804 8 0 0
v_cancel_client_order 3376 3376 0 0.00 0 1382 4 0 0
v_cancel_customer_prepayment 1192 1192 0 0.00 0 5625 15 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 2932 5 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2417 10 0 0
v_cancel_supplier_invoice 760 742 18 2.36 0 678 13 0 18
v_cancel_supplier_order 814 814 0 0.00 0 820 6 0 0
v_cancel_write_off 1980 1980 0 0.00 0 3988 17 0 0
v_max_id_clo_ord 5443 5443 0 0.00 0 283 1 0 0
v_max_id_clo_res 977 977 0 0.00 0 1716 10 0 0
v_max_id_ord_sup 5237 5237 0 0.00 0 6065 6 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 736 2 0 0
v_max_non_paid_realizn 3487 3487 0 0.00 0 3927 4 0 0
v_min_id_clo_ord 5443 5443 0 0.00 0 2988 7 0 0
v_min_id_clo_res 977 977 0 0.00 0 5391 22 0 0
v_min_id_ord_sup 5237 5237 0 0.00 0 10191 8 0 0
v_min_non_paid_invoice 5716 5716 0 0.00 0 6737 13 0 0
v_min_non_paid_realizn 6900 6900 0 0.00 0 7993 15 0 0
v_our_firm 9862 9862 0 0.00 0 14 1 0 0
v_reserve_write_off 19836 19836 0 0.00 0 4104 6 0 0
x_lock_depdocs_on_canc_invoice 11258 11258 0 0.00 0 10954 114 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 5300 343 0 0
x_make_qstorno_1000_1200 5443 5443 0 0.00 2 39953 1382 0 0
x_make_qstorno_1200_2000 5243 5243 0 0.00 3 94135 3075 0 0
x_make_qstorno_2100_3300 463408 463408 0 0.00 0 117214 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-01-2021 07:28:08 to 27-01-2021 07:28:12.

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
04:56:59 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
04:58:00 14831 8192000000 9720703264 10011230208 796055264 8.856 100 86 172 0 824406832 7370512 20981056
04:59:21 19847 8192000000 9753194448 10057850880 831713984 9.216 100 87 174 1 864897296 12614176 20569136
05:00:50 13721 8192000000 9800361712 10113449984 865038992 9.551 100 86 172 0 903769568 18798288 19932288
05:02:10 12737 8192000000 9855022160 10188419072 919020032 10.086 100 73 144 1 968840320 33975136 15845152
05:03:26 6217 8192000000 9896188256 10240942080 959928176 10.488 100 73 146 2 1008478800 33037568 15513056
05:04:36 3898 8192000000 9967521920 10315980800 989476592 10.776 100 85 167 3 1079946608 73963840 16506176
05:05:42 2923 8192000000 9994941568 10353979392 1009554544 10.971 100 96 192 0 1108253824 79471936 19227344
05:06:48 2898 8192000000 10006655920 10369417216 1032087344 11.189 100 85 169 1 1115656672 67084256 16485072
05:07:53 1454 8192000000 10019907968 10387374080 1054992416 11.409 100 95 189 1 1136768912 62070160 19706336
05:08:56 7223 8192000000 10020812352 10390503424 1058239936 11.440 100 81 161 2 1139738960 64333552 17165472
05:10:04 3975 8192000000 9990295984 10379759616 1068103840 11.534 100 39 77 0 1111932544 35566784 8261920
05:11:12 4367 8192000000 9989333680 10375540736 1077507328 11.624 100 50 100 2 1104348944 16335408 10506208
05:12:18 11297 8192000000 10002477504 10407174144 1086600464 11.710 100 32 59 5 1117017008 24033568 6382976
05:13:30 47146 8192000000 9997985456 10394976256 1093745056 11.778 100 39 77 2 1119615312 17431296 8438960
05:15:17 6807 8192000000 10008561120 10411159552 1096715872 11.806 100 27 54 1 1130572944 28026560 5830512
05:16:28 12947 8192000000 10008212944 10420195328 1090375840 11.746 99 27 54 1 1127677040 31553248 5747952
05:17:42 5665 8192000000 9990361920 10378465280 1054810160 11.407 98 43 85 2 1087172096 23010320 9351616
05:18:51 30449 8192000000 9981264048 10367225856 1046701744 11.329 98 66 135 1 1073471168 11574080 15195344
05:20:24 9144 8192000000 9960889984 10358751232 1042434400 11.288 99 40 78 1 1075227472 23971120 8821952
05:21:38 7787 8192000000 9955125008 10338258944 1010113456 10.976 98 47 93 2 1043337280 22677392 10546432
05:22:47 5946 8192000000 9945259264 10326499328 1034681600 11.214 100 33 68 0 1057728144 16632608 6413936
05:23:53 73071 8192000000 10011093136 10412478464 1053170192 11.391 100 33 65 1 1098472976 38767360 6535424
05:26:06 8720 8192000000 9982734432 10376228864 1064288912 11.498 100 45 89 1 1085128576 10424672 10414992
05:27:20 6561 8192000000 10001920816 10392985600 1083855184 11.684 100 46 92 1 1108090384 14224112 10011088
05:28:37 41246 8192000000 10070256864 10475532288 1108142704 11.915 100 37 69 2 1181258176 65600016 7515456
05:30:27 6683 8192000000 10098157440 10494341120 1127816848 12.101 100 54 105 2 1207246960 67560336 11869776
05:31:39 59373 8192000000 10145834864 10570448896 1137080784 12.188 100 45 90 5 1249193648 102244416 9868448
05:33:47 18823 8192000000 10172479952 10578194432 1147882768 12.290 100 52 104 0 1278790016 119705456 11201792
05:35:11 13667 8192000000 10195896800 10602651648 1151305936 12.322 100 54 106 2 1298555968 135610736 11639296
05:36:29 3837 8192000000 10236667264 10645729280 1163942384 12.440 100 59 118 0 1350712336 172296656 14473296
05:37:38 4580 8192000000 10258439968 10666508288 1159708784 12.401 99 60 120 3 1362979136 189951440 13318912
05:38:47 6351 8192000000 10299824096 10718146560 1182317392 12.612 100 64 128 1 1401929360 204771280 14840688
05:39:59 3546 8192000000 10343896800 10779308032 1192252800 12.704 100 67 134 1 1458179920 250639280 15287840
05:41:06 1780 8192000000 10404739088 10816937984 1208655936 12.857 100 77 154 0 1511773520 284789776 18327808
05:42:15 5198 8192000000 10458794800 10871779328 1215585312 12.921 100 78 156 0 1564152704 330094048 18473344
05:43:25 1035 8192000000 10512916512 10918641664 1217013136 12.934 100 94 188 0 1610302160 371267040 22021984
05:44:36 2736 8192000000 10540164656 10954981376 1216289184 12.927 100 82 164 0 1646377456 409967136 20121136
05:45:42 13026 8192000000 10589510608 11037155328 1211378240 12.882 99 53 106 0 1700779104 475469680 13931184
05:46:57 6420 8192000000 10615051600 11047370752 1214349600 12.909 100 48 96 0 1717769168 490852912 12566656
05:48:09 6705 8192000000 10566054320 11006246912 1191950448 12.702 100 49 98 0 1668534976 463856768 12727760
05:49:21 30069 8192000000 10555930784 11003015168 1158301472 12.387 99 40 80 1 1631543040 462014336 11227232
05:50:57 47314 8192000000 10430439264 10916237312 1135033808 12.169 99 25 50 1 1514644400 373194256 6416336
05:53:17 75135 8192000000 10047928928 10458042368 1072699472 11.578 95 40 79 2 1134313776 52772464 8841840
05:55:35 99394 8192000000 10045996880 10463408128 1071962272 11.571 95 36 72 0 1105832496 26410816 7459408
05:58:15 48531 8192000000 10067905776 10530103296 1088533344 11.729 98 24 48 0 1149059232 55542336 4983552
06:00:42 119707 8192000000 10104929680 10539057152 1089471120 11.738 98 24 47 3 1140221968 45134816 5616032
06:03:44 78814 8192000000 10142581120 10603970560 1096241824 11.802 98 20 40 1 1219139824 117542128 5355872
06:06:11 42474 8192000000 10066253120 10545168384 1093189440 11.773 98 22 44 0 1192127168 93758880 5178848
06:07:59 37062 8192000000 10032496832 10471436288 1110334128 11.936 99 17 34 1 1140985504 27533440 3117936
06:09:40 23014 8192000000 10109987392 10546003968 1123635088 12.061 99 37 73 0 1192088320 61287808 7165424
06:11:06 73967 8192000000 10130199952 10570096640 1126079616 12.084 99 25 49 0 1215726528 83902016 5744896
06:13:31 41691 8192000000 10119206800 10594631680 1124986320 12.074 99 27 54 0 1225585616 94378704 6220592
06:15:14 38181 8192000000 10073074352 10528669696 1099243760 11.830 97 14 28 0 1157783216 55496240 3043216
06:16:53 120648 8192000000 10069333360 10529562624 1125897744 12.083 100 31 62 1 1170253456 37502976 6852736
06:19:56 42545 8192000000 10059024208 10526658560 1133720336 12.156 100 9 18 1 1174594096 39181216 1692544
06:21:39 75329 8192000000 10066398544 10509492224 1114285136 11.973 98 15 28 0 1154360640 36272032 3803472
06:24:06 39899 8192000000 10052453760 10500636672 1123557600 12.061 99 13 26 0 1151173552 24877216 2738736
06:25:46 78294 8192000000 10116767184 10574839808 1145451264 12.267 100 15 30 1 1224061296 75353968 3256064
06:28:06 84622 8192000000 10120227984 10569981952 1078181824 11.630 94 21 43 0 1140193744 57858608 4153312
06:30:36 79566 8192000000 10081001952 10583797760 1141996288 12.234 100 17 34 1 1237932192 92470912 3464992
06:32:56 39663 8192000000 10120889488 10594893824 1128222880 12.105 98 39 78 0 1234149600 98327488 7599232
06:34:43 134154 8192000000 10054864240 10516602880 1098555216 11.824 96 16 32 0 1150174000 48004032 3614752
06:37:58 55174 8192000000 10042163232 10519695360 1105239056 11.887 98 4 8 0 1137487504 31006960 1241488
06:40:11 49213 8192000000 10075610064 10506244096 1127029088 12.093 99 19 37 1 1168283152 37255568 3998496
06:42:15 18523 8192000000 10132505280 10551435264 1148009728 12.291 100 59 116 1 1218380912 58092768 12278416
06:43:38 58139 8192000000 10138925024 10576449536 1092751584 11.769 96 16 32 0 1191613840 94982496 3879760
06:45:39 80501 8192000000 10142453872 10573955072 1111768112 11.949 99 11 22 0 1235121520 120764896 2588512
06:48:05 34373 8192000000 10105839296 10530045952 1106362464 11.898 99 43 86 1 1175152992 60116192 8674336
06:49:45 100181 8192000000 10065609792 10480648192 1080972928 11.657 96 27 55 0 1158181600 71317792 5890880
06:52:26 60218 8192000000 10052065744 10520530944 1092307824 11.765 98 17 34 0 1151335712 55692272 3335616
06:55:16 60550 8192000000 10061337168 10532278272 1100665760 11.844 98 8 16 2 1159090432 56610240 1814432
06:57:39 40646 8192000000 10044080592 10477039616 1092213424 11.764 97 13 26 0 1120055968 25477568 2364976
06:59:23 54050 8192000000 10078216880 10526695424 1125292256 12.077 100 14 26 2 1185549664 57827696 2429712
07:01:17 27990 8192000000 10081229728 10509582336 1115559328 11.985 98 22 43 2 1160862704 41357712 3945664
07:02:46 38134 8192000000 10100822304 10546135040 1128426240 12.107 99 30 60 1 1164973328 30576560 5970528
07:04:31 86331 8192000000 10096596944 10591227904 1110042784 11.933 97 23 46 0 1159705232 45144768 4517680
07:08:03 89209 8192000000 10077618128 10553843712 1099757536 11.835 96 10 20 0 1135105120 33545984 1801600
07:10:32 127109 8192000000 10085907952 10525736960 1114859872 11.978 98 21 42 0 1164114320 44987824 4266624
07:13:41 63355 8192000000 10099708096 10592313344 1143813600 12.251 100 7 15 0 1202480064 57228752 1437712
07:15:51 89451 8192000000 10112016528 10598830080 1118045728 12.009 98 15 30 0 1202697936 81258336 3393872
07:18:21 31274 8192000000 10077389568 10512764928 1120547408 12.032 99 25 48 0 1168008768 42634416 4826944
07:19:57 34080 8192000000 10065002192 10505859072 1125311280 12.077 99 19 36 3 1154934864 25217888 4405696
07:21:39 56462 8192000000 10070245984 10544881664 1116260592 11.992 98 18 36 1 1182866096 62707488 3898016
07:23:47 66340 8192000000 10155511136 10623991808 1156999136 12.375 100 13 26 0 1247791088 87956816 2835136
07:25:54 49277 8192000000 10131299424 10608394240 1114467024 11.975 97 19 38 1 1201370800 82542784 4360992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-01-2021 07:28:14 to 27-01-2021 07:28:29.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-01-2021 07:28:30 to 27-01-2021 07:28:32.

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          230248
MON$OLDEST_ACTIVE               231636
MON$OLDEST_SNAPSHOT             231636
MON$NEXT_TRANSACTION            231636
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                       2076640
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2342 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.2342 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Wed Jan 27 07:27:16 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Wed Jan 27 07:27:30 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

27.01.21 07:28:33. 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-27 07:28:35 No severe PSQL-related problems occured
27.01.21 07:28:37. End of report.