Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.2021 07:38
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 16251976 kB
MemAvailable 31188104 kB
Buffers 0 kB
Cached 15449956 kB
SwapCached 4092 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-16 07:36:42
DTS_END                         2021-02-16 07:36:42.3570
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/49408
TRN_ID                          235130
ATT_ID                          604
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: 8892.28 26741 1067073
customer order: creation 160.74 397 19289
customer order: refuse 13.93 388 1672
order to supplier: creation 43.16 1366 5179
order to supplier: removal 3.44 121458 413
invoice (draft): creation 44.09 4087 5291
invoice (draft): removal 49.78 1103 5973
invoice accept: apply 40.70 19168 4884
invoice accept: cancel 45.73 1036 5487
customer reserve: creation 3688.41 189 442609
customer reserve: removal 4515.98 71 541918
realization accept: apply 80.41 216 9649
realization accept: cancel 81.75 125 9810
payment from customer: creation 56.37 130 6764
payment from customer: removal 5.61 105 673
payment to supplier: creation 47.68 140 5722
payment to supplier: removal 3.28 90 393
service: total inventory turnovers 3.67 3833 440
service: total monetary turnovers 6.50 647 780
service: aggregate perf. data 1.03 13236 123
service: refresh index statistics 0.03 367038 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-02-2021 07:38:45 to 16-02-2021 07:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1936
TEST_TIME 2 2681
TEST_TIME 3 3012
TEST_TIME 4 3344
TEST_TIME 5 3362
TEST_TIME 6 3991
TEST_TIME 7 4647
TEST_TIME 8 5371
TEST_TIME 9 5812
TEST_TIME 10 5994
TEST_TIME 11 6155
TEST_TIME 12 6171
TEST_TIME 13 6470
TEST_TIME 14 6417
TEST_TIME 15 6742
TEST_TIME 16 6727
TEST_TIME 17 6695
TEST_TIME 18 6760
TEST_TIME 19 7259
TEST_TIME 20 7463
TEST_TIME 21 7417
TEST_TIME 22 7461
TEST_TIME 23 7667
TEST_TIME 24 8026
TEST_TIME 25 8157
TEST_TIME 26 8293
TEST_TIME 27 8267
TEST_TIME 28 8305
TEST_TIME 29 8423
TEST_TIME 30 8773
TEST_TIME 31 9876
TEST_TIME 32 10513
TEST_TIME 33 10467
TEST_TIME 34 10609
TEST_TIME 35 10475
TEST_TIME 36 10323
TEST_TIME 37 10194
TEST_TIME 38 10170
TEST_TIME 39 10170
TEST_TIME 40 10052
TEST_TIME 41 10040
TEST_TIME 42 10010
TEST_TIME 43 10086
TEST_TIME 44 10073
TEST_TIME 45 10060
TEST_TIME 46 10094
TEST_TIME 47 10229
TEST_TIME 48 10178
TEST_TIME 49 10061
TEST_TIME 50 9973
TEST_TIME 51 9813
TEST_TIME 52 9752
TEST_TIME 53 9660
TEST_TIME 54 9717
TEST_TIME 55 9652
TEST_TIME 56 9658
TEST_TIME 57 9593
TEST_TIME 58 9543
TEST_TIME 59 9482
TEST_TIME 60 9503
TEST_TIME 61 9458
TEST_TIME 62 9388
TEST_TIME 63 9339
TEST_TIME 64 9317
TEST_TIME 65 9243
TEST_TIME 66 9194
TEST_TIME 67 9295
TEST_TIME 68 9334
TEST_TIME 69 9301
TEST_TIME 70 9268
TEST_TIME 71 9337
TEST_TIME 72 9401
TEST_TIME 73 9402
TEST_TIME 74 9483
TEST_TIME 75 9435
TEST_TIME 76 9439
TEST_TIME 77 9427
TEST_TIME 78 9369
TEST_TIME 79 9340
TEST_TIME 80 9294
TEST_TIME 81 9233
TEST_TIME 82 9164
TEST_TIME 83 9142
TEST_TIME 84 9125
TEST_TIME 85 9108
TEST_TIME 86 9082
TEST_TIME 87 9061
TEST_TIME 88 9032
TEST_TIME 89 9062
TEST_TIME 90 9084
TEST_TIME 91 9085
TEST_TIME 92 9125
TEST_TIME 93 9205
TEST_TIME 94 9178
TEST_TIME 95 9182
TEST_TIME 96 9190
TEST_TIME 97 9107
TEST_TIME 98 9130
TEST_TIME 99 9082
TEST_TIME 100 9030
TEST_TIME 101 9036
TEST_TIME 102 8981
TEST_TIME 103 9038
TEST_TIME 104 9080
TEST_TIME 105 9070
TEST_TIME 106 9039
TEST_TIME 107 9039
TEST_TIME 108 9039
TEST_TIME 109 9026
TEST_TIME 110 9077
TEST_TIME 111 9078
TEST_TIME 112 9086
TEST_TIME 113 9059
TEST_TIME 114 9143
TEST_TIME 115 9128
TEST_TIME 116 9054
TEST_TIME 117 9000
TEST_TIME 118 8976
TEST_TIME 119 8916
TEST_TIME 120 8892

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-02-2021 07:38:49 to 16-02-2021 07:38:52.

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 1476703 1476703 0 0.00 0 24644 7 0 0
doc_list_biud 1476701 1476701 0 0.00 0 24176 34 0 0
sp_add_invoice_to_stock 4998 4884 114 2.28 35 187924 19168 0 114
sp_cancel_adding_invoice 5487 5487 0 0.00 5 136399 1037 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 17163 389 0 0
sp_cancel_customer_reserve 541918 541918 0 0.00 1 14181 71 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 7239 106 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 5027 91 0 0
sp_cancel_supplier_invoice 5979 5973 6 0.10 2 24183 1104 0 6
sp_cancel_supplier_order 413 413 0 0.00 16 1386086 121459 0 0
sp_cancel_write_off 9810 9810 0 0.00 1 21759 126 0 0
sp_client_order 19289 19289 0 0.00 4 108057 397 0 0
sp_customer_reserve 442609 442609 0 0.00 0 38293 189 0 0
sp_fill_shopping_cart 29732 29732 0 0.00 1 20902 45 0 0
sp_fill_shopping_cart_clo_res 442609 442609 0 0.00 0 21560 16 0 0
sp_get_clo_for_invoice 4884 4884 0 0.00 0 31307 397 0 0
sp_kill_cost_storno 16363 16363 0 0.00 0 9749 66 0 0
sp_kill_qstorno_ret_qs2qd 1100032 1100032 0 0.00 0 24175 39 0 0
sp_kill_qty_storno 579805 579805 0 0.00 0 24176 85 0 0
sp_lock_selected_doc 20152 20152 0 0.00 0 13539 7 0 0
sp_make_cost_storno 27018 27018 0 0.00 0 19466 68 0 0
sp_multiply_rows_for_pdistr 27018 27018 0 0.00 0 9481 36 0 0
sp_multiply_rows_for_qdistr 19289 19289 0 0.00 0 107284 196 0 0
sp_pay_from_customer 6764 6764 0 0.00 4 17494 130 0 0
sp_pay_to_supplier 5722 5722 0 0.00 5 10971 140 0 0
sp_payment_common 12486 12486 0 0.00 1 10948 31 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 17154 348 0 0
sp_qd_handle_on_invoice_upd_sts 10370 10370 0 0.00 0 23250 482 0 0
sp_qd_handle_on_reserve_upd_sts 9649 9649 0 0.00 0 13413 62 0 0
sp_reserve_write_off 9649 9649 0 0.00 6 20601 216 0 0
sp_supplier_invoice 5291 5291 0 0.00 7 123904 4088 0 0
sp_supplier_order 5179 5179 0 0.00 6 24431 1367 0 0
srv_aggregate_perf_data 123 123 0 0.00 2665 158561 13237 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1901 140057 46167 0 0
srv_find_qd_qs_mism 1021491 1021491 0 0.00 0 13010 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 48 113054 3833 0 0
srv_make_money_saldo 780 780 0 0.00 1 12307 648 0 0
srv_recalc_idx_stat 4 4 0 0.00 224593 652115 367039 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 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 4884 4884 0 0.00 0 11599 212 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 3150 104 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 2806 16 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 1487 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2732 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 13604 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 988 16 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 27500 1762 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 3252 10 0 0
t$perf-norm:sp_client_order 19289 19289 0 0.00 0 10646 20 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 4494 17 0 0
t$perf-norm:sp_pay_from_customer 6764 6764 0 0.00 0 7351 14 0 0
t$perf-norm:sp_pay_to_supplier 5722 5722 0 0.00 0 5109 15 0 0
t$perf-norm:sp_reserve_write_off 9649 9649 0 0.00 0 10413 15 0 0
t$perf-norm:sp_supplier_invoice 5291 5291 0 0.00 0 11418 24 0 0
t$perf-norm:sp_supplier_order 5179 5179 0 0.00 0 3813 19 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 541 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 839 11 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 5465 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 9992 9764 228 2.28 0 4649 17 0 228
v_all_customers 35178 35178 0 0.00 0 118 1 0 0
v_all_suppliers 28918 28918 0 0.00 0 100 1 0 0
v_all_wares 38578 38578 0 0.00 0 222 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1429 8 0 0
v_cancel_client_order 3344 3344 0 0.00 0 5032 5 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3510 9 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 1905 5 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 5019 13 0 0
v_cancel_supplier_invoice 814 802 12 1.47 0 2972 19 0 12
v_cancel_supplier_order 826 826 0 0.00 0 2040 9 0 0
v_cancel_write_off 1970 1970 0 0.00 0 707 13 0 0
v_max_id_clo_ord 5176 5176 0 0.00 0 1274 2 0 0
v_max_id_clo_res 988 988 0 0.00 0 3154 17 0 0
v_max_id_ord_sup 5266 5266 0 0.00 0 8349 8 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 329 2 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 6558 7 0 0
v_min_id_clo_ord 5176 5176 0 0.00 0 6135 11 0 0
v_min_id_clo_res 988 988 0 0.00 0 4335 14 0 0
v_min_id_ord_sup 5266 5266 0 0.00 0 7916 7 0 0
v_min_non_paid_invoice 5722 5722 0 0.00 0 10944 23 0 0
v_min_non_paid_realizn 6764 6764 0 0.00 0 9309 23 0 0
v_our_firm 9596 9596 0 0.00 0 17 1 0 0
v_reserve_write_off 19298 19298 0 0.00 0 2361 5 0 0
x_lock_depdocs_on_canc_invoice 11059 11059 0 0.00 0 12902 143 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 6808 385 0 0
x_make_qstorno_1000_1200 5179 5179 0 0.00 2 24379 1267 0 0
x_make_qstorno_1200_2000 5291 5291 0 0.00 3 111372 3931 0 0
x_make_qstorno_2100_3300 442609 442609 0 0.00 0 38291 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-02-2021 07:38:53 to 16-02-2021 07:38:57.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:06:46 14 8192000000 8803899728 8817008640 8704752 0.106 4 2 2 0 8809824 69296 35776
05:07:46 19267 8192000000 9714902832 10009034752 791251888 8.808 100 95 190 1 822749600 8128240 23369472
05:09:10 19784 8192000000 9764496192 10071818240 842571904 9.326 100 89 177 0 875460336 12028128 20860304
05:10:33 16887 8192000000 9795764592 10113187840 867964992 9.580 100 85 170 1 906916192 19105776 19845424
05:11:53 11329 8192000000 9844212192 10173640704 906711040 9.965 100 77 155 2 953756992 29695328 17350624
05:13:12 7654 8192000000 9924038080 10263326720 954611744 10.436 100 83 166 0 1035281952 63187760 17482448
05:14:22 5613 8192000000 9953140032 10302476288 992218304 10.803 100 84 168 1 1061713376 52480224 17014848
05:15:30 2924 8192000000 10010041968 10372145152 1024624624 11.117 100 87 175 0 1128054112 86009664 17419824
05:16:35 1602 8192000000 10032092496 10397700096 1047632848 11.338 100 97 194 1 1150053120 82930432 19489840
05:17:39 3050 8192000000 10039455328 10408759296 1064196000 11.497 100 89 180 0 1146095792 64896768 17003024
05:18:42 5756 8192000000 9989588544 10372235264 1072755200 11.578 100 36 70 1 1120808672 40343568 7709904
05:19:49 29135 8192000000 10010485184 10407387136 1077599632 11.625 100 40 80 1 1115612400 29584496 8428272
05:21:18 6285 8192000000 10003409984 10401406976 1087647616 11.720 100 38 73 5 1127208784 31339552 8221616
05:22:26 6589 8192000000 10005158624 10407460864 1090248640 11.745 100 16 30 4 1128042976 34647776 3146560
05:23:33 21234 8192000000 10003609216 10415419392 1085618880 11.701 99 32 64 2 1111274304 19072032 6583392
05:24:54 4053 8192000000 10019171712 10408230912 1094206480 11.783 100 74 150 4 1127510816 16480752 16823584
05:25:58 5733 8192000000 10015642064 10420568064 1100669728 11.844 100 23 46 1 1127350512 22236128 4444656
05:27:05 4546 8192000000 9961626656 10343817216 1037502304 11.241 96 44 90 0 1058901552 11106128 10293120
05:28:13 74433 8192000000 9961467264 10360795136 975589536 10.641 92 24 48 2 998636176 17254576 5792064
05:30:29 3820 8192000000 9964160592 10340368384 1015973872 11.033 98 33 67 4 1049456224 26272944 7209408
05:31:33 10682 8192000000 9936369456 10326450176 998728544 10.866 97 28 54 0 1037775696 33288832 5758320
05:32:44 49056 8192000000 9955632144 10351591424 1049703488 11.358 100 31 61 0 1076719184 19645504 7370192
05:34:51 4433 8192000000 9985183120 10380668928 1069049392 11.543 100 33 67 1 1093155216 16725424 7380400
05:35:57 6239 8192000000 10000959952 10399318016 1073556592 11.586 100 33 64 1 1114977232 34536048 6884592
05:37:08 29262 8192000000 10011338384 10401792000 1084889600 11.694 100 57 114 1 1106998944 9508320 12601024
05:38:41 47785 8192000000 10054161376 10458570752 1100613008 11.843 100 42 83 1 1161286832 52246768 8427056
05:40:50 18721 8192000000 10090208384 10498338816 1118236896 12.010 100 46 91 1 1196728672 68778032 9713744
05:42:13 12285 8192000000 10154660480 10559598592 1129055920 12.112 100 59 118 0 1270981712 129341936 12583856
05:43:28 24894 8192000000 10181211808 10598965248 1133607728 12.155 100 51 100 2 1287417344 143129056 10680560
05:45:02 8945 8192000000 10158844208 10574000128 1138599248 12.202 100 51 102 0 1270889040 121218416 11071376
05:46:20 4167 8192000000 10185741328 10596966400 1167843856 12.477 100 66 131 1 1291755648 108083456 15828336
05:47:31 8578 8192000000 10220636960 10631577600 1169278000 12.490 100 75 150 0 1326825696 141742160 15805536
05:48:41 2434 8192000000 10235154384 10641518592 1169121696 12.489 100 76 151 2 1345990384 159568032 17300656
05:49:49 4751 8192000000 10245830320 10644049920 1150242832 12.312 100 77 153 0 1348433456 181612656 16577968
05:51:10 4232 8192000000 10269488672 10669854720 1155021136 12.357 100 80 154 2 1368634336 196577552 17035648
05:52:18 2586 8192000000 10272294944 10667139072 1143298208 12.247 100 82 161 0 1365264464 203707264 18258992
05:53:24 2694 8192000000 10326411088 10725318656 1149639504 12.306 100 87 172 0 1429039552 260014480 19385568
05:54:34 1981 8192000000 10350667232 10745712640 1160155792 12.405 100 92 183 1 1450530784 269630448 20744544
05:55:40 3332 8192000000 10338155488 10738827264 1151155232 12.320 100 79 157 0 1436860176 268022704 17682240
05:56:49 2462 8192000000 10366109360 10779275264 1161659680 12.419 100 86 170 1 1463434272 282619600 19154992
05:57:58 1898 8192000000 10449047952 10848342016 1182015712 12.609 100 90 179 0 1544523264 341257392 21250160
05:59:04 2892 8192000000 10500752960 10907295744 1179347568 12.584 100 83 165 2 1591620384 392909152 19363664
06:00:12 7302 8192000000 10446314992 10895138816 1168125712 12.479 99 52 102 1 1538879360 357516240 13237408
06:01:40 6787 8192000000 10442473424 10858094592 1174049104 12.535 100 58 116 1 1544740912 355291648 15400160
06:02:53 14220 8192000000 10465655904 10915491840 1176655232 12.559 100 40 80 1 1585577376 398646768 10275376
06:04:12 18120 8192000000 10502922944 10946109440 1156548128 12.371 100 34 68 4 1612151792 445956688 9646976
06:05:32 19420 8192000000 10529705552 10972794880 1144954320 12.262 100 38 77 1 1628381808 472630592 10796896
06:07:02 12360 8192000000 10482933024 10951372800 1141094864 12.226 100 26 52 1 1606694048 457953728 7645456
06:08:35 39828 8192000000 10148085536 10607501312 1140513392 12.220 99 18 36 0 1253874432 109790528 3570512
06:10:16 92541 8192000000 10078465728 10563145728 1133861968 12.158 99 7 14 0 1176211936 41206544 1143424
06:13:09 97723 8192000000 10099513296 10556411904 1122802400 12.053 98 23 46 0 1201038032 72990176 5245456
06:16:04 68589 8192000000 10090560080 10541891584 1138838928 12.205 99 23 44 3 1185768352 41541392 5388032
06:18:13 122132 8192000000 10120686192 10606837760 1123684160 12.062 97 8 16 0 1144318384 19341696 1292528
06:21:17 28060 8192000000 10099661136 10529939456 1151207360 12.321 99 35 70 1 1175277040 16984720 7084960
06:22:47 58204 8192000000 10119427488 10647359488 1137737808 12.194 98 6 12 0 1221735968 82930752 1067408
06:24:47 140056 8192000000 10105127088 10583687168 1127505456 12.098 98 25 50 1 1195235648 62344560 5385632
06:28:08 25441 8192000000 10083799088 10529947648 1150772544 12.317 100 27 52 1 1184141840 27655952 5713344
06:29:35 63779 8192000000 10125916464 10598100992 1113689776 11.967 96 45 90 2 1203341440 79871120 9780544
06:31:49 61826 8192000000 10085002592 10582298624 1113215120 11.963 97 9 18 0 1190160800 74871424 2074256
06:34:21 66792 8192000000 10127911296 10584256512 1122379264 12.049 98 13 26 2 1197894672 72569696 2945712
06:36:33 67999 8192000000 10065764448 10561912832 1107976736 11.913 98 16 32 0 1165455312 54122352 3356224
06:38:49 59838 8192000000 10061469136 10537136128 1128183696 12.104 100 5 10 0 1157471280 28572976 714608
06:40:52 99834 8192000000 10114384304 10568683520 1120099008 12.028 98 10 20 1 1182402800 60254176 2049616
06:44:32 25910 8192000000 10063526432 10507972608 1116397296 11.993 98 33 66 1 1146218544 22957808 6863440
06:46:19 48123 8192000000 10142222432 10593136640 1122800096 12.053 98 15 30 1 1227268688 101540944 2927648
06:48:17 45655 8192000000 10107443200 10565521408 1118179776 12.010 98 15 30 1 1163565344 42364848 3020720
06:50:03 84014 8192000000 10074264896 10498646016 1092617408 11.768 96 24 46 0 1171356000 73895408 4843184
06:52:30 70860 8192000000 10097729840 10542477312 1131429472 12.135 100 25 50 3 1176578816 40237568 4911776
06:54:41 91785 8192000000 10062849552 10538917888 1123891088 12.064 99 17 34 0 1173992000 46631696 3469216
06:57:13 45060 8192000000 10038139888 10495590400 1111112944 11.943 98 11 21 0 1134232560 21249376 1870240
06:58:58 64615 8192000000 10059694816 10527191040 1136987872 12.187 100 17 34 0 1170374000 29750912 3635216
07:01:03 57300 8192000000 10062388432 10517995520 1134995888 12.168 100 11 20 0 1181014672 43847936 2170848
07:03:16 30319 8192000000 10109867872 10553622528 1129194176 12.114 99 26 52 2 1190100992 55295984 5610832
07:04:51 54051 8192000000 10117974864 10576908288 1128931264 12.111 98 19 38 2 1215375952 82421536 4023152
07:06:48 91688 8192000000 10126468576 10594496512 1106156688 11.896 96 16 32 0 1159095376 49219696 3718992
07:09:27 40275 8192000000 10076315392 10552877056 1140874912 12.224 100 17 34 0 1215945488 71655680 3414896
07:11:07 67158 8192000000 10134186752 10576875520 1124526592 12.070 97 25 50 2 1216118064 86348656 5242816
07:13:18 101227 8192000000 10063256592 10569916416 1141696960 12.231 100 17 34 0 1202452464 56948528 3806976
07:16:13 24177 8192000000 10078459200 10584518656 1146827616 12.280 100 3 6 1 1174727024 27736048 163360
07:17:42 46713 8192000000 10102369200 10550128640 1138338128 12.200 98 10 20 0 1181181344 40970464 1872752
07:19:29 101547 8192000000 10099682448 10578620416 1150873264 12.318 99 12 24 0 1200120080 47215248 2031568
07:22:10 60813 8192000000 10097967488 10563915776 1124715808 12.072 97 22 41 1 1154995056 26040784 4238464
07:24:48 16989 8192000000 10089046064 10515525632 1112060352 11.952 98 46 90 1 1157055504 36081744 8913408
07:26:15 99045 8192000000 10088936432 10559254528 1096486496 11.804 96 19 38 0 1152220288 52011632 3722160
07:28:55 102214 8192000000 10108666976 10580758528 1054769248 11.406 92 17 34 1 1110251824 51816560 3666016
07:32:12 111765 8192000000 10057811376 10516037632 1109097744 11.924 99 17 33 0 1153159872 40162560 3899568
07:35:07 72294 8192000000 10069849520 10538684416 1125180160 12.076 100 8 16 0 1166967088 39784832 2002096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-02-2021 07:38:59 to 16-02-2021 07:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 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 16-02-2021 07:39:15 to 16-02-2021 07:39:17.

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          234834
MON$OLDEST_ACTIVE               235274
MON$OLDEST_SNAPSHOT             235274
MON$NEXT_TRANSACTION            235274
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                       2134976
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2366 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2366 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2366 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.2366 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Feb 16 07:36:45 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Feb 16 07:36:57 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Feb 16 07:37:05 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

16.02.21 07:39:18. 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-16 07:39:20 No severe PSQL-related problems occured
16.02.21 07:39:22. End of report.