Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.11.2020 10: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 17040052 kB
MemAvailable 31429516 kB
Buffers 0 kB
Cached 14603148 kB
SwapCached 2824 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2020-11-06 10:26:54
DTS_END                         2020-11-06 10:26:54.3880
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/36968
TRN_ID                          215996
ATT_ID                          574
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: 8754.76 22838 1050571
customer order: creation 154.49 431 18539
customer order: refuse 13.23 355 1587
order to supplier: creation 41.53 1401 4983
order to supplier: removal 3.29 132142 395
invoice (draft): creation 41.50 3755 4980
invoice (draft): removal 51.88 970 6225
invoice accept: apply 38.13 18408 4576
invoice accept: cancel 47.12 954 5654
customer reserve: creation 3513.72 180 421646
customer reserve: removal 4575.60 75 549072
realization accept: apply 75.60 205 9072
realization accept: cancel 83.14 143 9977
payment from customer: creation 53.68 112 6441
payment from customer: removal 4.94 93 593
payment to supplier: creation 43.85 121 5262
payment to supplier: removal 3.12 96 374
service: total inventory turnovers 3.61 3781 433
service: total monetary turnovers 5.45 606 654
service: aggregate perf. data 0.87 15475 104
service: refresh index statistics 0.03 277461 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-11-2020 10:28:05 to 06-11-2020 10:28:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3675
TEST_TIME 2 3791
TEST_TIME 3 4080
TEST_TIME 4 4396
TEST_TIME 5 4249
TEST_TIME 6 4340
TEST_TIME 7 4459
TEST_TIME 8 4462
TEST_TIME 9 4506
TEST_TIME 10 4447
TEST_TIME 11 4488
TEST_TIME 12 4928
TEST_TIME 13 4956
TEST_TIME 14 5209
TEST_TIME 15 5506
TEST_TIME 16 5527
TEST_TIME 17 5987
TEST_TIME 18 6268
TEST_TIME 19 6356
TEST_TIME 20 6693
TEST_TIME 21 6952
TEST_TIME 22 7533
TEST_TIME 23 7850
TEST_TIME 24 7981
TEST_TIME 25 8114
TEST_TIME 26 8406
TEST_TIME 27 8304
TEST_TIME 28 9031
TEST_TIME 29 9680
TEST_TIME 30 10450
TEST_TIME 31 10624
TEST_TIME 32 10312
TEST_TIME 33 10120
TEST_TIME 34 10041
TEST_TIME 35 9954
TEST_TIME 36 9933
TEST_TIME 37 9811
TEST_TIME 38 9648
TEST_TIME 39 9577
TEST_TIME 40 9611
TEST_TIME 41 9442
TEST_TIME 42 9493
TEST_TIME 43 9561
TEST_TIME 44 9499
TEST_TIME 45 9465
TEST_TIME 46 9767
TEST_TIME 47 9751
TEST_TIME 48 9787
TEST_TIME 49 9755
TEST_TIME 50 9584
TEST_TIME 51 9411
TEST_TIME 52 9309
TEST_TIME 53 9264
TEST_TIME 54 9240
TEST_TIME 55 9251
TEST_TIME 56 9236
TEST_TIME 57 9198
TEST_TIME 58 9120
TEST_TIME 59 9074
TEST_TIME 60 8989
TEST_TIME 61 9006
TEST_TIME 62 8944
TEST_TIME 63 9009
TEST_TIME 64 8974
TEST_TIME 65 9010
TEST_TIME 66 9194
TEST_TIME 67 9163
TEST_TIME 68 9135
TEST_TIME 69 9221
TEST_TIME 70 9186
TEST_TIME 71 9185
TEST_TIME 72 9203
TEST_TIME 73 9254
TEST_TIME 74 9292
TEST_TIME 75 9215
TEST_TIME 76 9107
TEST_TIME 77 9086
TEST_TIME 78 9061
TEST_TIME 79 8992
TEST_TIME 80 8949
TEST_TIME 81 8910
TEST_TIME 82 8927
TEST_TIME 83 8879
TEST_TIME 84 8882
TEST_TIME 85 8836
TEST_TIME 86 8827
TEST_TIME 87 8958
TEST_TIME 88 9009
TEST_TIME 89 8994
TEST_TIME 90 9005
TEST_TIME 91 9088
TEST_TIME 92 9047
TEST_TIME 93 9076
TEST_TIME 94 9091
TEST_TIME 95 9124
TEST_TIME 96 9039
TEST_TIME 97 9043
TEST_TIME 98 8978
TEST_TIME 99 8928
TEST_TIME 100 8880
TEST_TIME 101 8856
TEST_TIME 102 8883
TEST_TIME 103 8859
TEST_TIME 104 8887
TEST_TIME 105 8880
TEST_TIME 106 8890
TEST_TIME 107 8917
TEST_TIME 108 8920
TEST_TIME 109 8956
TEST_TIME 110 8927
TEST_TIME 111 8989
TEST_TIME 112 8976
TEST_TIME 113 8898
TEST_TIME 114 8898
TEST_TIME 115 8852
TEST_TIME 116 8819
TEST_TIME 117 8786
TEST_TIME 118 8757
TEST_TIME 119 8769
TEST_TIME 120 8759

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-11-2020 10:28:09 to 06-11-2020 10:28:12.

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 1442973 1442973 0 0.00 0 14511 6 0 0
doc_list_biud 1442972 1442972 0 0.00 0 41231 36 0 0
sp_add_invoice_to_stock 4698 4576 122 2.59 38 166023 18409 0 122
sp_cancel_adding_invoice 5654 5654 0 0.00 5 75563 955 0 0
sp_cancel_client_order 1587 1587 0 0.00 4 6998 355 0 0
sp_cancel_customer_reserve 549072 549072 0 0.00 1 28928 75 0 0
sp_cancel_pay_from_customer 593 593 0 0.00 3 6780 93 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 6066 97 0 0
sp_cancel_supplier_invoice 6235 6225 10 0.16 2 27067 970 0 10
sp_cancel_supplier_order 395 395 0 0.00 11 1229072 132142 0 0
sp_cancel_write_off 9977 9977 0 0.00 1 41238 143 0 0
sp_client_order 18539 18539 0 0.00 4 41108 432 0 0
sp_customer_reserve 421646 421646 0 0.00 0 101702 181 0 0
sp_fill_shopping_cart 28479 28479 0 0.00 1 22985 46 0 0
sp_fill_shopping_cart_clo_res 421642 421642 0 0.00 0 23903 20 0 0
sp_get_clo_for_invoice 4571 4571 0 0.00 0 46334 461 0 0
sp_kill_cost_storno 16598 16598 0 0.00 0 11004 70 0 0
sp_kill_qstorno_ret_qs2qd 1114741 1114741 0 0.00 0 41229 40 0 0
sp_kill_qty_storno 586552 586552 0 0.00 0 41231 87 0 0
sp_lock_selected_doc 18938 18938 0 0.00 0 9021 7 0 0
sp_make_cost_storno 25345 25345 0 0.00 0 13977 58 0 0
sp_multiply_rows_for_pdistr 25345 25345 0 0.00 0 9402 36 0 0
sp_multiply_rows_for_qdistr 18539 18539 0 0.00 0 40926 237 0 0
sp_pay_from_customer 6441 6441 0 0.00 4 12122 112 0 0
sp_pay_to_supplier 5262 5262 0 0.00 4 11429 122 0 0
sp_payment_common 11703 11703 0 0.00 1 11413 20 0 0
sp_qd_handle_on_cancel_clo 1587 1587 0 0.00 0 6981 324 0 0
sp_qd_handle_on_invoice_upd_sts 10224 10224 0 0.00 0 23077 420 0 0
sp_qd_handle_on_reserve_upd_sts 9072 9072 0 0.00 0 11711 59 0 0
sp_reserve_write_off 9072 9072 0 0.00 6 19362 206 0 0
sp_supplier_invoice 4980 4980 0 0.00 7 128703 3756 0 0
sp_supplier_order 4983 4983 0 0.00 6 30102 1402 0 0
srv_aggregate_perf_data 104 104 0 0.00 2828 166425 15475 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1867 158073 51677 0 0
srv_find_qd_qs_mism 1008439 1008439 0 0.00 0 14510 5 0 0
srv_make_invnt_saldo 433 433 0 0.00 46 159604 3781 0 0
srv_make_money_saldo 654 654 0 0.00 1 19401 606 0 0
srv_recalc_idx_stat 4 4 0 0.00 80553 474081 277461 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4577 4577 0 0.00 0 21872 210 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 4512 111 0 0
t$perf-norm:sp_cancel_client_order 1587 1587 0 0.00 0 2186 11 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 2119 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 593 593 0 0.00 0 1787 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1604 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 1158 17 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 22282 1600 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 10702 25 0 0
t$perf-norm:sp_client_order 18539 18539 0 0.00 0 6372 21 0 0
t$perf-norm:sp_customer_reserve 885 885 0 0.00 0 5529 17 0 0
t$perf-norm:sp_pay_from_customer 6441 6441 0 0.00 0 4044 11 0 0
t$perf-norm:sp_pay_to_supplier 5262 5262 0 0.00 0 7204 18 0 0
t$perf-norm:sp_reserve_write_off 9072 9072 0 0.00 0 16071 16 0 0
t$perf-norm:sp_supplier_invoice 4980 4980 0 0.00 0 6489 22 0 0
t$perf-norm:sp_supplier_order 4983 4983 0 0.00 0 8340 17 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 5084 57 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1183 33 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 1860 10 0 0
t$perf-norm:srv_make_money_saldo 654 654 0 0.00 0 1551 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 9378 9134 244 2.60 0 4317 16 0 244
v_all_customers 34256 34256 0 0.00 0 75 1 0 0
v_all_suppliers 27726 27726 0 0.00 0 22 1 0 0
v_all_wares 37072 37072 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1315 7 0 0
v_cancel_client_order 3174 3174 0 0.00 0 3120 6 0 0
v_cancel_customer_prepayment 1186 1186 0 0.00 0 3963 15 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 2081 6 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 1801 6 0 0
v_cancel_supplier_invoice 780 760 20 2.56 0 5465 26 0 20
v_cancel_supplier_order 790 790 0 0.00 0 3025 6 0 0
v_cancel_write_off 1858 1858 0 0.00 0 1250 13 0 0
v_max_id_clo_ord 4975 4975 0 0.00 0 739 1 0 0
v_max_id_clo_res 885 885 0 0.00 0 14064 36 0 0
v_max_id_ord_sup 4967 4967 0 0.00 0 10812 7 0 0
v_max_non_paid_invoice 1341 1341 0 0.00 0 89 2 0 0
v_max_non_paid_realizn 3236 3236 0 0.00 0 1423 3 0 0
v_min_id_clo_ord 4975 4975 0 0.00 0 3262 6 0 0
v_min_id_clo_res 885 885 0 0.00 0 4003 12 0 0
v_min_id_ord_sup 4967 4967 0 0.00 0 4042 5 0 0
v_min_non_paid_invoice 5262 5262 0 0.00 0 8058 13 0 0
v_min_non_paid_realizn 6441 6441 0 0.00 0 6856 13 0 0
v_our_firm 9226 9226 0 0.00 0 15 1 0 0
v_reserve_write_off 18144 18144 0 0.00 0 3780 7 0 0
x_lock_depdocs_on_canc_invoice 11499 11499 0 0.00 0 10255 112 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 16361 430 0 0
x_make_qstorno_1000_1200 4983 4983 0 0.00 2 30096 1320 0 0
x_make_qstorno_1200_2000 4980 4980 0 0.00 4 126433 3581 0 0
x_make_qstorno_2100_3300 421645 421645 0 0.00 0 101701 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-11-2020 10:28:13 to 06-11-2020 10:28:17.

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:58 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:59 34957 8192000000 9711817584 10003730432 783990880 8.734 100 91 182 3 814255072 7215360 23048832
07:59:39 35987 8192000000 9748811632 10051362816 814913712 9.047 100 86 173 0 848303424 12332944 21056768
08:01:25 4837 8192000000 9776503088 10092490752 848487184 9.385 100 80 160 0 889006640 21838752 18680704
08:02:36 12889 8192000000 9848079856 10179891200 904950240 9.947 100 73 146 3 952879520 32169776 15759504
08:04:05 8784 8192000000 9926572976 10271850496 956372240 10.454 100 78 156 0 1044874464 72384256 16117968
08:05:18 5826 8192000000 9953040192 10317885440 992583728 10.807 100 72 145 0 1072745728 65298160 14863840
08:06:27 3639 8192000000 9998037072 10365263872 1022886768 11.100 100 71 139 1 1104574112 67221392 14465952
08:07:33 2078 8192000000 10026280592 10392481792 1054358912 11.402 100 91 182 1 1139234432 66762800 18112720
08:08:39 2074 8192000000 10043158800 10412814336 1061569776 11.472 100 88 176 0 1145555168 66816992 17168400
08:09:42 9770 8192000000 10028995824 10405437440 1064427072 11.499 100 70 140 1 1137605008 58815632 14362304
08:10:54 5504 8192000000 9992208400 10388029440 1073801920 11.588 100 46 92 1 1108008976 24186704 10020352
08:12:03 5337 8192000000 10018898720 10400198656 1080587392 11.653 100 73 148 1 1121812784 25612000 15613392
08:13:09 4180 8192000000 10020982528 10409566208 1082092192 11.667 100 48 97 1 1138711216 46954480 9664544
08:14:14 8750 8192000000 10017172688 10417446912 1087291088 11.717 100 43 85 3 1130868064 33577520 9999456
08:15:27 22366 8192000000 10011738672 10396680192 1090946624 11.752 100 67 135 2 1115186848 10001936 14238288
08:16:50 4728 8192000000 9991672640 10384011264 1076179072 11.611 99 49 97 1 1092194592 5802224 10213296
08:17:58 5154 8192000000 9996322608 10391195648 1080405888 11.651 99 34 65 3 1105539008 17090848 8042272
08:19:06 41990 8192000000 10002255008 10396798976 1045912816 11.321 97 37 71 3 1077368640 23971104 7484720
08:20:49 5158 8192000000 9951827472 10333519872 1028210032 11.151 98 47 94 0 1051251424 12563808 10477584
08:21:59 6669 8192000000 9926157680 10315837440 999917184 10.878 98 23 47 3 1042427568 36526800 5983584
08:23:06 6865 8192000000 9936388448 10318295040 1016388464 11.037 99 37 72 1 1036206304 11908928 7908912
08:24:17 49570 8192000000 9951680816 10351366144 1032457968 11.192 99 40 80 1 1062426432 19794144 10174320
08:26:11 7676 8192000000 9983744368 10377736192 1061036288 11.466 100 45 89 0 1093194880 21459536 10699056
08:27:23 2645 8192000000 10013198848 10396053504 1086101296 11.706 100 60 120 1 1118174752 20149552 11923904
08:28:34 45267 8192000000 10063362752 10473123840 1096780864 11.807 100 56 110 2 1189199024 81260544 11157616
08:30:50 22568 8192000000 10080616448 10479656960 1116417088 11.993 100 47 93 3 1177737792 51266352 10054352
08:32:33 16593 8192000000 10110016816 10517454848 1135856912 12.177 100 42 84 1 1223269632 77188208 10224512
08:33:55 5610 8192000000 10156128064 10566656000 1154686672 12.353 100 47 94 0 1261733280 96278944 10767664
08:35:05 22524 8192000000 10257749824 10668736512 1175775936 12.551 100 48 95 1 1360207968 173908832 10523200
08:36:35 11550 8192000000 10287505440 10698407936 1180897808 12.599 100 55 108 2 1400737728 207296480 12543440
08:37:52 10239 8192000000 10335862352 10746597376 1189667968 12.680 100 61 120 2 1438389680 234898256 13823456
08:39:11 4861 8192000000 10338426192 10750504960 1189753120 12.681 100 65 128 1 1442179008 238345712 14080176
08:40:19 6477 8192000000 10387606448 10801971200 1195909184 12.738 100 75 148 1 1491769072 279200080 16659808
08:41:32 6656 8192000000 10416092400 10833076224 1192998192 12.711 99 66 131 2 1518753536 311430016 14325328
08:42:48 7272 8192000000 10444009168 10870763520 1207163920 12.843 100 82 163 0 1546261216 321013904 18083392
08:44:02 6227 8192000000 10474558720 10893524992 1216943856 12.933 100 68 136 0 1576065584 342957584 16164144
08:45:13 2941 8192000000 10484974048 10893475840 1196422992 12.743 100 91 181 2 1587831760 370398512 21010256
08:46:24 1866 8192000000 10538755120 10945191936 1200175840 12.778 100 88 176 1 1636397776 415608128 20613808
08:47:35 2710 8192000000 10506682496 10914316288 1180028064 12.590 100 83 164 1 1608348800 408245360 20075376
08:48:43 7296 8192000000 10484487296 10930327552 1191411712 12.696 100 50 101 2 1587460848 383724032 12325104
08:49:57 7961 8192000000 10427974608 10867466240 1156924320 12.374 99 42 85 0 1526843712 359071360 10848032
08:51:12 23338 8192000000 10428099168 10866245632 1163108672 12.432 100 30 60 2 1516359680 345017936 8233072
08:52:37 15700 8192000000 10445973344 10929008640 1134793712 12.167 99 24 48 2 1530627888 389228096 6606080
08:54:00 27269 8192000000 10421592032 10892251136 1127374128 12.097 99 29 55 2 1536731920 401777248 7580544
08:55:33 21878 8192000000 10269323840 10815787008 1115267392 11.982 100 22 44 3 1382236288 261592864 5376032
08:56:56 67296 8192000000 10048261824 10516189184 1083375952 11.680 96 9 18 1 1147808944 62531120 1901872
08:59:04 42552 8192000000 10073367456 10496720896 1107347120 11.907 98 43 85 2 1154964544 38334928 9282496
09:00:49 52415 8192000000 10076368192 10556284928 1101469792 11.852 98 12 24 0 1181473696 77428352 2575552
09:02:42 28408 8192000000 10055946784 10525892608 1095785568 11.798 98 11 22 0 1146259216 47214032 3259616
09:04:11 39012 8192000000 10082332816 10494361600 1095166432 11.792 98 38 77 1 1147652288 44091696 8394160
09:05:51 90900 8192000000 10108854944 10571505664 1115142256 11.981 99 14 28 0 1205763296 87469792 3151248
09:08:23 93843 8192000000 10053598512 10491695104 1108238224 11.916 98 24 46 2 1158667136 45947824 4481088
09:10:58 69587 8192000000 10161136304 10618159104 1101655360 11.853 97 15 30 2 1184745184 80154560 2935264
09:13:11 149390 8192000000 10085928672 10576482304 1097789360 11.817 97 7 14 0 1145691952 46816624 1085968
09:16:41 158072 8192000000 10083301472 10535538688 1126309264 12.087 99 23 44 2 1167219632 36067904 4842464
09:20:27 98374 8192000000 10089497088 10561183744 1111229872 11.944 98 32 65 1 1173634384 55328736 7075776
09:23:07 110612 8192000000 10087423552 10531610624 1088267024 11.726 95 16 32 0 1140456496 48948640 3240832
09:25:58 131096 8192000000 10067348832 10512523264 1125058928 12.075 99 11 22 1 1184579216 57220080 2300208
09:29:09 51294 8192000000 10105657728 10584444928 1131600672 12.136 99 15 29 1 1214120640 79457744 3062224
09:31:01 79861 8192000000 10097843728 10594086912 1112350176 11.955 97 17 32 3 1211553024 95438992 3763856
09:33:21 57078 8192000000 10130978752 10562211840 1140597632 12.221 100 19 38 0 1205545696 60673744 4274320
09:35:21 59286 8192000000 10089564416 10539409408 1129211840 12.114 99 23 45 2 1185512384 51764480 4536064
09:37:21 30864 8192000000 10124346784 10561560576 1148342896 12.294 100 46 92 0 1217782176 60227728 9211552
09:38:56 39076 8192000000 10097328992 10592645120 1142342832 12.238 99 12 24 2 1191299792 46714368 2242592
09:40:40 87143 8192000000 10103219376 10583076864 1142801936 12.242 99 10 20 0 1183463728 38305392 2356400
09:43:07 83579 8192000000 10065772032 10512261120 1118883264 12.016 97 30 60 3 1177595792 52223376 6489152
09:45:35 47924 8192000000 10071119024 10514763776 1116604352 11.995 98 11 22 0 1164270608 45112784 2553472
09:47:43 66589 8192000000 10031291904 10515632128 1071699120 11.568 95 12 24 0 1132357488 58069888 2588480
09:49:57 51833 8192000000 10057479328 10514194432 1107378544 11.908 98 21 42 2 1144827392 33087072 4361776
09:51:51 37283 8192000000 10133731856 10571898880 1102707520 11.863 97 43 86 1 1204284384 91985696 9591168
09:53:46 82975 8192000000 10067234960 10583322624 1077504912 11.624 96 11 22 1 1134679520 55002384 2172224
09:56:09 112478 8192000000 10054820048 10542178304 1068376624 11.537 95 10 19 2 1112834128 42506752 1950752
09:59:03 79536 8192000000 10093682096 10521174016 1063477520 11.490 94 31 61 3 1135537088 65736576 6322992
10:01:26 152843 8192000000 10056638784 10545643520 1096806128 11.807 98 37 74 2 1157836736 52714784 8315824
10:05:10 75192 8192000000 10043689056 10527621120 1113839136 11.969 100 5 10 0 1152111264 37528384 743744
10:07:26 55818 8192000000 10078238064 10523500544 1118511856 12.013 100 23 46 0 1174054496 50760928 4781712
10:09:23 59991 8192000000 10119327104 10567237632 1117207440 12.001 99 18 36 0 1196051680 74728128 4116112
10:11:33 63246 8192000000 10126378432 10577920000 1131537056 12.136 99 20 40 0 1187924544 52005232 4382256
10:13:38 44154 8192000000 10072164896 10521350144 1111699072 11.949 98 21 40 1 1144624544 29111920 3813552
10:15:31 41297 8192000000 10076220624 10509107200 1116817584 11.997 99 25 50 2 1170982144 49020192 5144368
10:17:14 87357 8192000000 10106309696 10581028864 1139790416 12.214 100 22 44 0 1213471344 69498992 4181936
10:20:10 54489 8192000000 10051368544 10532499456 1116842176 11.997 98 14 28 0 1158420736 38017680 3560880
10:22:06 95085 8192000000 10071421888 10526572544 1122647040 12.052 99 7 14 0 1165364656 41074144 1643472
10:25:19 44890 8192000000 10039626256 10469314560 1103448128 11.870 98 10 20 0 1130710528 25404352 1858048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2020 10:28:19 to 06-11-2020 10:28:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-11-2020 10:28:35 to 06-11-2020 10:28:37.

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          214240
MON$OLDEST_ACTIVE               216129
MON$OLDEST_SNAPSHOT             216129
MON$NEXT_TRANSACTION            216129
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                       2073824
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             767
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:67: 
:68: mscs-test-161.budzdorov.ru    Fri Nov  6 10:26:56 2020
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Fri Nov  6 10:26:58 2020
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
--- end of diff output ---

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

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