Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2020 07:36
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 16754512 kB
MemAvailable 31336104 kB
Buffers 0 kB
Cached 14783828 kB
SwapCached 3700 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-12-24 07:36:28
DTS_END                         2020-12-24 07:36:28.8160
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/35338
TRN_ID                          231700
ATT_ID                          643
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: 9271.59 21460 1112591
customer order: creation 168.18 439 20181
customer order: refuse 14.56 304 1747
order to supplier: creation 44.94 1368 5393
order to supplier: removal 3.50 111798 420
invoice (draft): creation 45.78 3328 5494
invoice (draft): removal 53.19 873 6383
invoice accept: apply 41.17 17554 4940
invoice accept: cancel 47.75 895 5730
customer reserve: creation 3888.53 167 466624
customer reserve: removal 4674.68 67 560961
realization accept: apply 82.14 161 9857
realization accept: cancel 83.00 123 9960
payment from customer: creation 57.36 97 6883
payment from customer: removal 5.53 111 663
payment to supplier: creation 47.47 114 5696
payment to supplier: removal 3.17 72 380
service: total inventory turnovers 3.69 3189 443
service: total monetary turnovers 5.97 480 716
service: aggregate perf. data 0.97 13944 116
service: refresh index statistics 0.03 274117 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2020 07:37:13 to 24-12-2020 07:37:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6469
TEST_TIME 2 6081
TEST_TIME 3 6663
TEST_TIME 4 6169
TEST_TIME 5 5953
TEST_TIME 6 6216
TEST_TIME 7 6717
TEST_TIME 8 7601
TEST_TIME 9 7342
TEST_TIME 10 7554
TEST_TIME 11 7509
TEST_TIME 12 7476
TEST_TIME 13 7411
TEST_TIME 14 7707
TEST_TIME 15 7903
TEST_TIME 16 8091
TEST_TIME 17 8670
TEST_TIME 18 8880
TEST_TIME 19 8993
TEST_TIME 20 9105
TEST_TIME 21 9758
TEST_TIME 22 9887
TEST_TIME 23 10593
TEST_TIME 24 12002
TEST_TIME 25 11922
TEST_TIME 26 11822
TEST_TIME 27 11639
TEST_TIME 28 11269
TEST_TIME 29 11196
TEST_TIME 30 11022
TEST_TIME 31 10884
TEST_TIME 32 10761
TEST_TIME 33 10751
TEST_TIME 34 10582
TEST_TIME 35 10544
TEST_TIME 36 10395
TEST_TIME 37 10317
TEST_TIME 38 10147
TEST_TIME 39 10187
TEST_TIME 40 10087
TEST_TIME 41 10110
TEST_TIME 42 10267
TEST_TIME 43 10389
TEST_TIME 44 10319
TEST_TIME 45 10253
TEST_TIME 46 10183
TEST_TIME 47 10424
TEST_TIME 48 10351
TEST_TIME 49 10468
TEST_TIME 50 10371
TEST_TIME 51 10231
TEST_TIME 52 10074
TEST_TIME 53 10070
TEST_TIME 54 9960
TEST_TIME 55 9869
TEST_TIME 56 9789
TEST_TIME 57 9702
TEST_TIME 58 9748
TEST_TIME 59 9712
TEST_TIME 60 9756
TEST_TIME 61 9741
TEST_TIME 62 9686
TEST_TIME 63 9733
TEST_TIME 64 9802
TEST_TIME 65 9826
TEST_TIME 66 9932
TEST_TIME 67 9937
TEST_TIME 68 10153
TEST_TIME 69 10010
TEST_TIME 70 9874
TEST_TIME 71 9811
TEST_TIME 72 9776
TEST_TIME 73 9687
TEST_TIME 74 9630
TEST_TIME 75 9598
TEST_TIME 76 9605
TEST_TIME 77 9554
TEST_TIME 78 9570
TEST_TIME 79 9534
TEST_TIME 80 9567
TEST_TIME 81 9576
TEST_TIME 82 9539
TEST_TIME 83 9599
TEST_TIME 84 9557
TEST_TIME 85 9552
TEST_TIME 86 9555
TEST_TIME 87 9662
TEST_TIME 88 9615
TEST_TIME 89 9509
TEST_TIME 90 9497
TEST_TIME 91 9436
TEST_TIME 92 9408
TEST_TIME 93 9376
TEST_TIME 94 9345
TEST_TIME 95 9388
TEST_TIME 96 9430
TEST_TIME 97 9398
TEST_TIME 98 9381
TEST_TIME 99 9327
TEST_TIME 100 9439
TEST_TIME 101 9474
TEST_TIME 102 9492
TEST_TIME 103 9484
TEST_TIME 104 9500
TEST_TIME 105 9501
TEST_TIME 106 9543
TEST_TIME 107 9465
TEST_TIME 108 9409
TEST_TIME 109 9365
TEST_TIME 110 9312
TEST_TIME 111 9288
TEST_TIME 112 9269
TEST_TIME 113 9240
TEST_TIME 114 9257
TEST_TIME 115 9221
TEST_TIME 116 9220
TEST_TIME 117 9243
TEST_TIME 118 9269
TEST_TIME 119 9284
TEST_TIME 120 9276

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2020 07:37:17 to 24-12-2020 07:37:20.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1542206 1542206 0 0.00 0 18563 6 0 0
doc_list_biud 1542206 1542206 0 0.00 0 18053 31 0 0
sp_add_invoice_to_stock 5012 4940 72 1.43 37 193819 17555 0 72
sp_cancel_adding_invoice 5730 5730 0 0.00 6 77752 895 0 0
sp_cancel_client_order 1747 1747 0 0.00 5 10474 305 0 0
sp_cancel_customer_reserve 560961 560961 0 0.00 1 22160 67 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 8011 111 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 4024 72 0 0
sp_cancel_supplier_invoice 6391 6383 8 0.12 2 21530 874 0 8
sp_cancel_supplier_order 420 420 0 0.00 8 1148319 111799 0 0
sp_cancel_write_off 9960 9960 0 0.00 1 12612 123 0 0
sp_client_order 20181 20181 0 0.00 4 60189 439 0 0
sp_customer_reserve 466624 466624 0 0.00 0 99311 167 0 0
sp_fill_shopping_cart 31059 31059 0 0.00 1 14751 46 0 0
sp_fill_shopping_cart_clo_res 466614 466614 0 0.00 0 21116 19 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 40529 439 0 0
sp_kill_cost_storno 16733 16733 0 0.00 0 6914 58 0 0
sp_kill_qstorno_ret_qs2qd 1138685 1138685 0 0.00 0 18052 36 0 0
sp_kill_qty_storno 599983 599983 0 0.00 0 18053 78 0 0
sp_lock_selected_doc 20436 20436 0 0.00 0 9566 6 0 0
sp_make_cost_storno 27361 27361 0 0.00 0 17934 52 0 0
sp_multiply_rows_for_pdistr 27361 27361 0 0.00 0 9441 27 0 0
sp_multiply_rows_for_qdistr 20181 20181 0 0.00 0 59590 277 0 0
sp_pay_from_customer 6883 6883 0 0.00 4 15691 98 0 0
sp_pay_to_supplier 5696 5696 0 0.00 5 17310 115 0 0
sp_payment_common 12579 12579 0 0.00 1 4436 13 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 10466 267 0 0
sp_qd_handle_on_invoice_upd_sts 10655 10655 0 0.00 0 14186 377 0 0
sp_qd_handle_on_reserve_upd_sts 9857 9857 0 0.00 0 11268 48 0 0
sp_reserve_write_off 9857 9857 0 0.00 6 16819 162 0 0
sp_supplier_invoice 5494 5494 0 0.00 6 101064 3328 0 0
sp_supplier_order 5393 5393 0 0.00 6 22518 1368 0 0
srv_aggregate_perf_data 116 116 0 0.00 2497 94309 13945 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1289 172041 47448 0 0
srv_find_qd_qs_mism 1064293 1064293 0 0.00 0 12055 5 0 0
srv_make_invnt_saldo 443 443 0 0.00 59 109523 3190 0 0
srv_make_money_saldo 716 716 0 0.00 1 11838 480 0 0
srv_recalc_idx_stat 4 4 0 0.00 147322 404600 274117 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 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 4941 4941 0 0.00 0 25417 164 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4327 104 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 4561 19 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2616 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 8453 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 3684 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 321 8 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 16126 1430 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 2738 12 0 0
t$perf-norm:sp_client_order 20181 20181 0 0.00 0 12286 16 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 6016 11 0 0
t$perf-norm:sp_pay_from_customer 6883 6883 0 0.00 0 3093 8 0 0
t$perf-norm:sp_pay_to_supplier 5696 5696 0 0.00 0 4980 8 0 0
t$perf-norm:sp_reserve_write_off 9857 9857 0 0.00 0 4002 9 0 0
t$perf-norm:sp_supplier_invoice 5494 5494 0 0.00 0 5478 14 0 0
t$perf-norm:sp_supplier_order 5393 5393 0 0.00 0 4421 13 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 117 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 900 17 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 1593 8 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 3464 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 9992 9848 144 1.44 0 7004 17 0 144
v_all_customers 36912 36912 0 0.00 0 30 1 0 0
v_all_suppliers 30360 30360 0 0.00 0 35 1 0 0
v_all_wares 40362 40362 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 2066 8 0 0
v_cancel_client_order 3494 3494 0 0.00 0 5549 14 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 8006 18 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 3751 11 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 3997 11 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 2416 17 0 16
v_cancel_supplier_order 840 840 0 0.00 0 3921 17 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2871 14 0 0
v_max_id_clo_ord 5392 5392 0 0.00 0 627 1 0 0
v_max_id_clo_res 968 968 0 0.00 0 3726 16 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 3996 4 0 0
v_max_non_paid_invoice 1394 1394 0 0.00 0 295 2 0 0
v_max_non_paid_realizn 3545 3545 0 0.00 0 2497 3 0 0
v_min_id_clo_ord 5392 5392 0 0.00 0 4674 6 0 0
v_min_id_clo_res 968 968 0 0.00 0 7377 18 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 7053 8 0 0
v_min_non_paid_invoice 5696 5696 0 0.00 0 3711 8 0 0
v_min_non_paid_realizn 6883 6883 0 0.00 0 4432 8 0 0
v_our_firm 10126 10126 0 0.00 0 36 1 0 0
v_reserve_write_off 19714 19714 0 0.00 0 7332 6 0 0
x_lock_depdocs_on_canc_invoice 11737 11737 0 0.00 0 22269 100 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 13876 335 0 0
x_make_qstorno_1000_1200 5393 5393 0 0.00 2 22510 1296 0 0
x_make_qstorno_1200_2000 5494 5494 0 0.00 3 101050 3146 0 0
x_make_qstorno_2100_3300 466624 466624 0 0.00 0 99309 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-12-2020 07:37:21 to 24-12-2020 07:37:25.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:06:32 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
05:07:33 20553 8192000000 9716664048 10008158208 792780688 8.823 100 89 178 0 822798960 8174656 21843616
05:08:58 25627 8192000000 9759498448 10065186816 832888752 9.228 100 91 183 0 866608592 11800128 21919712
05:10:34 16695 8192000000 9805166080 10126913536 867911712 9.579 100 77 154 1 908640000 23129216 17599072
05:12:01 14407 8192000000 9875442176 10206887936 923157136 10.127 100 79 158 1 977979856 37749488 17073232
05:13:19 7792 8192000000 9934942896 10273583104 954349440 10.434 100 83 166 1 1040967968 69259776 17358752
05:14:30 7135 8192000000 9971088208 10321879040 987819984 10.760 100 80 160 1 1074443440 70724048 15899408
05:15:38 2211 8192000000 9987361280 10345603072 1015322560 11.027 100 86 167 0 1100180784 67368192 17490032
05:16:42 2554 8192000000 10026828432 10389286912 1043814912 11.301 100 88 174 0 1133934608 72361792 17757904
05:17:46 1737 8192000000 10012185584 10378764288 1060795232 11.464 100 90 180 1 1128632704 48897328 18940144
05:18:50 17669 8192000000 9960172384 10363129856 1062753120 11.483 100 27 53 4 1106229488 37664640 5811728
05:20:11 10714 8192000000 9986008416 10393186304 1070794864 11.560 100 14 28 0 1101383760 27457232 3131664
05:21:28 5337 8192000000 9987965744 10378358784 1079852112 11.646 100 35 68 2 1108910880 21756192 7302576
05:22:37 17470 8192000000 10013386496 10402136064 1083547120 11.681 100 64 128 1 1118925360 21372528 14005712
05:23:55 5543 8192000000 10019037088 10412343296 1089897168 11.742 100 40 83 1 1120351024 20682528 9771328
05:25:03 11315 8192000000 10006146144 10408087552 1086087344 11.705 99 27 52 1 1126569584 35012880 5469360
05:26:16 18282 8192000000 10005542896 10428133376 1082931472 11.675 99 32 64 0 1111144560 21211088 7002000
05:27:35 8105 8192000000 9994855488 10384310272 1046635712 11.328 97 46 92 2 1074354064 16636160 11082192
05:28:45 5545 8192000000 9937571328 10322067456 995992704 10.840 96 39 76 1 1016542944 12478208 8072032
05:29:55 66601 8192000000 9934852752 10331930624 970661776 10.593 94 26 52 1 996055872 19893200 5500896
05:32:08 7554 8192000000 9961624736 10345394176 1021378784 11.085 100 62 121 3 1065467936 29937600 14151552
05:33:16 7250 8192000000 9956036240 10343452672 1029103680 11.160 99 38 72 4 1052371280 14392256 8875344
05:34:25 8149 8192000000 9973884736 10368774144 1054542816 11.404 100 24 47 2 1095538048 36681824 4313408
05:35:37 36031 8192000000 9978057024 10373230592 1062782832 11.483 100 37 74 2 1088761280 18568800 7409648
05:37:20 30003 8192000000 10044495680 10439614464 1095572368 11.796 100 48 96 2 1142685152 37991728 9121056
05:38:55 32182 8192000000 10079806880 10474426368 1115382992 11.983 100 60 120 2 1193411440 65510512 12517936
05:40:31 26911 8192000000 10120548672 10536894464 1131571776 12.136 100 54 108 1 1230983264 87440128 11971360
05:42:08 13530 8192000000 10152663152 10558664704 1145712000 12.269 100 39 77 1 1254844528 99967088 9165440
05:43:24 3694 8192000000 10193234384 10603917312 1153471504 12.342 100 54 108 3 1295942656 130116352 12354800
05:44:31 3611 8192000000 10206087904 10620907520 1161794960 12.420 100 69 136 2 1327581472 151599872 14186640
05:45:38 9591 8192000000 10246555600 10659913728 1169571888 12.493 100 59 116 1 1356378656 173714672 13092096
05:46:54 2256 8192000000 10298137856 10704736256 1171345152 12.509 100 71 142 0 1399373856 212293072 15735632
05:48:00 1874 8192000000 10318172864 10723684352 1177930928 12.571 100 70 139 0 1419409872 225552464 15926480
05:49:09 2951 8192000000 10408116096 10814222336 1196839104 12.747 100 84 168 3 1500865152 284520192 19505856
05:50:16 2029 8192000000 10471935040 10876551168 1189907904 12.683 100 91 179 2 1568340608 358120608 20312096
05:51:22 1286 8192000000 10514725392 10925752320 1193667248 12.717 100 86 172 1 1610771328 397135024 19969056
05:52:29 2518 8192000000 10520245648 10937188352 1194340048 12.724 100 71 142 1 1613655040 402794512 16520480
05:53:35 6320 8192000000 10500501504 10956525568 1179674800 12.587 100 43 86 0 1600416912 409522656 11219456
05:54:50 12463 8192000000 10548661872 10988564480 1178709984 12.578 100 49 96 2 1644960752 454065616 12185152
05:56:08 13585 8192000000 10530652464 10994724864 1155769168 12.364 100 34 68 2 1624233472 458935632 9528672
05:57:31 30289 8192000000 10403444560 10882539520 1130699872 12.128 98 26 52 1 1528549776 390660784 7189120
05:59:04 42093 8192000000 10331274192 10855043072 1092207392 11.764 98 24 48 0 1470193888 371338592 6647904
06:00:47 60130 8192000000 10065273904 10523160576 1069771376 11.550 97 9 18 1 1130206240 58623840 1811024
06:02:48 35980 8192000000 10071363904 10487508992 1083481328 11.681 98 37 75 3 1137763904 46524208 7758368
06:04:25 172041 8192000000 10020386496 10471571456 1051617056 11.376 96 29 58 1 1110258672 52714864 5926752
06:08:18 13809 8192000000 10063038240 10482765824 1098982032 11.828 99 47 92 2 1145002112 36588240 9431840
06:09:33 27339 8192000000 10054072544 10503135232 1110999344 11.942 100 16 32 1 1183470352 69078528 3392480
06:11:08 35340 8192000000 10062010912 10533748736 1114491376 11.975 99 24 49 5 1189142592 69811872 4839344
06:12:44 125475 8192000000 10087300480 10530922496 1116815248 11.997 99 14 28 1 1176263168 56918800 2529120
06:15:56 58393 8192000000 10142846480 10571268096 1128154528 12.104 99 26 52 2 1219119264 84149232 6815504
06:17:55 75232 8192000000 10080676688 10581270528 1092837824 11.770 96 18 36 1 1187020608 89956880 4225904
06:20:11 32404 8192000000 10060136160 10492026880 1114191936 11.972 99 27 53 0 1154075376 34420368 5463072
06:21:49 32428 8192000000 10128610240 10557255680 1132262416 12.143 99 29 58 2 1205991952 67972368 5757168
06:23:22 71595 8192000000 10093318480 10546348032 1125702144 12.081 99 16 32 0 1185068880 55648576 3718160
06:25:34 66510 8192000000 10086846192 10529738752 1128576240 12.108 99 18 36 1 1176577936 43663984 4337712
06:27:45 88666 8192000000 10073244304 10539114496 1126150144 12.085 99 26 52 0 1177686592 45773632 5762816
06:30:23 60761 8192000000 10076412480 10557071360 1130115648 12.122 99 7 14 0 1176627232 44613120 1898464
06:32:29 74679 8192000000 10109023392 10561019904 1098311776 11.822 96 14 28 0 1164397792 62836768 3249248
06:35:06 45240 8192000000 10123708496 10571034624 1129670112 12.118 99 19 37 1 1209866176 75879568 4316496
06:37:00 32877 8192000000 10143664992 10569596928 1138852176 12.205 99 29 58 1 1217135008 72277232 6005600
06:38:41 63998 8192000000 10171885808 10639691776 1112990912 11.961 96 24 49 1 1237788160 119547040 5250208
06:40:45 47137 8192000000 10142008736 10573606912 1113932848 11.970 98 27 53 1 1247493072 127648064 5912160
06:42:42 22381 8192000000 10088475152 10513596416 1111290880 11.945 99 41 82 3 1156039632 36253344 8495408
06:44:19 40659 8192000000 10062353168 10600554496 1081424752 11.661 97 8 16 0 1200101600 116935904 1740944
06:46:22 71118 8192000000 10050432080 10507714560 1061972720 11.475 96 18 36 0 1107350672 41607104 3770848
06:48:46 50412 8192000000 10047360912 10516299776 1096007856 11.800 99 10 20 0 1136252688 38189584 2055248
06:50:45 104884 8192000000 10077950048 10559045632 1107048992 11.904 99 15 30 1 1171921344 61528528 3343824
06:54:08 54757 8192000000 10077634576 10542219264 1103455952 11.870 98 20 40 2 1168277184 60492304 4328928
06:56:06 22574 8192000000 10030257520 10480934912 1067030448 11.524 96 19 37 1 1114808192 43359056 4418688
06:57:31 39369 8192000000 10066232864 10496716800 1082776176 11.674 98 43 85 1 1139231408 47726576 8728656
06:59:15 70708 8192000000 10049707744 10517360640 1106171616 11.896 100 37 74 1 1169004608 55714576 7118416
07:01:26 44307 8192000000 10061764176 10538049536 1101608784 11.853 99 20 40 1 1167916592 62198000 4109808
07:03:16 50386 8192000000 10075112912 10542206976 1115715552 11.986 100 18 36 0 1187595952 67915264 3965136
07:05:11 92229 8192000000 10058213056 10526064640 1121934704 12.045 100 12 24 0 1178399488 53744608 2720176
07:07:57 30531 8192000000 10052016528 10546077696 1120875632 12.035 100 5 10 0 1169577408 47622112 1079664
07:10:38 32355 8192000000 10109749712 10539851776 1121149280 12.038 99 27 52 0 1203142880 76586224 5407376
07:12:13 52842 8192000000 10065823824 10494922752 1093618688 11.777 97 24 49 1 1146346848 47943888 4784272
07:14:07 46230 8192000000 10103894160 10530955264 1109350848 11.926 98 24 48 1 1160896032 46167024 5378160
07:15:54 96676 8192000000 10107586032 10691575808 1135492272 12.173 100 33 66 0 1265296800 122276928 7527600
07:18:32 77712 8192000000 10102406432 10563182592 1127483744 12.098 99 14 28 0 1173805264 43333568 2987952
07:21:08 9094 8192000000 10098007968 10514591744 1141660928 12.231 100 66 132 4 1170652544 13996656 14994960
07:22:23 118894 8192000000 10076359392 10589925376 1134991296 12.168 100 8 16 0 1209449536 72699776 1758464
07:25:40 48921 8192000000 10058588768 10527219712 1125150896 12.076 99 11 22 1 1158970256 31673968 2145392
07:27:31 76930 8192000000 10054717776 10527252480 1119958208 12.027 98 7 14 1 1156136544 34780752 1397584
07:29:49 69167 8192000000 10090627696 10554757120 1079670224 11.644 94 24 48 1 1135777632 51183248 4924160
07:32:04 57556 8192000000 10139114880 10611191808 1135904960 12.177 100 23 46 0 1235637904 94608944 5124000
07:34:01 151229 8192000000 9615838048 10023911424 669018384 7.550 60 6 12 0 726916032 56590800 1306848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2020 07:37:27 to 24-12-2020 07:37:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 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 24-12-2020 07:37:43 to 24-12-2020 07:37:45.

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          231201
MON$OLDEST_ACTIVE               231921
MON$OLDEST_SNAPSHOT             231921
MON$NEXT_TRANSACTION            231921
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                       2088608
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             797
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2303 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.2303 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Dec 24 07:36:35 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Dec 24 07:36:35 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

24.12.20 07:37:46. 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-12-24 07:37:48 No severe PSQL-related problems occured
24.12.20 07:37:50. End of report.