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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17095348 kB
MemAvailable 31556664 kB
Buffers 0 kB
Cached 14537056 kB
SwapCached 1940 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-03 13:26:53
DTS_END                         2020-12-03 13:26:53.8970
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/46678
TRN_ID                          220869
ATT_ID                          585
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: 8839.88 22287 1060786
customer order: creation 154.48 418 18537
customer order: refuse 13.21 335 1585
order to supplier: creation 40.56 1349 4867
order to supplier: removal 3.20 132051 384
invoice (draft): creation 41.43 3390 4971
invoice (draft): removal 52.34 1004 6281
invoice accept: apply 37.97 17423 4556
invoice accept: cancel 47.10 844 5652
customer reserve: creation 3562.78 166 427533
customer reserve: removal 4611.76 73 553411
realization accept: apply 77.34 168 9281
realization accept: cancel 81.35 120 9762
payment from customer: creation 55.07 115 6608
payment from customer: removal 4.95 89 594
payment to supplier: creation 43.43 102 5212
payment to supplier: removal 2.81 93 337
service: total inventory turnovers 3.78 2919 453
service: total monetary turnovers 5.44 522 653
service: aggregate perf. data 0.88 18026 105
service: refresh index statistics 0.03 266538 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-12-2020 13:28:27 to 03-12-2020 13:28:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 571
TEST_TIME 2 1773
TEST_TIME 3 3393
TEST_TIME 4 3754
TEST_TIME 5 4336
TEST_TIME 6 4825
TEST_TIME 7 5059
TEST_TIME 8 5082
TEST_TIME 9 5269
TEST_TIME 10 5399
TEST_TIME 11 5606
TEST_TIME 12 5698
TEST_TIME 13 5933
TEST_TIME 14 6127
TEST_TIME 15 6358
TEST_TIME 16 6814
TEST_TIME 17 7277
TEST_TIME 18 7326
TEST_TIME 19 7271
TEST_TIME 20 7337
TEST_TIME 21 7841
TEST_TIME 22 8281
TEST_TIME 23 8408
TEST_TIME 24 9199
TEST_TIME 25 9610
TEST_TIME 26 10888
TEST_TIME 27 11779
TEST_TIME 28 11474
TEST_TIME 29 11108
TEST_TIME 30 10970
TEST_TIME 31 10802
TEST_TIME 32 10596
TEST_TIME 33 10410
TEST_TIME 34 10238
TEST_TIME 35 10073
TEST_TIME 36 9999
TEST_TIME 37 9909
TEST_TIME 38 10077
TEST_TIME 39 9894
TEST_TIME 40 9868
TEST_TIME 41 9724
TEST_TIME 42 9646
TEST_TIME 43 9495
TEST_TIME 44 9335
TEST_TIME 45 9460
TEST_TIME 46 9346
TEST_TIME 47 9261
TEST_TIME 48 9298
TEST_TIME 49 9307
TEST_TIME 50 9254
TEST_TIME 51 9236
TEST_TIME 52 9310
TEST_TIME 53 9273
TEST_TIME 54 9233
TEST_TIME 55 9236
TEST_TIME 56 9222
TEST_TIME 57 9315
TEST_TIME 58 9470
TEST_TIME 59 9488
TEST_TIME 60 9590
TEST_TIME 61 9502
TEST_TIME 62 9352
TEST_TIME 63 9266
TEST_TIME 64 9216
TEST_TIME 65 9117
TEST_TIME 66 9072
TEST_TIME 67 9087
TEST_TIME 68 9040
TEST_TIME 69 9015
TEST_TIME 70 9039
TEST_TIME 71 9011
TEST_TIME 72 9056
TEST_TIME 73 9102
TEST_TIME 74 9096
TEST_TIME 75 9162
TEST_TIME 76 9166
TEST_TIME 77 9130
TEST_TIME 78 9158
TEST_TIME 79 9189
TEST_TIME 80 9146
TEST_TIME 81 9070
TEST_TIME 82 8997
TEST_TIME 83 8983
TEST_TIME 84 8920
TEST_TIME 85 8900
TEST_TIME 86 8868
TEST_TIME 87 8855
TEST_TIME 88 8836
TEST_TIME 89 8823
TEST_TIME 90 8805
TEST_TIME 91 8840
TEST_TIME 92 8950
TEST_TIME 93 9001
TEST_TIME 94 8994
TEST_TIME 95 9025
TEST_TIME 96 9052
TEST_TIME 97 9033
TEST_TIME 98 9041
TEST_TIME 99 8962
TEST_TIME 100 8897
TEST_TIME 101 8881
TEST_TIME 102 8918
TEST_TIME 103 8887
TEST_TIME 104 8838
TEST_TIME 105 8881
TEST_TIME 106 8869
TEST_TIME 107 8897
TEST_TIME 108 8921
TEST_TIME 109 8883
TEST_TIME 110 8945
TEST_TIME 111 8920
TEST_TIME 112 8923
TEST_TIME 113 9007
TEST_TIME 114 8988
TEST_TIME 115 9014
TEST_TIME 116 8940
TEST_TIME 117 8891
TEST_TIME 118 8873
TEST_TIME 119 8843
TEST_TIME 120 8842

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-12-2020 13:28:30 to 03-12-2020 13:28:34.

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 1456222 1456222 0 0.00 0 24750 6 0 0
doc_list_biud 1456222 1456222 0 0.00 0 106957 34 0 0
sp_add_invoice_to_stock 4645 4556 89 1.91 32 179454 17424 0 89
sp_cancel_adding_invoice 5652 5652 0 0.00 5 59914 845 0 0
sp_cancel_client_order 1585 1585 0 0.00 4 9577 335 0 0
sp_cancel_customer_reserve 553411 553411 0 0.00 1 106965 73 0 0
sp_cancel_pay_from_customer 594 594 0 0.00 3 7279 90 0 0
sp_cancel_pay_to_supplier 337 337 0 0.00 3 5778 94 0 0
sp_cancel_supplier_invoice 6287 6281 6 0.09 2 76492 1005 0 6
sp_cancel_supplier_order 384 384 0 0.00 9 1186938 132052 0 0
sp_cancel_write_off 9762 9762 0 0.00 1 24751 121 0 0
sp_client_order 18537 18537 0 0.00 4 31901 419 0 0
sp_customer_reserve 427533 427533 0 0.00 0 36673 166 0 0
sp_fill_shopping_cart 28363 28363 0 0.00 1 14015 48 0 0
sp_fill_shopping_cart_clo_res 427531 427531 0 0.00 0 30765 18 0 0
sp_get_clo_for_invoice 4554 4554 0 0.00 0 61719 623 0 0
sp_kill_cost_storno 16345 16345 0 0.00 0 24748 59 0 0
sp_kill_qstorno_ret_qs2qd 1123249 1123249 0 0.00 0 106953 39 0 0
sp_kill_qty_storno 590910 590910 0 0.00 0 106957 84 0 0
sp_lock_selected_doc 19083 19083 0 0.00 0 9405 6 0 0
sp_make_cost_storno 25655 25655 0 0.00 0 18633 52 0 0
sp_multiply_rows_for_pdistr 25655 25655 0 0.00 0 15236 31 0 0
sp_multiply_rows_for_qdistr 18537 18537 0 0.00 0 19605 232 0 0
sp_pay_from_customer 6608 6608 0 0.00 4 18466 115 0 0
sp_pay_to_supplier 5212 5212 0 0.00 5 12497 102 0 0
sp_payment_common 11820 11820 0 0.00 1 7228 18 0 0
sp_qd_handle_on_cancel_clo 1585 1585 0 0.00 0 8296 305 0 0
sp_qd_handle_on_invoice_upd_sts 10206 10206 0 0.00 0 31226 365 0 0
sp_qd_handle_on_reserve_upd_sts 9281 9281 0 0.00 0 9750 50 0 0
sp_reserve_write_off 9281 9281 0 0.00 7 24897 168 0 0
sp_supplier_invoice 4971 4971 0 0.00 7 103797 3391 0 0
sp_supplier_order 4867 4867 0 0.00 5 32852 1350 0 0
srv_aggregate_perf_data 105 105 0 0.00 2637 307782 18026 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1819 125143 49380 0 0
srv_find_qd_qs_mism 1017197 1017197 0 0.00 0 12348 5 0 0
srv_make_invnt_saldo 453 453 0 0.00 37 92491 2920 0 0
srv_make_money_saldo 653 653 0 0.00 1 14322 522 0 0
srv_recalc_idx_stat 4 4 0 0.00 146420 478672 266539 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4556 4556 0 0.00 0 8565 134 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 2014 79 0 0
t$perf-norm:sp_cancel_client_order 1585 1585 0 0.00 0 2200 12 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 606 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 594 594 0 0.00 0 521 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 337 337 0 0.00 0 560 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 3163 23 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 14376 1347 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 1601 6 0 0
t$perf-norm:sp_client_order 18537 18537 0 0.00 0 4720 14 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 1838 6 0 0
t$perf-norm:sp_pay_from_customer 6608 6608 0 0.00 0 1719 6 0 0
t$perf-norm:sp_pay_to_supplier 5212 5212 0 0.00 0 6751 9 0 0
t$perf-norm:sp_reserve_write_off 9281 9281 0 0.00 0 2681 6 0 0
t$perf-norm:sp_supplier_invoice 4971 4971 0 0.00 0 5241 17 0 0
t$perf-norm:sp_supplier_order 4867 4867 0 0.00 0 4417 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 217 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 337 13 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 2553 11 0 0
t$perf-norm:srv_make_money_saldo 653 653 0 0.00 0 330 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 9286 9108 178 1.91 0 11301 17 0 178
v_all_customers 34490 34490 0 0.00 0 16 1 0 0
v_all_suppliers 27530 27530 0 0.00 0 20 1 0 0
v_all_wares 37074 37074 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 58 5 0 0
v_cancel_client_order 3170 3170 0 0.00 0 3459 7 0 0
v_cancel_customer_prepayment 1188 1188 0 0.00 0 3710 11 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 1256 5 0 0
v_cancel_payment_to_supplier 674 674 0 0.00 0 921 6 0 0
v_cancel_supplier_invoice 756 744 12 1.58 0 1904 14 0 12
v_cancel_supplier_order 768 768 0 0.00 0 339 3 0 0
v_cancel_write_off 1892 1892 0 0.00 0 4467 14 0 0
v_max_id_clo_ord 4867 4867 0 0.00 0 246 1 0 0
v_max_id_clo_res 919 919 0 0.00 0 4994 24 0 0
v_max_id_ord_sup 4959 4959 0 0.00 0 9751 10 0 0
v_max_non_paid_invoice 1273 1273 0 0.00 0 98 2 0 0
v_max_non_paid_realizn 3259 3259 0 0.00 0 2528 3 0 0
v_min_id_clo_ord 4867 4867 0 0.00 0 6517 5 0 0
v_min_id_clo_res 919 919 0 0.00 0 22673 27 0 0
v_min_id_ord_sup 4959 4959 0 0.00 0 3942 7 0 0
v_min_non_paid_invoice 5212 5212 0 0.00 0 7010 7 0 0
v_min_non_paid_realizn 6608 6608 0 0.00 0 7223 17 0 0
v_our_firm 9282 9282 0 0.00 0 158 1 0 0
v_reserve_write_off 18562 18562 0 0.00 0 3927 5 0 0
x_lock_depdocs_on_canc_invoice 11561 11561 0 0.00 0 16867 119 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 3395 321 0 0
x_make_qstorno_1000_1200 4867 4867 0 0.00 2 32806 1284 0 0
x_make_qstorno_1200_2000 4971 4971 0 0.00 4 103772 3183 0 0
x_make_qstorno_2100_3300 427533 427533 0 0.00 0 36669 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-12-2020 13:28:35 to 03-12-2020 13:28:39.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:57 9 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:59 32027 8192000000 9731543248 10028150784 809358480 8.991 100 86 172 0 837878416 7685680 20834256
10:59:39 14887 8192000000 9773999824 10078355456 846485648 9.365 100 95 191 0 882541568 13767856 22288064
11:01:02 8723 8192000000 9794882320 10109923328 863486688 9.535 100 84 168 0 905188288 22407152 19294448
11:02:15 12948 8192000000 9860181152 10189074432 915520528 10.052 100 78 156 0 968965712 36377280 17067904
11:03:30 11684 8192000000 9917047632 10260729856 955692528 10.447 100 76 153 3 1027983712 56802128 15489056
11:04:45 5325 8192000000 9970116960 10324262912 990883680 10.790 100 84 165 3 1087611456 80267072 16460704
11:05:51 8467 8192000000 9997454752 10361942016 1018648848 11.059 100 80 158 2 1108858704 74448208 15761648
11:07:05 2532 8192000000 10010943632 10376654848 1042052672 11.284 100 92 182 1 1125525936 64563248 18910016
11:08:10 1647 8192000000 10053679520 10424946688 1065240944 11.507 100 88 180 0 1168255728 85176720 17838064
11:09:16 19441 8192000000 10037124336 10419527680 1069675504 11.549 100 68 138 2 1151996640 67127296 15193840
11:10:36 5100 8192000000 9984353728 10388209664 1080170800 11.649 100 34 68 2 1113489472 26261232 7057440
11:11:45 67435 8192000000 10010571152 10414534656 1087289328 11.717 100 44 86 2 1110507936 13072496 10146112
11:13:57 4003 8192000000 10000839984 10406592512 1093927744 11.780 100 49 100 2 1135257408 31203680 10125984
11:15:05 7581 8192000000 10026283792 10429206528 1096921792 11.808 100 53 107 1 1132452224 23403504 12126928
11:16:13 7586 8192000000 10005581440 10414358528 1092753376 11.769 100 29 58 2 1125105456 25588720 6763360
11:17:23 16665 8192000000 10016467328 10408247296 1080874144 11.656 99 66 134 3 1117614464 22488352 14251968
11:18:43 3333 8192000000 9971992208 10351464448 1040376944 11.268 98 46 92 2 1079368768 29728816 9263008
11:19:47 7715 8192000000 9966068016 10360037376 1025881280 11.129 97 44 85 5 1046088400 11162960 9044160
11:20:55 4984 8192000000 9951387888 10333040640 1025650368 11.127 99 50 96 8 1051467920 14542688 11274864
11:22:04 39649 8192000000 9930498608 10333102080 985265792 10.735 95 24 48 1 1001837552 11225312 5346448
11:23:45 2349 8192000000 9955658256 10332233728 1027856832 11.148 99 60 123 4 1050544112 9227888 13459392
11:24:49 7840 8192000000 9967329664 10360770560 1045293664 11.316 99 47 94 2 1072733888 16744064 10696160
11:25:57 5636 8192000000 9980611696 10377527296 1062363440 11.479 100 35 68 2 1088778672 18934304 7480928
11:27:07 72366 8192000000 10015612144 10409439232 1090530704 11.748 100 39 77 2 1113836128 15574240 7731184
11:29:22 11903 8192000000 10044725264 10455584768 1103315072 11.869 100 32 63 1 1154381200 44389792 6676336
11:30:40 13635 8192000000 10098602784 10501971968 1113935200 11.970 100 46 91 1 1189460656 66451488 9073968
11:31:58 10737 8192000000 10107939792 10516156416 1137142400 12.189 100 39 78 1 1220078992 73403104 9533488
11:33:14 9118 8192000000 10157450256 10563018752 1149793184 12.308 100 56 112 1 1260831072 99327456 11710432
11:34:32 14619 8192000000 10198645680 10615947264 1156774960 12.373 100 61 120 3 1302268480 132587248 12906272
11:35:51 3740 8192000000 10236372592 10644496384 1160680560 12.410 99 66 131 1 1326261408 150781936 14798912
11:36:58 1817 8192000000 10297309856 10706436096 1173470432 12.529 100 74 149 0 1404742000 214458848 16812720
11:38:06 8635 8192000000 10318467136 10737352704 1186060560 12.647 100 53 104 2 1435286480 236986944 12238976
11:39:19 3155 8192000000 10373907872 10799800320 1186603744 12.652 100 78 155 3 1478923280 274495168 17824368
11:40:29 2836 8192000000 10413158128 10828845056 1186449424 12.650 100 72 144 0 1513368032 309852016 17066592
11:41:35 4652 8192000000 10459292352 10871689216 1189609152 12.680 99 68 134 0 1567259312 361641872 16008288
11:42:42 2243 8192000000 10468930480 10916057088 1204361792 12.817 100 80 158 0 1563484880 340270000 18853088
11:43:48 6482 8192000000 10498007488 10956541952 1189087312 12.675 100 45 87 1 1594702160 394380400 11234448
11:44:58 19393 8192000000 10521511232 10980888576 1179222640 12.583 100 44 88 2 1618658800 427602704 11833456
11:46:34 14132 8192000000 10545144880 10973290496 1149457648 12.304 98 39 78 1 1627473344 467679056 10336640
11:47:57 12492 8192000000 10526936128 10980003840 1165564048 12.455 99 48 96 1 1609197712 431400928 12232736
11:49:39 11016 8192000000 10528026944 10968350720 1155255600 12.359 100 42 84 1 1608648768 442234112 11159056
11:51:09 16202 8192000000 10424850160 10927276032 1135939920 12.177 100 27 54 0 1540869776 397134304 7795552
11:52:26 33602 8192000000 10227105344 10795655168 1117393456 12.002 99 20 40 0 1440689040 317765584 5530000
11:55:13 29002 8192000000 10058289296 10489126912 1121537200 12.042 100 40 80 1 1164737616 34442080 8758336
11:56:46 38603 8192000000 10039918032 10506088448 1116750768 11.996 99 14 28 3 1146446944 27113872 2582304
11:58:27 47403 8192000000 10050379136 10490617856 1102866048 11.865 98 23 45 0 1137293632 30012800 4414784
12:00:15 82003 8192000000 10116979440 10569117696 1094552880 11.786 97 21 42 0 1199968432 100667312 4748240
12:02:38 89737 8192000000 10134605744 10615308288 1112571712 11.957 99 23 47 0 1254228704 136264752 5392240
12:05:17 113844 8192000000 10024176048 10477027328 1084692960 11.692 97 11 21 0 1130811888 43698640 2420288
12:08:11 35359 8192000000 10082647824 10537771008 1114950592 11.979 100 53 104 5 1181993744 55191696 11851456
12:09:48 45486 8192000000 10081498176 10526978048 1119297024 12.020 100 12 24 2 1192007520 69980128 2730368
12:11:33 60963 8192000000 10035772704 10499563520 1116151136 11.991 99 5 10 0 1155066224 38166688 748400
12:13:35 53611 8192000000 10088633920 10546343936 1127088592 12.094 100 20 40 0 1184306208 52472688 4744928
12:15:29 76908 8192000000 10068691248 10518290432 1066099632 11.515 94 13 26 1 1109526720 40306064 3121024
12:17:48 87648 8192000000 10073624688 10530365440 1109286784 11.926 99 13 26 1 1139544272 27840128 2417360
12:20:17 36657 8192000000 10085567296 10520993792 1128676032 12.109 100 31 61 3 1182775376 47600784 6498560
12:21:56 63419 8192000000 10136952208 10590679040 1137724688 12.194 100 20 40 1 1221189040 79089392 4374960
12:24:03 53242 8192000000 10111873312 10646319104 1134190592 12.161 99 12 24 0 1241594624 104965488 2438544
12:25:59 115862 8192000000 10103138320 10663608320 1150002928 12.310 100 14 28 0 1210979584 58408944 2567712
12:29:22 100410 8192000000 10076321440 10540281856 1120694048 12.034 97 13 26 0 1159309216 36125776 2489392
12:32:20 68304 8192000000 10081213936 10586308608 1155255968 12.359 100 11 22 1 1194581408 37133104 2192336
12:34:30 71675 8192000000 10106198800 10564857856 1104902944 11.884 95 21 42 1 1167586336 58143232 4540160
12:36:49 90257 8192000000 10183763568 10624737280 1144945136 12.262 99 31 62 2 1232016608 80474432 6597040
12:39:45 76950 8192000000 10128721792 10593980416 1132451776 12.144 98 18 36 2 1221450192 84920704 4077712
12:42:07 76198 8192000000 10094152880 10539040768 1076775504 11.617 94 15 31 0 1115264496 35384672 3104320
12:44:25 53790 8192000000 10075545200 10565275648 1087598496 11.720 96 30 60 3 1166723696 72727280 6397920
12:46:28 124796 8192000000 10049166448 10488340480 1092994640 11.771 97 27 54 1 1134606624 35718976 5893008
12:50:12 42459 8192000000 10050258432 10490683392 1101608768 11.853 99 9 18 1 1153619936 50184368 1826800
12:51:56 39531 8192000000 10024818400 10469965824 1082988576 11.676 97 9 18 0 1104415440 19431536 1995328
12:53:43 57961 8192000000 10084475392 10533310464 1080462144 11.652 96 27 53 3 1151441360 65203184 5776032
12:55:44 57935 8192000000 10136330976 10570985472 1109661888 11.929 99 22 44 1 1219608768 105290000 4656880
12:57:42 77699 8192000000 10108944272 10642440192 1084210352 11.688 95 20 40 0 1178830816 90021376 4599088
13:00:10 37338 8192000000 10088011824 10522198016 1129250144 12.114 100 27 54 5 1170828000 36482528 5095328
13:01:49 57834 8192000000 10069877360 10524971008 1097948416 11.818 97 26 52 1 1139802144 36768800 5084928
13:04:00 125143 8192000000 10102842288 10557894656 1104442416 11.880 97 17 34 0 1158060704 50286304 3331984
13:07:29 68761 8192000000 10116196688 10599141376 1139895920 12.215 100 10 20 0 1227193264 85061488 2235856
13:09:38 53515 8192000000 10127734608 10575712256 1129338176 12.115 99 12 24 0 1195406240 63671504 2396560
13:11:33 100102 8192000000 10119216512 10583572480 1127620080 12.099 98 21 42 3 1190128144 57560928 4947136
13:14:38 55694 8192000000 10126196816 10570121216 1146875024 12.280 99 18 37 1 1197774624 46921808 3977792
13:16:42 45534 8192000000 10110495136 10552688640 1128823520 12.110 98 18 36 0 1194300368 61538016 3938832
13:18:58 35210 8192000000 10128469648 10615193600 1149333120 12.303 100 39 78 0 1216970752 59756496 7881136
13:20:41 30131 8192000000 10136864944 10592153600 1138276192 12.199 99 34 66 1 1189181744 43647136 7258416
13:22:13 112942 8192000000 10084324752 10549186560 1150840192 12.317 100 24 48 1 1198233024 41619168 5773664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-12-2020 13:28:41 to 03-12-2020 13:28:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-12-2020 13:28:56 to 03-12-2020 13:28:58.

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          219725
MON$OLDEST_ACTIVE               220976
MON$OLDEST_SNAPSHOT             220976
MON$NEXT_TRANSACTION            220976
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                       2079328
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             766
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2287 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.2287 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Thu Dec  3 13:26:59 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:05 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:07 2020
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:91: 
:92: 
:93: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:07 2020
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:07 2020
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:07 2020
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:07 2020
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Thu Dec  3 13:27:07 2020
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
--- end of diff output ---

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

03.12.20 13:28:59. 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-03 13:29:01 No severe PSQL-related problems occured
03.12.20 13:29:03. End of report.