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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16712156 kB
MemAvailable 31477528 kB
Buffers 20 kB
Cached 14845824 kB
SwapCached 1988 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-08 04:26:54
DTS_END                         2020-12-08 04:26:54.3710
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/35358
TRN_ID                          241215
ATT_ID                          681
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: 9052.63 19987 1086315
customer order: creation 172.00 401 20640
customer order: refuse 14.78 354 1773
order to supplier: creation 47.56 1344 5707
order to supplier: removal 3.22 114078 386
invoice (draft): creation 46.77 3760 5612
invoice (draft): removal 49.47 932 5936
invoice accept: apply 43.38 16456 5206
invoice accept: cancel 44.58 906 5349
customer reserve: creation 3943.23 163 473187
customer reserve: removal 4390.37 67 526844
realization accept: apply 84.88 198 10186
realization accept: cancel 81.40 119 9768
payment from customer: creation 59.73 121 7167
payment from customer: removal 5.61 87 673
payment to supplier: creation 50.67 135 6080
payment to supplier: removal 3.60 66 432
service: total inventory turnovers 4.18 3286 502
service: total monetary turnovers 6.38 505 765
service: aggregate perf. data 0.82 17587 98
service: refresh index statistics 0.03 239165 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-12-2020 04:28:19 to 08-12-2020 04:28:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3804
TEST_TIME 2 3044
TEST_TIME 3 2951
TEST_TIME 4 4140
TEST_TIME 5 4702
TEST_TIME 6 4943
TEST_TIME 7 5179
TEST_TIME 8 5438
TEST_TIME 9 5642
TEST_TIME 10 6005
TEST_TIME 11 6272
TEST_TIME 12 6774
TEST_TIME 13 7124
TEST_TIME 14 7198
TEST_TIME 15 7580
TEST_TIME 16 7676
TEST_TIME 17 7859
TEST_TIME 18 7869
TEST_TIME 19 8161
TEST_TIME 20 8577
TEST_TIME 21 8702
TEST_TIME 22 9430
TEST_TIME 23 9598
TEST_TIME 24 9983
TEST_TIME 25 10421
TEST_TIME 26 10835
TEST_TIME 27 11426
TEST_TIME 28 11737
TEST_TIME 29 11605
TEST_TIME 30 11463
TEST_TIME 31 11405
TEST_TIME 32 11388
TEST_TIME 33 11221
TEST_TIME 34 11103
TEST_TIME 35 10887
TEST_TIME 36 10658
TEST_TIME 37 10527
TEST_TIME 38 10499
TEST_TIME 39 10436
TEST_TIME 40 10402
TEST_TIME 41 10375
TEST_TIME 42 10248
TEST_TIME 43 10203
TEST_TIME 44 10091
TEST_TIME 45 9982
TEST_TIME 46 9958
TEST_TIME 47 9887
TEST_TIME 48 9753
TEST_TIME 49 9887
TEST_TIME 50 9809
TEST_TIME 51 9756
TEST_TIME 52 9721
TEST_TIME 53 9648
TEST_TIME 54 9861
TEST_TIME 55 9948
TEST_TIME 56 10006
TEST_TIME 57 10055
TEST_TIME 58 10029
TEST_TIME 59 9926
TEST_TIME 60 9826
TEST_TIME 61 9759
TEST_TIME 62 9709
TEST_TIME 63 9620
TEST_TIME 64 9538
TEST_TIME 65 9520
TEST_TIME 66 9508
TEST_TIME 67 9530
TEST_TIME 68 9475
TEST_TIME 69 9471
TEST_TIME 70 9463
TEST_TIME 71 9550
TEST_TIME 72 9549
TEST_TIME 73 9526
TEST_TIME 74 9570
TEST_TIME 75 9711
TEST_TIME 76 9640
TEST_TIME 77 9566
TEST_TIME 78 9477
TEST_TIME 79 9415
TEST_TIME 80 9340
TEST_TIME 81 9306
TEST_TIME 82 9307
TEST_TIME 83 9243
TEST_TIME 84 9268
TEST_TIME 85 9252
TEST_TIME 86 9218
TEST_TIME 87 9310
TEST_TIME 88 9385
TEST_TIME 89 9368
TEST_TIME 90 9360
TEST_TIME 91 9389
TEST_TIME 92 9403
TEST_TIME 93 9394
TEST_TIME 94 9366
TEST_TIME 95 9270
TEST_TIME 96 9210
TEST_TIME 97 9146
TEST_TIME 98 9172
TEST_TIME 99 9174
TEST_TIME 100 9172
TEST_TIME 101 9148
TEST_TIME 102 9125
TEST_TIME 103 9149
TEST_TIME 104 9129
TEST_TIME 105 9151
TEST_TIME 106 9224
TEST_TIME 107 9193
TEST_TIME 108 9247
TEST_TIME 109 9206
TEST_TIME 110 9249
TEST_TIME 111 9315
TEST_TIME 112 9351
TEST_TIME 113 9281
TEST_TIME 114 9204
TEST_TIME 115 9165
TEST_TIME 116 9146
TEST_TIME 117 9107
TEST_TIME 118 9088
TEST_TIME 119 9088
TEST_TIME 120 9055

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-12-2020 04:28:23 to 08-12-2020 04:28:27.

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 1524062 1524062 0 0.00 0 20239 6 0 0
doc_list_biud 1524057 1524057 0 0.00 0 26881 30 0 0
sp_add_invoice_to_stock 5287 5206 81 1.53 17 171485 16456 0 81
sp_cancel_adding_invoice 5349 5349 0 0.00 5 86979 906 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 11668 355 0 0
sp_cancel_customer_reserve 526844 526844 0 0.00 1 25572 68 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 2 6690 88 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 2569 66 0 0
sp_cancel_supplier_invoice 5948 5936 12 0.20 2 16008 933 0 12
sp_cancel_supplier_order 386 386 0 0.00 7 1209614 114079 0 0
sp_cancel_write_off 9768 9768 0 0.00 1 14428 119 0 0
sp_client_order 20640 20640 0 0.00 4 16185 402 0 0
sp_customer_reserve 473187 473187 0 0.00 0 68491 164 0 0
sp_fill_shopping_cart 31936 31936 0 0.00 1 14698 43 0 0
sp_fill_shopping_cart_clo_res 473176 473176 0 0.00 0 19171 15 0 0
sp_get_clo_for_invoice 5195 5195 0 0.00 0 29516 327 0 0
sp_kill_cost_storno 16222 16222 0 0.00 0 9948 54 0 0
sp_kill_qstorno_ret_qs2qd 1069778 1069778 0 0.00 0 25362 37 0 0
sp_kill_qty_storno 565435 565435 0 0.00 0 26881 79 0 0
sp_lock_selected_doc 21291 21291 0 0.00 0 8318 7 0 0
sp_make_cost_storno 28628 28628 0 0.00 0 15718 56 0 0
sp_multiply_rows_for_pdistr 28626 28626 0 0.00 0 9971 32 0 0
sp_multiply_rows_for_qdistr 20640 20640 0 0.00 0 12932 221 0 0
sp_pay_from_customer 7167 7167 0 0.00 5 15620 122 0 0
sp_pay_to_supplier 6080 6080 0 0.00 4 15733 135 0 0
sp_payment_common 13247 13247 0 0.00 1 9753 27 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 11662 318 0 0
sp_qd_handle_on_invoice_upd_sts 10543 10543 0 0.00 0 26880 350 0 0
sp_qd_handle_on_reserve_upd_sts 10185 10185 0 0.00 0 17144 63 0 0
sp_reserve_write_off 10186 10186 0 0.00 7 17162 199 0 0
sp_supplier_invoice 5612 5612 0 0.00 6 96263 3761 0 0
sp_supplier_order 5707 5707 0 0.00 5 26822 1345 0 0
srv_aggregate_perf_data 98 98 0 0.00 2460 221811 17587 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1772 127956 46624 0 0
srv_find_qd_qs_mism 1037584 1037584 0 0.00 0 20239 5 0 0
srv_make_invnt_saldo 502 502 0 0.00 59 134711 3286 0 0
srv_make_money_saldo 765 765 0 0.00 1 12592 505 0 0
srv_recalc_idx_stat 4 4 0 0.00 128793 425745 239166 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5207 5207 0 0.00 0 13164 159 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 3647 87 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 2752 11 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 2071 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 672 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 296 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 2796 29 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 17619 1324 0 0
t$perf-norm:sp_cancel_write_off 1125 1125 0 0.00 0 2188 6 0 0
t$perf-norm:sp_client_order 20640 20640 0 0.00 0 15951 16 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 1063 6 0 0
t$perf-norm:sp_pay_from_customer 7167 7167 0 0.00 0 9712 9 0 0
t$perf-norm:sp_pay_to_supplier 6080 6080 0 0.00 0 12593 10 0 0
t$perf-norm:sp_reserve_write_off 10186 10186 0 0.00 0 5580 10 0 0
t$perf-norm:sp_supplier_invoice 5612 5612 0 0.00 0 4174 16 0 0
t$perf-norm:sp_supplier_order 5707 5707 0 0.00 0 6282 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 174 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 392 7 0 0
t$perf-norm:srv_make_invnt_saldo 502 502 0 0.00 0 6180 23 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 886 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 10548 10386 162 1.53 0 5331 16 0 162
v_all_customers 37704 37704 0 0.00 0 44 1 0 0
v_all_suppliers 31252 31252 0 0.00 0 21 1 0 0
v_all_wares 41280 41280 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 5577 17 0 0
v_cancel_client_order 3546 3546 0 0.00 0 6150 8 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 1124 6 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 3782 6 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 630 4 0 0
v_cancel_supplier_invoice 838 814 24 2.86 0 5101 19 0 24
v_cancel_supplier_order 772 772 0 0.00 0 641 5 0 0
v_cancel_write_off 2250 2250 0 0.00 0 2532 14 0 0
v_max_id_clo_ord 5703 5703 0 0.00 0 2081 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 1312 11 0 0
v_max_id_ord_sup 5593 5593 0 0.00 0 11049 8 0 0
v_max_non_paid_invoice 1749 1749 0 0.00 0 151 2 0 0
v_max_non_paid_realizn 3839 3839 0 0.00 0 4672 4 0 0
v_min_id_clo_ord 5703 5703 0 0.00 0 7524 9 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 7051 13 0 0
v_min_id_ord_sup 5592 5592 0 0.00 0 9553 10 0 0
v_min_non_paid_invoice 6080 6080 0 0.00 0 9259 16 0 0
v_min_non_paid_realizn 7167 7167 0 0.00 0 9709 19 0 0
v_our_firm 10232 10232 0 0.00 0 21 1 0 0
v_reserve_write_off 20370 20370 0 0.00 0 5905 6 0 0
x_lock_depdocs_on_canc_invoice 10878 10878 0 0.00 0 14109 106 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 4349 327 0 0
x_make_qstorno_1000_1200 5707 5707 0 0.00 2 26811 1253 0 0
x_make_qstorno_1200_2000 5612 5612 0 0.00 3 96226 3609 0 0
x_make_qstorno_2100_3300 473187 473187 0 0.00 0 68474 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2020 04:28:28 to 08-12-2020 04:28:32.

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
01:56:58 7 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:59 21180 8192000000 9724180512 10018734080 797497648 8.871 100 90 180 0 827696672 7901392 22297632
01:59:24 19756 8192000000 9764483920 10073546752 843508000 9.335 100 84 169 0 875213840 12002832 19703008
02:00:52 19157 8192000000 9808225632 10123227136 869620624 9.596 100 88 176 0 914329584 24207792 20501168
02:02:20 9668 8192000000 9866068272 10198523904 918116944 10.077 100 79 159 0 974547424 39281040 17149440
02:03:34 11407 8192000000 9922296816 10264174592 951823376 10.409 100 85 170 0 1033245520 63472480 17949664
02:04:47 4119 8192000000 9948660256 10299883520 983542672 10.719 100 90 179 2 1071628256 69822544 18263040
02:05:52 3499 8192000000 9990941776 10350956544 1012830480 11.003 100 86 170 2 1099011088 69335840 16844768
02:06:57 2339 8192000000 10008153904 10376105984 1039318192 11.258 100 82 164 2 1119717040 63776720 16622128
02:08:01 1381 8192000000 10059587680 10432299008 1063262288 11.488 100 87 173 0 1166642768 86163344 17217136
02:09:03 11376 8192000000 10002229424 10394685440 1064682640 11.501 100 46 90 0 1121818144 47991904 9143600
02:10:15 6239 8192000000 9978083728 10368356352 1075645344 11.606 100 36 72 0 1108821280 24304624 8871312
02:11:25 5439 8192000000 9990751024 10385092608 1086328416 11.708 100 31 60 3 1107511904 14942736 6240752
02:12:31 5425 8192000000 9992822976 10399666176 1090329728 11.746 100 42 84 4 1118345936 18491360 9524848
02:13:40 50726 8192000000 10019092976 10437623808 1091589840 11.758 100 44 87 3 1117710432 16983104 9137488
02:15:34 7365 8192000000 10038206240 10444500992 1102477088 11.861 100 51 101 1 1153212480 39692608 11042784
02:16:44 8882 8192000000 10013498256 10415009792 1077824752 11.627 98 29 56 3 1109187968 25211184 6152032
02:17:56 7300 8192000000 9950526592 10342260736 1014871264 11.022 96 32 62 4 1040810256 19256816 6682176
02:19:08 20454 8192000000 9960847728 10356097024 998207568 10.861 94 26 52 1 1039821072 36267792 5345712
02:20:28 5461 8192000000 9945968144 10328494080 996801200 10.848 96 53 109 1 1027355024 19173200 11380624
02:21:35 8771 8192000000 9927919072 10312024064 998082368 10.860 98 42 84 3 1022555136 14661328 9811440
02:22:44 13027 8192000000 9950273712 10342170624 1023506832 11.106 99 44 87 3 1046584848 12021184 11056832
02:24:27 6763 8192000000 9971415232 10357551104 1047983632 11.341 100 47 93 2 1087762080 29073856 10704592
02:25:35 5888 8192000000 9982461312 10383958016 1058823120 11.445 100 41 79 5 1085577744 17925520 8829104
02:26:41 6309 8192000000 9984245232 10382569472 1069132784 11.544 100 45 91 2 1088770688 9072192 10565712
02:27:56 85731 8192000000 10022865520 10425843712 1086658512 11.711 100 46 92 2 1124881008 27270000 10952496
02:30:31 6019 8192000000 10099407552 10499821568 1102451664 11.861 100 57 115 3 1202001952 88483888 11066400
02:31:39 9900 8192000000 10128129184 10551201792 1118863184 12.016 100 45 90 2 1238582192 110436032 9282976
02:32:59 16972 8192000000 10127311168 10534051840 1131255376 12.133 100 56 112 0 1250879280 107403648 12220256
02:34:19 9046 8192000000 10148003360 10562269184 1138969536 12.206 100 60 118 1 1256377504 104010304 13397664
02:35:37 6039 8192000000 10208539552 10609258496 1148847856 12.299 100 78 156 0 1307610544 140809984 17952704
02:36:56 1769 8192000000 10237578160 10638196736 1154572656 12.352 100 73 138 2 1328501504 159246608 14682240
02:38:01 3659 8192000000 10246944464 10661195776 1149218016 12.302 99 78 154 1 1340951312 174423472 17309824
02:39:10 2175 8192000000 10263638496 10658201600 1162170016 12.424 100 93 181 0 1357528528 175740288 19618224
02:40:17 2318 8192000000 10261961712 10654949376 1136112304 12.179 98 80 158 0 1349056656 195588096 17356256
02:41:24 4077 8192000000 10318998400 10720428032 1152400096 12.332 100 80 160 2 1417639104 247207312 18031696
02:42:32 4079 8192000000 10318622096 10717683712 1154995280 12.356 99 73 145 0 1415908144 243374576 17538288
02:43:45 3153 8192000000 10416394416 10810781696 1161738608 12.420 100 82 164 0 1512826160 331662800 19424752
02:44:52 2226 8192000000 10426285008 10817458176 1141477632 12.229 99 91 182 1 1517079104 353110912 22490560
02:46:00 2914 8192000000 10450173664 10854637568 1143531552 12.249 100 71 142 0 1550137088 388989328 17616208
02:47:09 9142 8192000000 10435975840 10857713664 1136937168 12.187 99 45 91 0 1527865568 378883424 12044976
02:48:20 11838 8192000000 10441470384 10888990720 1132593040 12.146 99 37 74 0 1539275968 397385968 9296960
02:49:44 23322 8192000000 10372786096 10808094720 1110942304 11.941 98 26 52 2 1445732320 327220848 7569168
02:51:38 46799 8192000000 10285867600 10759491584 1118916272 12.017 100 24 48 1 1456212592 331129872 6166448
02:53:27 45271 8192000000 10122160176 10698248192 1101263680 11.850 99 10 20 2 1236147184 132584944 2298560
02:55:24 40475 8192000000 10055721488 10507784192 1100896208 11.846 98 36 70 4 1120608912 12909296 6803408
02:57:18 125441 8192000000 10052319152 10499735552 1104049376 11.876 98 12 24 0 1143586720 37067104 2470240
03:00:25 124257 8192000000 10068450624 10518511616 1104743248 11.883 98 34 68 1 1154118464 42409648 6965568
03:03:30 53275 8192000000 10073203136 10523127808 1111919824 11.951 99 17 34 0 1149262432 33597792 3744816
03:05:26 27776 8192000000 10077343328 10527838208 1119674352 12.024 99 26 52 3 1181884400 56640368 5569680
03:06:58 49014 8192000000 10054752272 10517438464 1124503104 12.070 98 17 34 0 1151896432 23629984 3763344
03:08:47 49310 8192000000 10095947200 10549690368 1146784336 12.279 100 12 25 1 1200332384 50929440 2618608
03:10:37 50742 8192000000 10087732704 10568167424 1145074096 12.263 100 11 22 1 1198156560 50809872 2272592
03:12:36 49518 8192000000 10090468736 10579935232 1119571296 12.023 98 13 26 2 1175388272 53198384 2618592
03:14:32 58955 8192000000 10140591888 10608271360 1135845360 12.176 99 18 37 0 1226246000 86069312 4331328
03:16:38 68741 8192000000 10156160160 10622210048 1127297824 12.096 97 17 34 1 1223945120 92715456 3931840
03:18:49 78456 8192000000 10178623904 10654482432 1117338272 12.002 97 25 50 1 1270089968 147135648 5616048
03:21:15 49196 8192000000 10111647488 10576883712 1114775344 11.978 97 31 62 1 1170738400 48438272 7524784
03:23:13 26884 8192000000 10063423280 10524037120 1107715008 11.911 97 34 69 2 1169040192 54559872 6765312
03:24:41 53848 8192000000 10046780992 10494193664 1087201504 11.716 97 28 56 2 1138240800 45193264 5846032
03:27:57 46297 8192000000 10045921728 10520375296 1096994208 11.809 98 5 10 0 1124400688 26393328 1013152
03:30:56 55949 8192000000 10077368976 10527289344 1081722864 11.664 96 19 38 0 1160548768 74351008 4474896
03:32:56 61059 8192000000 10049197136 10518405120 1092164512 11.763 99 20 40 1 1156616656 60084368 4367776
03:35:00 56682 8192000000 10088539536 10533904384 1083596656 11.682 97 28 56 1 1158185792 68847744 5741392
03:37:08 34796 8192000000 10083598096 10541985792 1112700080 11.958 100 21 40 3 1189664048 73268368 3695600
03:39:26 79532 8192000000 10155324624 10584072192 1107626576 11.910 98 30 60 0 1232677040 118696208 6354256
03:41:46 55914 8192000000 10071468160 10495143936 1112596416 11.957 99 27 53 1 1162603568 43635840 6371312
03:43:44 94607 8192000000 10073592208 10532192256 1118132832 12.009 99 20 40 0 1150924528 28761440 4030256
03:46:28 47113 8192000000 10108021088 10578202624 1131189952 12.133 100 9 18 1 1207541024 74226800 2124272
03:48:15 86857 8192000000 10098825344 10563272704 1058514768 11.442 94 13 26 0 1161035648 99602320 2918560
03:50:43 50686 8192000000 10102273984 10572132352 1116286928 11.992 99 24 47 0 1187122032 65231520 5603584
03:52:34 86319 8192000000 10106221760 10576060416 1127871344 12.101 100 28 56 1 1241971920 107931328 6169248
03:55:01 50852 8192000000 10070610448 10512289792 1120000432 12.027 99 35 70 1 1166676000 39542800 7132768
03:57:00 53374 8192000000 10090924992 10539745280 1126374320 12.087 99 21 42 1 1195598336 64414688 4809328
03:58:59 39306 8192000000 10058442624 10498650112 1090565824 11.748 96 12 24 0 1128793856 35934496 2293536
04:00:41 119734 8192000000 10092398704 10541957120 1125566528 12.080 100 17 34 1 1150460400 21499952 3393920
04:03:56 66909 8192000000 10111015552 10626560000 1139671520 12.212 100 8 16 0 1211731648 70024320 2035808
04:06:46 43770 8192000000 10071557920 10521083904 1100020064 11.838 96 20 41 1 1146404720 42237760 4146896
04:08:33 42758 8192000000 10119885808 10559950848 1121544624 12.042 98 28 56 1 1182648432 55104848 5998960
04:10:20 65599 8192000000 10131156048 10603442176 1117173376 12.000 97 12 24 1 1189559520 69718560 2667584
04:12:26 68660 8192000000 10091566720 10524864512 1094565264 11.786 96 18 36 1 1139320928 41153888 3601776
04:14:35 31022 8192000000 10058498208 10483245056 1084404688 11.689 96 21 42 3 1138334400 49263184 4666528
04:16:12 20469 8192000000 10107008384 10550796288 1097931648 11.818 98 43 86 1 1173555520 67079808 8544064
04:17:34 127955 8192000000 10049193680 10542481408 1072143680 11.573 96 10 20 0 1141931280 67730064 2057536
04:21:13 42454 8192000000 10043212128 10486534144 1102613712 11.862 99 20 42 0 1141495984 34870144 4012128
04:22:58 82511 8192000000 10053115376 10513559552 1102445632 11.861 99 11 22 1 1133821504 28637648 2738224
04:25:23 77084 8192000000 10066683824 10503405568 1067322720 11.527 95 22 44 1 1136174640 64736704 4115216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2020 04:28:33 to 08-12-2020 04:28:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-12-2020 04:28:49 to 08-12-2020 04:28:51.

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          238533
MON$OLDEST_ACTIVE               241529
MON$OLDEST_SNAPSHOT             241529
MON$NEXT_TRANSACTION            241529
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                       2111744
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             815
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
:125: 
:126: mscs-test-161.budzdorov.ru    Tue Dec  8 04:26:59 2020
:127:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:128: 
:129: 
:130: mscs-test-161.budzdorov.ru    Tue Dec  8 04:27:01 2020
:131:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:132: 
:133: 
:134: mscs-test-161.budzdorov.ru    Tue Dec  8 04:27:01 2020
:135:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:136: 
:137: 
:138: mscs-test-161.budzdorov.ru    Tue Dec  8 04:27:01 2020
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
--- end of diff output ---

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

08.12.20 04:28:52. 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-08 04:28:55 No severe PSQL-related problems occured
08.12.20 04:28:56. End of report.