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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-12 10:26:57
DTS_END                         2021-02-12 10:26:57.2030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46248
TRN_ID                          234492
ATT_ID                          691
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: 9449.73 25119 1133967
customer order: creation 165.20 400 19824
customer order: refuse 13.55 302 1626
order to supplier: creation 44.20 1248 5304
order to supplier: removal 3.42 139478 410
invoice (draft): creation 44.48 3427 5337
invoice (draft): removal 54.40 958 6528
invoice accept: apply 41.18 17120 4941
invoice accept: cancel 49.72 883 5966
customer reserve: creation 3866.38 165 463966
customer reserve: removal 4865.08 78 583810
realization accept: apply 80.39 173 9647
realization accept: cancel 96.09 120 11531
payment from customer: creation 58.48 123 7017
payment from customer: removal 5.44 86 653
payment to supplier: creation 47.63 122 5716
payment to supplier: removal 3.24 113 389
service: total inventory turnovers 4.06 3049 487
service: total monetary turnovers 5.88 572 705
service: aggregate perf. data 0.88 17825 106
service: refresh index statistics 0.03 316130 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-02-2021 10:29:35 to 12-02-2021 10:29:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6437
TEST_TIME 2 4034
TEST_TIME 3 4231
TEST_TIME 4 4178
TEST_TIME 5 4445
TEST_TIME 6 4637
TEST_TIME 7 4874
TEST_TIME 8 4772
TEST_TIME 9 5039
TEST_TIME 10 5538
TEST_TIME 11 5641
TEST_TIME 12 5806
TEST_TIME 13 5757
TEST_TIME 14 5653
TEST_TIME 15 5839
TEST_TIME 16 6026
TEST_TIME 17 6105
TEST_TIME 18 6291
TEST_TIME 19 6500
TEST_TIME 20 7028
TEST_TIME 21 7315
TEST_TIME 22 7327
TEST_TIME 23 7745
TEST_TIME 24 7630
TEST_TIME 25 7996
TEST_TIME 26 8598
TEST_TIME 27 8923
TEST_TIME 28 8932
TEST_TIME 29 9451
TEST_TIME 30 11271
TEST_TIME 31 11320
TEST_TIME 32 11301
TEST_TIME 33 11240
TEST_TIME 34 11291
TEST_TIME 35 11212
TEST_TIME 36 11163
TEST_TIME 37 10984
TEST_TIME 38 10745
TEST_TIME 39 10580
TEST_TIME 40 10459
TEST_TIME 41 10422
TEST_TIME 42 10375
TEST_TIME 43 10294
TEST_TIME 44 10424
TEST_TIME 45 10330
TEST_TIME 46 10272
TEST_TIME 47 10215
TEST_TIME 48 10212
TEST_TIME 49 10116
TEST_TIME 50 10089
TEST_TIME 51 10100
TEST_TIME 52 10116
TEST_TIME 53 10136
TEST_TIME 54 10108
TEST_TIME 55 10130
TEST_TIME 56 10178
TEST_TIME 57 10166
TEST_TIME 58 10242
TEST_TIME 59 10221
TEST_TIME 60 10307
TEST_TIME 61 10172
TEST_TIME 62 10098
TEST_TIME 63 10057
TEST_TIME 64 9994
TEST_TIME 65 9905
TEST_TIME 66 9866
TEST_TIME 67 9873
TEST_TIME 68 9879
TEST_TIME 69 9829
TEST_TIME 70 9820
TEST_TIME 71 9878
TEST_TIME 72 9831
TEST_TIME 73 9882
TEST_TIME 74 9953
TEST_TIME 75 9918
TEST_TIME 76 10008
TEST_TIME 77 9989
TEST_TIME 78 10008
TEST_TIME 79 10019
TEST_TIME 80 10034
TEST_TIME 81 9960
TEST_TIME 82 9907
TEST_TIME 83 9833
TEST_TIME 84 9763
TEST_TIME 85 9696
TEST_TIME 86 9701
TEST_TIME 87 9692
TEST_TIME 88 9664
TEST_TIME 89 9624
TEST_TIME 90 9619
TEST_TIME 91 9581
TEST_TIME 92 9598
TEST_TIME 93 9613
TEST_TIME 94 9620
TEST_TIME 95 9734
TEST_TIME 96 9795
TEST_TIME 97 9820
TEST_TIME 98 9852
TEST_TIME 99 9796
TEST_TIME 100 9704
TEST_TIME 101 9658
TEST_TIME 102 9602
TEST_TIME 103 9566
TEST_TIME 104 9496
TEST_TIME 105 9488
TEST_TIME 106 9533
TEST_TIME 107 9539
TEST_TIME 108 9517
TEST_TIME 109 9509
TEST_TIME 110 9517
TEST_TIME 111 9512
TEST_TIME 112 9535
TEST_TIME 113 9536
TEST_TIME 114 9503
TEST_TIME 115 9530
TEST_TIME 116 9529
TEST_TIME 117 9566
TEST_TIME 118 9529
TEST_TIME 119 9506
TEST_TIME 120 9452

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-02-2021 10:29:39 to 12-02-2021 10:29:42.

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 1562504 1562504 0 0.00 0 19071 6 0 0
doc_list_biud 1562500 1562500 0 0.00 0 40000 36 0 0
sp_add_invoice_to_stock 5031 4941 90 1.78 28 206719 17120 0 90
sp_cancel_adding_invoice 5966 5966 0 0.00 5 115633 883 0 0
sp_cancel_client_order 1626 1626 0 0.00 4 9502 302 0 0
sp_cancel_customer_reserve 583810 583810 0 0.00 1 40001 79 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 8402 87 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 6164 113 0 0
sp_cancel_supplier_invoice 6536 6528 8 0.12 2 24246 959 0 8
sp_cancel_supplier_order 410 410 0 0.00 7 1241442 139479 0 0
sp_cancel_write_off 11531 11531 0 0.00 1 7548 120 0 0
sp_client_order 19824 19824 0 0.00 4 18721 401 0 0
sp_customer_reserve 463966 463966 0 0.00 0 59919 165 0 0
sp_fill_shopping_cart 30458 30458 0 0.00 1 16164 39 0 0
sp_fill_shopping_cart_clo_res 463955 463955 0 0.00 0 29022 16 0 0
sp_get_clo_for_invoice 4930 4930 0 0.00 0 51742 407 0 0
sp_kill_cost_storno 18539 18539 0 0.00 0 6863 62 0 0
sp_kill_qstorno_ret_qs2qd 1186089 1186089 0 0.00 0 39996 42 0 0
sp_kill_qty_storno 624447 624447 0 0.00 0 40000 88 0 0
sp_lock_selected_doc 20098 20098 0 0.00 0 13372 7 0 0
sp_make_cost_storno 27310 27310 0 0.00 0 13795 58 0 0
sp_multiply_rows_for_pdistr 27308 27308 0 0.00 0 6385 31 0 0
sp_multiply_rows_for_qdistr 19822 19822 0 0.00 0 17803 217 0 0
sp_pay_from_customer 7017 7017 0 0.00 4 18055 123 0 0
sp_pay_to_supplier 5716 5716 0 0.00 5 14838 123 0 0
sp_payment_common 12732 12732 0 0.00 1 6826 18 0 0
sp_qd_handle_on_cancel_clo 1626 1626 0 0.00 0 9440 261 0 0
sp_qd_handle_on_invoice_upd_sts 10896 10896 0 0.00 0 20686 380 0 0
sp_qd_handle_on_reserve_upd_sts 9646 9646 0 0.00 0 10487 52 0 0
sp_reserve_write_off 9647 9647 0 0.00 7 14643 173 0 0
sp_supplier_invoice 5337 5337 0 0.00 7 87690 3427 0 0
sp_supplier_order 5304 5304 0 0.00 5 22114 1248 0 0
srv_aggregate_perf_data 106 106 0 0.00 3228 226182 17826 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1931 118583 39880 0 0
srv_find_qd_qs_mism 1086592 1086592 0 0.00 0 19070 5 0 0
srv_make_invnt_saldo 487 487 0 0.00 39 110149 3049 0 0
srv_make_money_saldo 705 705 0 0.00 1 19207 572 0 0
srv_recalc_idx_stat 4 4 0 0.00 191570 502141 316131 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4941 4941 0 0.00 0 15924 153 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 2111 80 0 0
t$perf-norm:sp_cancel_client_order 1626 1626 0 0.00 0 4250 14 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1668 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 1284 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 303 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 448 8 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 33961 1793 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 671 4 0 0
t$perf-norm:sp_client_order 19824 19824 0 0.00 0 12067 17 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 2296 7 0 0
t$perf-norm:sp_pay_from_customer 7017 7017 0 0.00 0 12445 9 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 12841 11 0 0
t$perf-norm:sp_reserve_write_off 9647 9647 0 0.00 0 6216 8 0 0
t$perf-norm:sp_supplier_invoice 5337 5337 0 0.00 0 6543 17 0 0
t$perf-norm:sp_supplier_order 5304 5304 0 0.00 0 3176 12 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 95 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 171 4 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 742 6 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 2473 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 81 28 0 0
v_add_invoice_to_stock 10036 9856 180 1.79 0 4187 15 0 180
v_all_customers 36944 36944 0 0.00 0 60 1 0 0
v_all_suppliers 29912 29912 0 0.00 0 17 1 0 0
v_all_wares 39644 39644 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1253 8 0 0
v_cancel_client_order 3252 3252 0 0.00 0 1746 4 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 2744 11 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 1839 5 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 2129 10 0 0
v_cancel_supplier_invoice 844 828 16 1.89 0 1237 14 0 16
v_cancel_supplier_order 820 820 0 0.00 0 831 5 0 0
v_cancel_write_off 1936 1936 0 0.00 0 4265 17 0 0
v_max_id_clo_ord 5303 5303 0 0.00 0 911 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 4427 19 0 0
v_max_id_ord_sup 5333 5333 0 0.00 0 8283 9 0 0
v_max_non_paid_invoice 1395 1395 0 0.00 0 76 2 0 0
v_max_non_paid_realizn 3475 3475 0 0.00 0 3594 4 0 0
v_min_id_clo_ord 5303 5303 0 0.00 0 1500 4 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 5371 16 0 0
v_min_id_ord_sup 5333 5333 0 0.00 0 6934 7 0 0
v_min_non_paid_invoice 5715 5715 0 0.00 0 4067 8 0 0
v_min_non_paid_realizn 7017 7017 0 0.00 0 6096 14 0 0
v_our_firm 9784 9784 0 0.00 0 186 1 0 0
v_reserve_write_off 19292 19292 0 0.00 0 4674 5 0 0
x_lock_depdocs_on_canc_invoice 12080 12080 0 0.00 0 25061 114 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 6815 343 0 0
x_make_qstorno_1000_1200 5304 5304 0 0.00 2 22089 1181 0 0
x_make_qstorno_1200_2000 5337 5337 0 0.00 3 87685 3276 0 0
x_make_qstorno_2100_3300 463966 463966 0 0.00 0 59915 147 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-02-2021 10:29:43 to 12-02-2021 10:29:48.

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:57:00 15 8192000000 8802964960 8815763456 5768576 0.070 3 1 2 0 5869744 65392 35776
07:58:01 35198 8192000000 9730867792 10027413504 805061744 8.948 100 90 180 0 834768768 7714672 21992352
07:59:49 25713 8192000000 9767559568 10073759744 839453200 9.294 100 86 173 0 874985536 14854000 20678336
08:01:21 19565 8192000000 9832665776 10151796736 876572384 9.666 100 84 168 1 927865920 31730896 19562640
08:02:47 15495 8192000000 9872885872 10206842880 928040752 10.175 100 84 168 1 980338384 33597200 18700432
08:04:04 8081 8192000000 9932157328 10274148352 963112256 10.519 100 85 170 1 1043193648 62426400 17654992
08:05:15 6492 8192000000 9988647904 10343026688 994656176 10.827 100 86 173 0 1100908128 88988576 17263376
08:06:24 2721 8192000000 9982715248 10346143744 1024988192 11.120 100 80 158 0 1100160624 59084928 16087504
08:07:28 974 8192000000 10014832272 10381238272 1047236288 11.334 100 91 181 0 1129300432 63739296 18324848
08:08:30 24955 8192000000 9994409344 10370981888 1060819744 11.464 100 48 96 0 1114912864 43778208 10314912
08:09:56 7334 8192000000 10005373600 10394537984 1075805760 11.607 100 51 102 2 1107532544 20657232 11069552
08:11:05 7015 8192000000 9992504736 10386960384 1078080144 11.629 100 42 82 0 1111346496 24029632 9236720
08:12:16 8359 8192000000 9997123328 10398126080 1089917728 11.742 100 35 70 2 1116181088 19349712 6913648
08:13:25 18661 8192000000 10006910848 10391351296 1089098304 11.734 100 70 141 1 1115706240 11806352 14801584
08:14:44 5409 8192000000 10020075216 10425102336 1089608560 11.739 100 44 87 0 1142518640 43728656 9181424
08:15:49 6061 8192000000 10009454416 10409201664 1096874832 11.808 100 54 107 0 1120677632 12348048 11454752
08:17:00 13530 8192000000 9978509168 10375802880 1003843216 10.916 93 27 56 2 1031227664 21655168 5729280
08:18:16 28715 8192000000 9954767872 10357522432 996429664 10.844 94 22 45 2 1028090496 27156128 4504704
08:19:45 9149 8192000000 9922532528 10306854912 955202368 10.442 92 30 61 1 968920208 6128272 7589568
08:20:54 5191 8192000000 9931221632 10319032320 1006739056 10.944 98 37 72 0 1038293520 23746304 7808160
08:22:02 6123 8192000000 9933075056 10313273344 980392848 10.688 95 41 83 0 1010106608 20994608 8719152
08:23:08 39879 8192000000 9944710800 10351505408 1023436352 11.105 99 24 48 1 1055005456 26747232 4821872
08:24:48 5355 8192000000 9967872944 10357215232 1045522624 11.318 100 48 95 2 1074124016 18291792 10309600
08:25:54 14371 8192000000 9971121840 10381148160 1056368016 11.422 100 26 52 1 1081113328 18637600 6107712
08:27:12 3984 8192000000 10011424992 10396569600 1081459584 11.661 100 68 136 1 1113827024 16883984 15483456
08:28:18 75679 8192000000 10041596624 10451116032 1098094384 11.820 100 41 81 2 1138354160 31673648 8586128
08:30:40 48771 8192000000 10077364464 10496327680 1116099232 11.990 100 36 70 2 1187222368 63338048 7785088
08:32:38 14294 8192000000 10128193248 10533384192 1139804096 12.214 100 59 116 2 1235829152 83611360 12413696
08:33:57 11066 8192000000 10142521680 10551730176 1151602752 12.325 100 44 87 0 1248086944 86135296 10348896
08:35:18 17584 8192000000 10217472928 10632241152 1159594208 12.399 100 52 104 0 1336954512 165062304 12298000
08:36:40 3255 8192000000 10230419856 10632826880 1152249952 12.331 99 81 159 0 1328045136 157861840 17933344
08:37:46 5464 8192000000 10266114560 10675830784 1166209968 12.461 100 64 128 1 1372640096 192160624 14269504
08:38:56 7995 8192000000 10258827904 10676695040 1165074352 12.451 99 46 90 2 1350660528 174748208 10837968
08:40:13 11337 8192000000 10319052320 10728308736 1186473872 12.651 100 66 132 1 1418292160 216722256 15096032
08:41:29 4130 8192000000 10358509152 10766733312 1182113952 12.610 100 69 138 0 1456861552 258634000 16113600
08:42:38 3508 8192000000 10379624304 10784280576 1182225136 12.611 100 80 160 1 1480307120 280214032 17867952
08:43:45 3666 8192000000 10430681056 10839576576 1170507712 12.502 98 73 144 1 1524363152 336735376 17120064
08:44:57 2916 8192000000 10469854256 10876907520 1186841040 12.654 100 76 153 2 1560926288 355673168 18412080
08:46:05 1929 8192000000 10506674512 10922778624 1166237696 12.462 100 86 171 0 1598156816 410935296 20983824
08:47:10 6510 8192000000 10458516864 10895630336 1166443008 12.464 99 58 114 0 1563439984 381897280 15099696
08:48:23 9645 8192000000 10521912832 10946457600 1167738096 12.476 100 48 93 1 1623811856 443549904 12523856
08:49:39 7662 8192000000 10519807200 10951897088 1179605056 12.587 100 48 96 1 1625302736 433262432 12435248
08:50:50 7933 8192000000 10609637616 11032809472 1176435152 12.557 100 54 107 0 1700405216 509385008 14585056
08:51:59 6868 8192000000 10587776736 11042054144 1178684816 12.578 100 36 72 0 1686711248 497973408 10053024
08:53:20 14707 8192000000 10559901984 11013476352 1150852976 12.318 100 28 56 0 1649737136 490832544 8051616
08:54:56 19433 8192000000 10501270320 11016474624 1127779648 12.100 99 29 58 0 1603433744 467835408 7818688
08:56:29 37448 8192000000 10075737168 10553634816 1097823568 11.817 97 15 28 2 1209608768 108367600 3417600
08:58:07 23496 8192000000 10079094976 10490744832 1100685136 11.844 99 44 87 3 1160731840 50191456 9855248
08:59:32 33529 8192000000 10037133712 10468200448 1055974576 11.418 95 15 30 2 1096257152 37571632 2710944
09:01:09 35500 8192000000 10050749184 10500132864 1108522336 11.918 100 9 17 2 1135745472 24969840 2253296
09:02:57 116779 8192000000 10063935632 10488086528 1103778784 11.873 99 32 64 1 1148986896 38986656 6221456
09:06:01 68713 8192000000 10105779984 10536751104 1126945696 12.093 100 39 79 0 1187945664 52945440 8054528
09:08:11 43936 8192000000 10124441200 10579820544 1138482768 12.201 100 21 42 0 1215385936 72186464 4716704
09:10:08 37821 8192000000 10095614304 10572541952 1117500816 12.003 98 29 58 0 1182787424 59459696 5826912
09:11:47 55119 8192000000 10093580448 10554359808 1130237664 12.124 99 20 39 0 1195808896 61603696 3967536
09:13:43 40291 8192000000 10108925664 10571026432 1140003008 12.216 100 18 36 0 1199142464 54962448 4177008
09:16:04 40846 8192000000 10091006288 10542342144 1135531072 12.173 99 11 20 0 1196049200 58158576 2359552
09:17:46 40835 8192000000 10073527328 10521296896 1126634880 12.090 98 25 48 1 1158186064 26470768 5080416
09:19:35 25434 8192000000 10088424544 10539315200 1135735344 12.175 99 23 46 1 1159875872 19571888 4568640
09:21:01 25966 8192000000 10118455920 10579857408 1142437680 12.238 100 41 82 2 1211783488 61235072 8110736
09:22:28 69389 8192000000 10145714048 10605465600 1145667968 12.269 100 26 52 0 1218016304 66618336 5730000
09:24:48 81843 8192000000 10101692240 10588266496 1139565200 12.211 99 10 20 1 1188506256 46960240 1980816
09:27:56 76592 8192000000 10088771360 10604515328 1134358496 12.162 100 8 16 1 1203354192 67410976 1584720
09:30:13 49035 8192000000 10041903472 10518204416 1101786304 11.855 98 8 16 0 1128527216 25412848 1328064
09:32:02 50331 8192000000 10075883840 10559012864 1113775472 11.968 99 14 28 0 1190421840 73362704 3283664
09:34:03 53396 8192000000 10033345584 10500300800 1077053232 11.619 96 15 30 0 1126315856 46211792 3050832
09:35:57 80207 8192000000 10113712272 10558464000 1097916368 11.818 96 33 66 1 1167573136 62176016 7480752
09:38:18 34494 8192000000 10149241792 10579574784 1119927360 12.026 98 44 88 0 1203638368 74393488 9317520
09:39:53 61292 8192000000 10058216624 10494668800 1087080864 11.715 96 12 24 1 1122722096 33281920 2359312
09:41:55 25110 8192000000 10130644288 10577125376 1094781312 11.788 97 34 68 1 1199862304 98403360 6677632
09:43:22 45679 8192000000 10035153408 10491478016 1015053152 11.024 92 17 34 1 1063680320 44846016 3781152
09:45:08 23760 8192000000 10072147984 10489217024 1093496640 11.776 100 40 80 0 1155508576 54021136 7990800
09:46:32 118583 8192000000 10035274192 10493661184 1098245840 11.821 100 17 34 0 1167601184 65708640 3646704
09:49:37 65362 8192000000 10036029136 10491666432 1099004848 11.828 99 9 18 0 1124625984 23803536 1817600
09:51:42 73030 8192000000 10058764960 10533044224 1116540416 11.994 100 11 22 0 1164215200 45464528 2210256
09:53:56 43354 8192000000 10035501664 10492600320 1107319632 11.907 99 25 50 0 1135480816 22855840 5305344
09:55:43 40353 8192000000 10092826720 10534625280 1083634768 11.682 96 29 56 0 1156862064 67525024 5702272
09:57:31 56748 8192000000 10168092464 10615439360 1140289088 12.218 100 34 67 2 1259939392 111935840 7714464
09:59:30 58636 8192000000 10176890672 10621956096 1122823008 12.054 98 12 24 1 1235119328 109691312 2605008
10:01:30 48559 8192000000 10145099072 10593034240 1127929760 12.102 99 11 22 1 1258662096 128069216 2663120
10:04:00 73269 8192000000 10085754528 10543468544 1117740016 12.006 98 12 24 0 1173910016 53740896 2429104
10:06:16 82013 8192000000 10054870032 10511863808 1136259664 12.180 100 17 34 2 1165501696 26086800 3155232
10:08:53 46672 8192000000 10083787056 10558742528 1132184768 12.142 99 12 24 0 1187261232 52379760 2696704
10:10:39 63959 8192000000 10163227856 10645651456 1139023632 12.206 100 13 26 1 1239976912 97944704 3008576
10:12:53 42868 8192000000 10068894272 10588934144 1130596784 12.127 100 11 22 1 1207837968 74964944 2276240
10:14:37 25425 8192000000 10065555632 10515992576 1139715760 12.213 100 22 44 0 1169593920 25007152 4871008
10:16:18 50511 8192000000 10067102912 10508361728 1117665744 12.005 98 22 43 1 1149168608 27012480 4490384
10:18:15 56314 8192000000 10066245456 10521509888 1071457504 11.566 94 18 36 1 1105563008 30663040 3442464
10:20:16 36128 8192000000 10100002080 10534363136 1108509600 11.918 98 29 58 1 1161948320 47597120 5841600
10:21:53 48364 8192000000 10082598400 10541387776 1109746272 11.930 98 21 42 0 1159395312 45658336 3990704
10:23:43 67179 8192000000 10090393888 10585452544 1114778608 11.978 99 10 20 0 1186020112 69594672 1646832
10:26:16 49233 8192000000 9472922000 9826521088 591448256 6.733 53 4 7 0 612689040 20370928 869856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-02-2021 10:29:49 to 12-02-2021 10:30:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-02-2021 10:30:06 to 12-02-2021 10:30:08.

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          234491
MON$OLDEST_ACTIVE               235226
MON$OLDEST_SNAPSHOT             235226
MON$NEXT_TRANSACTION            235226
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                       2125696
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:315: 
:316: mscs-test-161.budzdorov.ru    Fri Feb 12 10:27:41 2021
:317:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:318: 
--- end of diff output ---

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

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