Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2020 10: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 16805156 kB
MemAvailable 31448056 kB
Buffers 0 kB
Cached 14813216 kB
SwapCached 3696 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-19 10:26:53
DTS_END                         2020-12-19 10:26:53.5700
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/41916
TRN_ID                          226235
ATT_ID                          671
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: 8879.46 23225 1065535
customer order: creation 163.32 457 19599
customer order: refuse 14.11 320 1693
order to supplier: creation 43.06 1508 5167
order to supplier: removal 3.38 114916 406
invoice (draft): creation 43.33 3843 5200
invoice (draft): removal 50.94 909 6113
invoice accept: apply 39.05 18526 4686
invoice accept: cancel 46.46 859 5575
customer reserve: creation 3603.40 181 432408
customer reserve: removal 4588.02 68 550563
realization accept: apply 78.99 176 9479
realization accept: cancel 84.66 110 10159
payment from customer: creation 56.13 111 6736
payment from customer: removal 5.16 74 619
payment to supplier: creation 45.51 118 5461
payment to supplier: removal 3.40 87 408
service: total inventory turnovers 3.53 3551 424
service: total monetary turnovers 6.05 665 726
service: aggregate perf. data 0.91 14242 109
service: refresh index statistics 0.03 303788 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2020 10:29:08 to 19-12-2020 10:29:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7671
TEST_TIME 2 6311
TEST_TIME 3 5847
TEST_TIME 4 5972
TEST_TIME 5 5899
TEST_TIME 6 5833
TEST_TIME 7 6022
TEST_TIME 8 6464
TEST_TIME 9 6506
TEST_TIME 10 6479
TEST_TIME 11 6631
TEST_TIME 12 6720
TEST_TIME 13 6856
TEST_TIME 14 7212
TEST_TIME 15 7157
TEST_TIME 16 7598
TEST_TIME 17 7495
TEST_TIME 18 7831
TEST_TIME 19 8165
TEST_TIME 20 8605
TEST_TIME 21 8660
TEST_TIME 22 8698
TEST_TIME 23 8985
TEST_TIME 24 8902
TEST_TIME 25 9688
TEST_TIME 26 9836
TEST_TIME 27 9768
TEST_TIME 28 10819
TEST_TIME 29 11302
TEST_TIME 30 11023
TEST_TIME 31 10939
TEST_TIME 32 10785
TEST_TIME 33 10801
TEST_TIME 34 10695
TEST_TIME 35 10551
TEST_TIME 36 10436
TEST_TIME 37 10294
TEST_TIME 38 10163
TEST_TIME 39 10075
TEST_TIME 40 9984
TEST_TIME 41 9960
TEST_TIME 42 9949
TEST_TIME 43 9853
TEST_TIME 44 9739
TEST_TIME 45 9672
TEST_TIME 46 9543
TEST_TIME 47 9536
TEST_TIME 48 9603
TEST_TIME 49 9609
TEST_TIME 50 9657
TEST_TIME 51 9644
TEST_TIME 52 9579
TEST_TIME 53 9530
TEST_TIME 54 9528
TEST_TIME 55 9663
TEST_TIME 56 9649
TEST_TIME 57 9714
TEST_TIME 58 9664
TEST_TIME 59 9670
TEST_TIME 60 9630
TEST_TIME 61 9494
TEST_TIME 62 9406
TEST_TIME 63 9382
TEST_TIME 64 9273
TEST_TIME 65 9255
TEST_TIME 66 9266
TEST_TIME 67 9225
TEST_TIME 68 9191
TEST_TIME 69 9204
TEST_TIME 70 9237
TEST_TIME 71 9252
TEST_TIME 72 9396
TEST_TIME 73 9414
TEST_TIME 74 9406
TEST_TIME 75 9418
TEST_TIME 76 9418
TEST_TIME 77 9488
TEST_TIME 78 9492
TEST_TIME 79 9414
TEST_TIME 80 9310
TEST_TIME 81 9289
TEST_TIME 82 9256
TEST_TIME 83 9193
TEST_TIME 84 9141
TEST_TIME 85 9110
TEST_TIME 86 9145
TEST_TIME 87 9102
TEST_TIME 88 9076
TEST_TIME 89 9073
TEST_TIME 90 9069
TEST_TIME 91 9091
TEST_TIME 92 9105
TEST_TIME 93 9132
TEST_TIME 94 9254
TEST_TIME 95 9313
TEST_TIME 96 9318
TEST_TIME 97 9313
TEST_TIME 98 9359
TEST_TIME 99 9278
TEST_TIME 100 9200
TEST_TIME 101 9189
TEST_TIME 102 9148
TEST_TIME 103 9081
TEST_TIME 104 9039
TEST_TIME 105 9017
TEST_TIME 106 9024
TEST_TIME 107 8992
TEST_TIME 108 8987
TEST_TIME 109 8971
TEST_TIME 110 9007
TEST_TIME 111 9030
TEST_TIME 112 8994
TEST_TIME 113 9011
TEST_TIME 114 8988
TEST_TIME 115 8997
TEST_TIME 116 8992
TEST_TIME 117 8985
TEST_TIME 118 8987
TEST_TIME 119 8923
TEST_TIME 120 8889

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2020 10:29:12 to 19-12-2020 10:29:15.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1466090 1466090 0 0.00 0 18960 7 0 0
doc_list_biud 1466080 1466080 0 0.00 0 109781 32 0 0
sp_add_invoice_to_stock 4798 4686 112 2.33 16 260693 18527 0 112
sp_cancel_adding_invoice 5575 5575 0 0.00 3 63016 859 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 11203 321 0 0
sp_cancel_customer_reserve 550563 550563 0 0.00 1 109782 68 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 3 5861 74 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 9938 88 0 0
sp_cancel_supplier_invoice 6126 6113 13 0.21 2 102368 910 0 13
sp_cancel_supplier_order 406 406 0 0.00 10 1036061 114917 0 0
sp_cancel_write_off 10159 10159 0 0.00 1 9839 110 0 0
sp_client_order 19599 19599 0 0.00 4 44832 458 0 0
sp_customer_reserve 432408 432408 0 0.00 0 37944 182 0 0
sp_fill_shopping_cart 29951 29951 0 0.00 1 24209 50 0 0
sp_fill_shopping_cart_clo_res 432385 432385 0 0.00 0 24373 15 0 0
sp_get_clo_for_invoice 4663 4663 0 0.00 0 37241 410 0 0
sp_kill_cost_storno 16761 16761 0 0.00 0 9833 56 0 0
sp_kill_qstorno_ret_qs2qd 1117804 1117804 0 0.00 0 109372 37 0 0
sp_kill_qty_storno 588651 588651 0 0.00 0 109781 78 0 0
sp_lock_selected_doc 19586 19586 0 0.00 0 8651 6 0 0
sp_make_cost_storno 26339 26339 0 0.00 0 16148 52 0 0
sp_multiply_rows_for_pdistr 26339 26339 0 0.00 0 9312 28 0 0
sp_multiply_rows_for_qdistr 19599 19599 0 0.00 0 44787 250 0 0
sp_pay_from_customer 6736 6736 0 0.00 4 14294 111 0 0
sp_pay_to_supplier 5461 5461 0 0.00 5 10457 118 0 0
sp_payment_common 12197 12197 0 0.00 1 5886 17 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 11195 298 0 0
sp_qd_handle_on_invoice_upd_sts 10238 10238 0 0.00 0 28471 360 0 0
sp_qd_handle_on_reserve_upd_sts 9479 9479 0 0.00 0 15882 52 0 0
sp_reserve_write_off 9479 9479 0 0.00 6 19829 176 0 0
sp_supplier_invoice 5200 5200 0 0.00 8 91667 3843 0 0
sp_supplier_order 5167 5167 0 0.00 5 35271 1509 0 0
srv_aggregate_perf_data 109 109 0 0.00 2325 217403 14242 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1026 162818 47539 0 0
srv_find_qd_qs_mism 1020771 1020771 0 0.00 0 18959 6 0 0
srv_make_invnt_saldo 424 424 0 0.00 53 103403 3551 0 0
srv_make_money_saldo 726 726 0 0.00 1 22358 665 0 0
srv_recalc_idx_stat 4 4 0 0.00 86169 448029 303788 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4686 4686 0 0.00 0 13441 160 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 8027 102 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1578 10 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 820 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 1542 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 2254 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 357 357 0 0.00 0 4449 33 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 22514 1636 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 1885 7 0 0
t$perf-norm:sp_client_order 19599 19599 0 0.00 0 4464 16 0 0
t$perf-norm:sp_customer_reserve 839 839 0 0.00 0 2290 8 0 0
t$perf-norm:sp_pay_from_customer 6736 6736 0 0.00 0 2851 9 0 0
t$perf-norm:sp_pay_to_supplier 5461 5461 0 0.00 0 12647 13 0 0
t$perf-norm:sp_reserve_write_off 9479 9479 0 0.00 0 7846 13 0 0
t$perf-norm:sp_supplier_invoice 5200 5200 0 0.00 0 12470 22 0 0
t$perf-norm:sp_supplier_order 5167 5167 0 0.00 0 2897 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 773 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 84 5 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 6351 20 0 0
t$perf-norm:srv_make_money_saldo 726 726 0 0.00 0 533 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9548 9324 224 2.34 0 7062 18 0 224
v_all_customers 35790 35790 0 0.00 0 173 1 0 0
v_all_suppliers 28338 28338 0 0.00 0 78 1 0 0
v_all_wares 39198 39198 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 1375 7 0 0
v_cancel_client_order 3386 3386 0 0.00 0 1864 4 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 1865 6 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 433 4 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 1765 6 0 0
v_cancel_supplier_invoice 740 714 26 3.51 0 3089 16 0 26
v_cancel_supplier_order 812 812 0 0.00 0 450 4 0 0
v_cancel_write_off 1830 1830 0 0.00 0 1506 13 0 0
v_max_id_clo_ord 5160 5160 0 0.00 0 4973 3 0 0
v_max_id_clo_res 839 839 0 0.00 0 3010 20 0 0
v_max_id_ord_sup 5191 5191 0 0.00 0 4760 5 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 374 2 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 1028 3 0 0
v_min_id_clo_ord 5160 5160 0 0.00 0 2246 6 0 0
v_min_id_clo_res 839 839 0 0.00 0 6280 24 0 0
v_min_id_ord_sup 5191 5191 0 0.00 0 3961 7 0 0
v_min_non_paid_invoice 5461 5461 0 0.00 0 5253 9 0 0
v_min_non_paid_realizn 6736 6736 0 0.00 0 5884 12 0 0
v_our_firm 9684 9684 0 0.00 0 14 1 0 0
v_reserve_write_off 18958 18958 0 0.00 0 5767 6 0 0
x_lock_depdocs_on_canc_invoice 11331 11331 0 0.00 0 11531 102 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 18872 373 0 0
x_make_qstorno_1000_1200 5167 5167 0 0.00 2 35253 1435 0 0
x_make_qstorno_1200_2000 5200 5200 0 0.00 4 88298 3634 0 0
x_make_qstorno_2100_3300 432408 432408 0 0.00 0 37942 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2020 10:29:16 to 19-12-2020 10:29:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:57 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:57 36877 8192000000 9730019952 10025144320 799405056 8.890 100 86 172 0 827703952 7524496 20774400
07:59:41 18996 8192000000 9767924688 10073944064 841886752 9.319 100 88 176 0 876494112 13855920 20751440
08:01:10 16078 8192000000 9822764992 10144821248 879916320 9.699 100 85 170 0 933547088 34560528 19070240
08:02:30 19544 8192000000 9883018800 10217824256 927682560 10.172 100 81 162 1 985894208 40867424 17344224
08:03:53 9829 8192000000 9930320912 10276851712 960400816 10.493 100 76 152 2 1038997776 62892352 15704608
08:05:06 4908 8192000000 9960156736 10314653696 993856912 10.819 100 80 158 1 1067000256 57153168 15990176
08:06:12 2800 8192000000 9992092000 10351886336 1026078736 11.131 100 82 160 0 1103655040 61232992 16343312
08:07:17 2320 8192000000 10011017728 10379591680 1049998768 11.361 100 82 164 0 1124389456 57441792 16948896
08:08:21 4904 8192000000 10023583856 10403905536 1060582272 11.462 100 67 133 0 1133141376 58260560 14298544
08:09:26 4479 8192000000 9987793808 10383589376 1070700384 11.559 100 37 74 0 1118988704 40001008 8287312
08:10:33 9863 8192000000 10001845824 10398765056 1077699408 11.626 100 28 57 0 1106445056 22995632 5750016
08:11:43 5702 8192000000 10004600848 10393047040 1084304800 11.688 100 52 105 2 1126309936 30273216 11731920
08:12:50 1047 8192000000 10018782976 10398117888 1088095328 11.725 100 89 180 2 1121094304 13007520 19991456
08:13:53 5253 8192000000 10019845184 10411249664 1094353104 11.784 100 45 88 3 1127627392 23359872 9914416
08:14:59 6186 8192000000 10004042912 10405793792 1086149920 11.706 99 50 101 2 1114205984 17514208 10541856
08:16:11 51467 8192000000 10017071872 10415378432 1083901568 11.685 99 33 64 2 1098909232 7512864 7494800
08:18:08 6161 8192000000 9991756208 10392616960 1054218672 11.401 98 60 119 3 1090754848 23358640 13177536
08:19:15 4712 8192000000 9989025744 10377527296 1040600080 11.270 99 41 82 1 1076563488 27642928 8320480
08:20:22 7216 8192000000 9913199376 10289041408 961988992 10.508 95 36 71 1 986562112 16278944 8294176
08:21:32 40979 8192000000 9915621520 10297085952 975559344 10.641 95 33 66 0 989482496 6961456 6961696
08:23:17 5460 8192000000 9955412752 10337050624 1039113520 11.256 100 50 101 4 1062174240 12042288 11018432
08:24:23 9502 8192000000 9981669392 10372730880 1055773648 11.416 100 38 76 2 1089515712 25115952 8626112
08:25:34 7420 8192000000 9987484480 10380185600 1063506928 11.490 100 35 69 2 1091514864 19703296 8304640
08:26:43 37931 8192000000 9995311504 10394669056 1081086560 11.658 100 41 81 0 1119092624 29625536 8380528
08:28:22 5034 8192000000 10061689872 10458234880 1103068400 11.867 100 63 121 2 1163946912 48661568 12216944
08:29:32 20940 8192000000 10064621600 10463899648 1119185440 12.019 100 58 115 4 1170982944 39602208 12195296
08:30:57 6633 8192000000 10092440144 10494148608 1135415184 12.172 100 53 106 3 1201776016 54641520 11719312
08:32:07 18110 8192000000 10130468320 10541019136 1137718800 12.194 100 42 84 2 1242707760 96109600 8879360
08:33:34 8241 8192000000 10155574752 10558423040 1145376096 12.266 100 56 112 0 1256020832 98947696 11697040
08:34:55 12756 8192000000 10163521712 10582159360 1159504288 12.399 100 49 98 1 1271588528 100746960 11337280
08:36:12 7547 8192000000 10199465712 10606096384 1157412032 12.379 100 68 136 2 1300908080 128673264 14822784
08:37:28 3228 8192000000 10233273504 10635968512 1160038928 12.404 100 69 137 0 1336978848 161852992 15086928
08:38:36 7473 8192000000 10279491920 10686922752 1165206400 12.452 100 66 132 1 1370679520 190367088 15106032
08:39:47 1936 8192000000 10286898528 10685861888 1145783888 12.270 98 83 164 3 1368832608 205352928 17695792
08:40:54 4318 8192000000 10296269632 10710274048 1170510496 12.502 100 59 116 0 1402381408 218076272 13794640
08:42:08 3400 8192000000 10338868192 10752057344 1175624304 12.549 100 83 167 3 1444489744 249591024 19274416
08:43:16 4557 8192000000 10338236576 10743873536 1156563904 12.371 100 65 128 1 1436903008 265349536 14989568
08:44:28 3987 8192000000 10371435296 10793676800 1166888368 12.468 100 76 153 0 1468721984 283828864 18004752
08:45:37 1024 8192000000 10360091136 10753871872 1154474608 12.351 100 93 186 1 1450791232 275576944 20739680
08:46:40 10387 8192000000 10342598000 10768642048 1164556096 12.446 100 40 80 1 1445015424 270387040 10072288
08:47:58 14390 8192000000 10392430736 10815254528 1147382336 12.285 98 36 72 1 1482839200 326443360 9013504
08:49:14 7395 8192000000 10447219184 10863046656 1151401088 12.323 99 50 98 1 1540805920 375962384 13442448
08:50:29 7147 8192000000 10442917200 10872111104 1149284048 12.303 100 49 98 1 1539154320 377606624 12263648
08:51:44 23285 8192000000 10322692000 10779619328 1139887376 12.214 100 29 58 4 1490170368 342480816 7802176
08:53:27 19208 8192000000 10405528384 10846965760 1118589024 12.014 99 23 46 2 1486372800 361627632 6156144
08:55:17 13375 8192000000 10035529840 10450518016 1110165808 11.934 100 49 94 4 1139059376 17327808 11565760
08:56:31 12486 8192000000 10114703776 10528219136 1131926960 12.140 100 61 122 0 1210618864 65771408 12920496
08:57:44 97267 8192000000 10140262912 10600419328 1106490000 11.899 97 15 29 1 1209497088 99788592 3218496
09:00:23 45871 8192000000 10099558160 10533634048 1133929136 12.158 100 13 27 0 1213187488 76569184 2689168
09:02:10 47624 8192000000 10055658240 10489794560 1136803872 12.185 100 55 109 1 1167773632 19423168 11546592
09:04:03 92589 8192000000 10069332720 10516234240 1117834672 12.007 99 20 39 1 1180150000 57638896 4676432
09:06:38 64382 8192000000 10071783904 10524577792 1132228976 12.142 100 22 45 0 1200885296 64357952 4298368
09:08:54 40884 8192000000 10080812864 10516959232 1114545584 11.975 98 19 38 3 1168738752 50051440 4141728
09:10:41 64757 8192000000 10089764224 10544623616 1139107552 12.207 100 21 41 0 1202702496 59513168 4081776
09:12:55 62949 8192000000 10108512368 10611699712 1138993216 12.206 100 12 24 0 1232538752 90787344 2758192
09:14:58 32413 8192000000 10084985824 10519781376 1145414880 12.266 100 27 54 0 1189821760 38834048 5572832
09:16:31 58379 8192000000 10099283408 10566250496 1139319584 12.209 99 12 24 0 1192816048 50955808 2540656
09:18:29 72696 8192000000 10120517072 10552676352 1127477696 12.098 98 14 28 0 1170135296 39943888 2713712
09:20:46 93885 8192000000 10100135504 10575630336 1113053648 11.961 97 19 38 2 1224326096 107083936 4188512
09:23:27 49120 8192000000 10057149168 10522759168 1081971952 11.666 95 12 24 1 1114711968 30315600 2424416
09:25:26 35295 8192000000 10072899856 10508001280 1111816000 11.950 98 35 70 1 1155934784 36934736 7184048
09:27:09 124383 8192000000 10100197248 10548080640 1138994432 12.206 100 28 56 1 1216411568 71584448 5832688
09:30:21 43585 8192000000 10095584496 10554392576 1150560496 12.315 100 9 18 0 1219222064 66800560 1861008
09:32:07 47835 8192000000 10050013056 10516189184 1061631296 11.472 94 17 34 1 1102285328 37648832 3005200
09:33:56 46182 8192000000 10095429744 10539380736 1089567904 11.739 96 18 36 1 1168972784 75195600 4209280
09:35:43 51165 8192000000 10107074544 10547474432 1080878960 11.656 97 28 56 0 1160590640 73845056 5866624
09:37:34 62252 8192000000 10092433792 10597748736 1096469120 11.804 98 19 38 1 1198608128 97634496 4504512
09:39:41 46480 8192000000 10052653696 10485862400 1076033712 11.610 96 36 69 0 1125519472 42184176 7301584
09:41:29 40432 8192000000 10076670736 10534547456 1109254992 11.925 100 29 58 0 1154019152 38810512 5953648
09:43:17 160121 8192000000 10050649664 10501996544 1085168192 11.697 97 7 14 0 1124484608 37753440 1562976
09:49:11 63634 8192000000 10043213936 10545745920 1108576448 11.919 99 10 21 0 1150927008 40083312 2267248
09:51:15 77114 8192000000 10100611984 10547916800 1128422144 12.106 100 12 24 1 1186502000 55611984 2467872
09:53:33 42422 8192000000 10086404496 10522128384 1112338752 11.955 98 27 54 1 1164277728 45917840 6021136
09:55:16 53079 8192000000 10101573856 10559492096 1089184512 11.735 97 18 36 0 1185479584 91828032 4467040
09:57:37 70248 8192000000 10127933968 10560630784 1121356368 12.040 99 25 50 0 1192665360 66167696 5141296
09:59:52 52001 8192000000 10160785744 10700718080 1119636288 12.024 99 11 22 1 1268133552 146138944 2358320
10:02:01 80216 8192000000 10058370000 10529882112 1074648144 11.596 95 23 46 1 1109564992 30475936 4440912
10:04:21 162818 8192000000 10092674960 10568699904 1120698400 12.034 99 20 40 2 1188568960 63667616 4202944
10:08:30 89684 8192000000 10067147008 10525437952 1115638960 11.986 98 9 19 0 1147696480 30159568 1897952
10:12:02 118585 8192000000 10089008608 10563485696 1099363648 11.832 97 15 30 1 1157708816 54893872 3451296
10:15:01 128484 8192000000 10087717152 10561191936 1104725152 11.882 97 14 28 2 1206941648 98932208 3284288
10:18:11 39427 8192000000 10073677616 10491834368 1130859840 12.129 100 32 64 0 1151260912 13488032 6913040
10:19:52 60316 8192000000 10071156576 10506895360 1125450640 12.078 99 33 66 1 1160517664 28368064 6698960
10:22:00 51425 8192000000 10091245552 10519814144 1124399664 12.069 99 17 34 0 1154010320 26451504 3159152
10:23:52 122559 8192000000 10079202592 10571366400 1133943504 12.159 100 16 32 1 1177351472 40368896 3039072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-12-2020 10:29:22 to 19-12-2020 10:29:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2020 10:29:38 to 19-12-2020 10:29:40.

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          226117
MON$OLDEST_ACTIVE               226609
MON$OLDEST_SNAPSHOT             226609
MON$NEXT_TRANSACTION            226609
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2099008
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2300 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2300 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.2300 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Dec 19 10:26:57 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Dec 19 10:27:20 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Sat Dec 19 10:27:23 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Dec 19 10:27:23 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Dec 19 10:27:23 2020
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

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