Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2020 13: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 16967164 kB
MemAvailable 31490020 kB
Buffers 0 kB
Cached 14613840 kB
SwapCached 1976 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-04 13:26:51
DTS_END                         2020-12-04 13:26:51.9520
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/34208
TRN_ID                          212867
ATT_ID                          644
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: 8560.60 25784 1027272
customer order: creation 152.08 451 18250
customer order: refuse 13.56 467 1627
order to supplier: creation 40.38 1501 4845
order to supplier: removal 3.28 132007 393
invoice (draft): creation 40.45 3988 4854
invoice (draft): removal 50.68 988 6082
invoice accept: apply 37.26 19463 4471
invoice accept: cancel 46.23 977 5547
customer reserve: creation 3413.24 188 409589
customer reserve: removal 4499.29 75 539915
realization accept: apply 73.53 211 8823
realization accept: cancel 77.14 117 9257
payment from customer: creation 52.77 140 6332
payment from customer: removal 4.88 114 585
payment to supplier: creation 43.22 143 5186
payment to supplier: removal 3.07 105 368
service: total inventory turnovers 3.20 4416 384
service: total monetary turnovers 5.53 672 663
service: aggregate perf. data 0.81 16303 97
service: refresh index statistics 0.03 333363 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2020 13:28:10 to 04-12-2020 13:28:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5909
TEST_TIME 2 5289
TEST_TIME 3 5253
TEST_TIME 4 4814
TEST_TIME 5 5309
TEST_TIME 6 5214
TEST_TIME 7 5408
TEST_TIME 8 5845
TEST_TIME 9 5899
TEST_TIME 10 6336
TEST_TIME 11 6221
TEST_TIME 12 6357
TEST_TIME 13 6264
TEST_TIME 14 6243
TEST_TIME 15 6337
TEST_TIME 16 6377
TEST_TIME 17 6500
TEST_TIME 18 6517
TEST_TIME 19 6648
TEST_TIME 20 6818
TEST_TIME 21 7253
TEST_TIME 22 7468
TEST_TIME 23 7808
TEST_TIME 24 8120
TEST_TIME 25 8553
TEST_TIME 26 8487
TEST_TIME 27 8655
TEST_TIME 28 9123
TEST_TIME 29 9601
TEST_TIME 30 10827
TEST_TIME 31 10730
TEST_TIME 32 10591
TEST_TIME 33 10324
TEST_TIME 34 10301
TEST_TIME 35 10268
TEST_TIME 36 10127
TEST_TIME 37 9956
TEST_TIME 38 9826
TEST_TIME 39 9700
TEST_TIME 40 9673
TEST_TIME 41 9548
TEST_TIME 42 9485
TEST_TIME 43 9292
TEST_TIME 44 9262
TEST_TIME 45 9180
TEST_TIME 46 9123
TEST_TIME 47 9008
TEST_TIME 48 8957
TEST_TIME 49 8970
TEST_TIME 50 9006
TEST_TIME 51 8959
TEST_TIME 52 8907
TEST_TIME 53 8803
TEST_TIME 54 8704
TEST_TIME 55 8620
TEST_TIME 56 8632
TEST_TIME 57 8580
TEST_TIME 58 8545
TEST_TIME 59 8530
TEST_TIME 60 8492
TEST_TIME 61 8546
TEST_TIME 62 8620
TEST_TIME 63 8745
TEST_TIME 64 8754
TEST_TIME 65 8781
TEST_TIME 66 8928
TEST_TIME 67 8930
TEST_TIME 68 8954
TEST_TIME 69 9042
TEST_TIME 70 8959
TEST_TIME 71 9061
TEST_TIME 72 8947
TEST_TIME 73 8934
TEST_TIME 74 8852
TEST_TIME 75 8761
TEST_TIME 76 8676
TEST_TIME 77 8748
TEST_TIME 78 8723
TEST_TIME 79 8723
TEST_TIME 80 8714
TEST_TIME 81 8744
TEST_TIME 82 8731
TEST_TIME 83 8683
TEST_TIME 84 8739
TEST_TIME 85 8763
TEST_TIME 86 8883
TEST_TIME 87 8843
TEST_TIME 88 8861
TEST_TIME 89 8906
TEST_TIME 90 8825
TEST_TIME 91 8764
TEST_TIME 92 8734
TEST_TIME 93 8670
TEST_TIME 94 8665
TEST_TIME 95 8666
TEST_TIME 96 8684
TEST_TIME 97 8672
TEST_TIME 98 8646
TEST_TIME 99 8792
TEST_TIME 100 8780
TEST_TIME 101 8773
TEST_TIME 102 8771
TEST_TIME 103 8828
TEST_TIME 104 8813
TEST_TIME 105 8841
TEST_TIME 106 8812
TEST_TIME 107 8883
TEST_TIME 108 8924
TEST_TIME 109 8855
TEST_TIME 110 8795
TEST_TIME 111 8768
TEST_TIME 112 8720
TEST_TIME 113 8678
TEST_TIME 114 8627
TEST_TIME 115 8627
TEST_TIME 116 8604
TEST_TIME 117 8601
TEST_TIME 118 8589
TEST_TIME 119 8583
TEST_TIME 120 8567

Performance per minute, chart


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

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 1406527 1406527 0 0.00 0 24169 7 0 0
doc_list_biud 1406524 1406524 0 0.00 0 95236 36 0 0
sp_add_invoice_to_stock 4570 4471 99 2.16 39 184038 19463 0 99
sp_cancel_adding_invoice 5547 5547 0 0.00 5 78081 977 0 0
sp_cancel_client_order 1627 1627 0 0.00 4 26484 468 0 0
sp_cancel_customer_reserve 539915 539915 0 0.00 1 21265 76 0 0
sp_cancel_pay_from_customer 585 585 0 0.00 3 5513 115 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 4176 106 0 0
sp_cancel_supplier_invoice 6092 6082 10 0.16 3 95240 989 0 10
sp_cancel_supplier_order 393 393 0 0.00 7 1128876 132007 0 0
sp_cancel_write_off 9257 9257 0 0.00 1 9971 118 0 0
sp_client_order 18250 18250 0 0.00 4 21697 451 0 0
sp_customer_reserve 409589 409589 0 0.00 0 91060 189 0 0
sp_fill_shopping_cart 27938 27938 0 0.00 1 17299 46 0 0
sp_fill_shopping_cart_clo_res 409580 409580 0 0.00 0 23101 19 0 0
sp_get_clo_for_invoice 4458 4458 0 0.00 0 45573 582 0 0
sp_kill_cost_storno 15757 15757 0 0.00 0 10033 71 0 0
sp_kill_qstorno_ret_qs2qd 1095562 1095562 0 0.00 0 95235 40 0 0
sp_kill_qty_storno 576102 576102 0 0.00 0 95235 88 0 0
sp_lock_selected_doc 18439 18439 0 0.00 0 8107 7 0 0
sp_make_cost_storno 24799 24799 0 0.00 0 23642 65 0 0
sp_multiply_rows_for_pdistr 24799 24799 0 0.00 0 13793 37 0 0
sp_multiply_rows_for_qdistr 18250 18250 0 0.00 0 17393 248 0 0
sp_pay_from_customer 6332 6332 0 0.00 4 24185 141 0 0
sp_pay_to_supplier 5186 5186 0 0.00 4 16156 144 0 0
sp_payment_common 11517 11517 0 0.00 1 18911 28 0 0
sp_qd_handle_on_cancel_clo 1627 1627 0 0.00 0 26476 425 0 0
sp_qd_handle_on_invoice_upd_sts 10005 10005 0 0.00 0 26573 475 0 0
sp_qd_handle_on_reserve_upd_sts 8823 8823 0 0.00 0 10409 57 0 0
sp_reserve_write_off 8823 8823 0 0.00 7 17153 212 0 0
sp_supplier_invoice 4854 4854 0 0.00 7 90755 3988 0 0
sp_supplier_order 4845 4845 0 0.00 6 52380 1502 0 0
srv_aggregate_perf_data 97 97 0 0.00 2283 146325 16304 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1465 144810 48510 0 0
srv_find_qd_qs_mism 985087 985087 0 0.00 0 13247 5 0 0
srv_make_invnt_saldo 384 384 0 0.00 63 103213 4416 0 0
srv_make_money_saldo 663 663 0 0.00 1 20172 673 0 0
srv_recalc_idx_stat 4 4 0 0.00 54303 481148 333364 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4472 4472 0 0.00 0 35000 186 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 10678 121 0 0
t$perf-norm:sp_cancel_client_order 1627 1627 0 0.00 0 6562 16 0 0
t$perf-norm:sp_cancel_customer_reserve 575 575 0 0.00 0 1113 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 585 585 0 0.00 0 617 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 2639 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 338 338 0 0.00 0 996 24 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 20483 1589 0 0
t$perf-norm:sp_cancel_write_off 877 877 0 0.00 0 4993 14 0 0
t$perf-norm:sp_client_order 18250 18250 0 0.00 0 19673 26 0 0
t$perf-norm:sp_customer_reserve 866 866 0 0.00 0 1299 8 0 0
t$perf-norm:sp_pay_from_customer 6332 6332 0 0.00 0 8813 11 0 0
t$perf-norm:sp_pay_to_supplier 5186 5186 0 0.00 0 13138 13 0 0
t$perf-norm:sp_reserve_write_off 8823 8823 0 0.00 0 7442 13 0 0
t$perf-norm:sp_supplier_invoice 4854 4854 0 0.00 0 33113 30 0 0
t$perf-norm:sp_supplier_order 4845 4845 0 0.00 0 7263 22 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 370 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 210 7 0 0
t$perf-norm:srv_make_invnt_saldo 384 384 0 0.00 0 504 10 0 0
t$perf-norm:srv_make_money_saldo 663 663 0 0.00 0 979 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9112 8914 198 2.17 0 6414 21 0 198
v_all_customers 33248 33248 0 0.00 0 18 1 0 0
v_all_suppliers 26792 26792 0 0.00 0 20 1 0 0
v_all_wares 36496 36496 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 5954 22 0 0
v_cancel_client_order 3254 3254 0 0.00 0 6006 8 0 0
v_cancel_customer_prepayment 1170 1170 0 0.00 0 2785 14 0 0
v_cancel_customer_reserve 1150 1150 0 0.00 0 1339 8 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 3905 9 0 0
v_cancel_supplier_invoice 696 676 20 2.87 0 5790 24 0 20
v_cancel_supplier_order 786 786 0 0.00 0 1340 7 0 0
v_cancel_write_off 1754 1754 0 0.00 0 1529 16 0 0
v_max_id_clo_ord 4843 4843 0 0.00 0 1132 2 0 0
v_max_id_clo_res 866 866 0 0.00 0 3348 17 0 0
v_max_id_ord_sup 4846 4846 0 0.00 0 4231 4 0 0
v_max_non_paid_invoice 1479 1479 0 0.00 0 5565 6 0 0
v_max_non_paid_realizn 3384 3384 0 0.00 0 1780 4 0 0
v_min_id_clo_ord 4843 4843 0 0.00 0 3412 6 0 0
v_min_id_clo_res 866 866 0 0.00 0 4914 13 0 0
v_min_id_ord_sup 4846 4846 0 0.00 0 6287 13 0 0
v_min_non_paid_invoice 5186 5186 0 0.00 0 8403 14 0 0
v_min_non_paid_realizn 6331 6331 0 0.00 0 17005 21 0 0
v_our_firm 9142 9142 0 0.00 0 15 1 0 0
v_reserve_write_off 17642 17642 0 0.00 0 7542 9 0 0
x_lock_depdocs_on_canc_invoice 11291 11291 0 0.00 0 19675 123 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 10063 383 0 0
x_make_qstorno_1000_1200 4845 4845 0 0.00 2 52359 1415 0 0
x_make_qstorno_1200_2000 4854 4854 0 0.00 4 90661 3817 0 0
x_make_qstorno_2100_3300 409589 409589 0 0.00 0 91059 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2020 13:28:17 to 04-12-2020 13:28:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:55 8 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:56 23040 8192000000 9713008816 10006994944 791740432 8.813 100 81 163 0 818588352 7216208 19631712
10:59:31 19443 8192000000 9762102272 10068029440 837626832 9.276 100 88 176 0 868868336 10957904 20283600
11:01:04 16512 8192000000 9804128912 10120249344 866889216 9.569 100 85 170 2 907342144 21107600 19345328
11:02:30 9840 8192000000 9864140480 10194956288 911948944 10.017 100 79 158 3 975961744 46677696 17335104
11:03:47 10600 8192000000 9909328448 10250956800 953230768 10.423 100 73 146 1 1019834800 51052416 15551616
11:04:59 7089 8192000000 9953233968 10305744896 991268112 10.794 100 82 164 0 1073138880 64409232 17461536
11:06:09 3092 8192000000 10000158128 10356768768 1025087824 11.121 100 87 174 1 1115512048 73141744 17282480
11:07:14 4940 8192000000 10018464608 10382733312 1031657376 11.184 100 81 161 0 1134367552 86415328 16294848
11:08:20 2631 8192000000 10034230976 10400641024 1052960928 11.389 100 92 181 0 1144151440 72780416 18410096
11:09:24 18000 8192000000 10049600096 10424164352 1070291040 11.555 100 78 156 1 1154203952 68055808 15857104
11:10:44 3911 8192000000 9973844176 10367840256 1076308080 11.612 100 47 93 3 1101000592 13362240 11330272
11:11:50 5396 8192000000 10002944464 10397171712 1085187168 11.697 100 36 74 2 1124030960 30584688 8259104
11:12:56 4696 8192000000 10010965280 10399969280 1088360352 11.727 100 44 85 2 1125139296 27455040 9323904
11:14:01 6972 8192000000 10004069664 10399739904 1096018864 11.800 100 40 81 4 1130157344 24076208 10062272
11:15:10 37401 8192000000 10022517504 10418921472 1098530432 11.824 100 59 119 3 1126009120 14874256 12604432
11:16:48 5897 8192000000 10023156096 10435219456 1100415648 11.842 100 45 86 1 1146934144 37117152 9401344
11:17:55 6373 8192000000 10030515840 10428465152 1093661968 11.777 99 36 72 1 1132600512 31386128 7552416
11:19:03 48321 8192000000 9980707552 10378223616 1044090320 11.304 96 38 74 2 1087665344 36091104 7483920
11:20:54 3430 8192000000 9964419536 10342989824 1002718544 10.905 96 55 109 1 1053182608 38622624 11841440
11:21:58 10972 8192000000 9917795744 10304524288 977239456 10.657 96 30 60 2 998759552 14544640 6975456
11:23:10 5941 8192000000 9957597840 10340999168 1027190400 11.141 99 46 92 4 1061470624 23596048 10684176
11:24:20 39189 8192000000 9954008256 10361782272 1019915248 11.071 98 30 58 0 1048750720 22565312 6270160
11:26:03 6177 8192000000 9983322160 10380034048 1061783712 11.474 100 35 68 2 1091710752 21986560 7940480
11:27:14 17555 8192000000 10024619104 10413805568 1086514256 11.710 100 61 126 3 1128788944 28923040 13351648
11:28:39 9989 8192000000 10055508112 10456555520 1101199008 11.849 100 63 126 2 1167644672 53073728 13371936
11:29:52 36521 8192000000 10053821696 10466910208 1112578704 11.957 100 37 74 0 1170441552 49829360 8033488
11:31:32 6397 8192000000 10116078432 10516242432 1127808928 12.101 100 77 155 2 1218552784 73769904 16973952
11:32:54 25039 8192000000 10128631616 10530783232 1136641232 12.184 100 54 106 1 1231582608 84083344 10858032
11:34:23 27034 8192000000 10171932208 10594304000 1150024304 12.310 100 58 114 0 1270186208 108126016 12035888
11:35:56 4214 8192000000 10194329776 10599088128 1161918224 12.421 100 66 132 0 1293688000 117774352 13995424
11:37:02 10112 8192000000 10218986208 10630156288 1164951584 12.450 100 61 119 1 1320927488 142792240 13183664
11:38:16 15037 8192000000 10241536608 10661994496 1181184368 12.601 100 55 110 1 1340797152 147662320 11950464
11:39:40 3003 8192000000 10268780784 10682920960 1181360656 12.603 100 69 138 2 1372725296 175971728 15392912
11:40:48 3532 8192000000 10323100880 10725621760 1178265488 12.574 99 78 156 1 1421555696 225153280 18136928
11:41:56 4436 8192000000 10381795200 10789326848 1197066272 12.749 100 84 167 0 1482911856 266307488 19538096
11:43:06 4498 8192000000 10429924000 10852044800 1207831136 12.849 100 75 150 2 1544735776 319224496 17680144
11:44:13 1460 8192000000 10422850048 10825875456 1169501008 12.492 99 78 152 1 1525705696 337079008 19125680
11:45:22 4034 8192000000 10497254400 10912681984 1184030128 12.628 100 78 156 1 1609968368 407120400 18817840
11:46:32 4057 8192000000 10533733488 10945937408 1173756592 12.532 99 82 164 1 1625919264 432222320 19940352
11:47:48 2691 8192000000 10518947824 10937503744 1183069872 12.619 100 88 176 0 1622905712 417719728 22116112
11:48:55 5027 8192000000 10520706304 10943512576 1162901120 12.430 99 52 104 1 1612951920 436920112 13130688
11:50:05 12719 8192000000 10491158880 10951938048 1173078288 12.526 100 40 80 1 1599376592 415794800 10503504
11:51:27 28822 8192000000 10474237792 10953302016 1173313632 12.528 100 36 72 0 1605600320 422702800 9583888
11:52:57 23725 8192000000 10517833280 10970120192 1142249280 12.237 99 30 60 0 1589315856 438547808 8518768
11:54:23 23339 8192000000 10451139920 10926977024 1146012224 12.272 100 25 50 2 1567698528 414726096 6960208
11:56:20 38529 8192000000 10105295776 10664001536 1119321008 12.021 98 11 22 0 1308617232 186777328 2518896
11:58:00 38625 8192000000 10164427840 10623418368 1151850720 12.327 100 32 64 0 1227309840 68835888 6623232
11:59:51 72222 8192000000 10098702128 10550644736 1107760464 11.911 97 30 58 1 1187875632 73942896 6172272
12:02:05 102611 8192000000 10064276464 10509701120 1117652544 12.005 99 24 47 0 1163574880 40947040 4975296
12:04:48 53714 8192000000 10082411776 10514120704 1110918608 11.941 98 27 54 1 1167842816 50940496 5983712
12:06:43 42726 8192000000 10057901696 10480717824 1117100112 12.000 99 38 76 2 1143681856 18509168 8072576
12:08:28 83254 8192000000 10077737856 10527776768 1120457728 12.031 99 16 32 0 1175885168 52094576 3332864
12:11:08 78620 8192000000 10071011888 10512916480 1122984128 12.055 99 24 46 3 1177538784 49918768 4635888
12:13:28 23539 8192000000 10096871728 10553356288 1136428432 12.182 100 23 46 1 1203078384 61781376 4868576
12:14:53 38405 8192000000 10097026384 10565877760 1114843616 11.978 98 30 55 1 1181259904 60362144 6054144
12:16:33 71157 8192000000 10032457968 10512949248 1098190784 11.820 97 12 23 0 1131728224 30778496 2758944
12:18:45 77413 8192000000 10037221328 10485264384 1104120864 11.877 98 11 22 0 1132753408 25895632 2736912
12:21:02 67378 8192000000 10066300544 10518646784 1131372048 12.134 100 15 30 0 1187471056 53371856 2727152
12:23:12 70364 8192000000 10045627952 10532581376 1119890352 12.026 99 3 6 0 1145491456 24789104 812000
12:25:23 91391 8192000000 10127528752 10575147008 1125223488 12.076 98 16 31 3 1215045456 86242288 3579680
12:28:00 60504 8192000000 10148046912 10628763648 1135702528 12.175 99 23 46 2 1256474912 115988400 4783984
12:30:01 87396 8192000000 10138964016 10616102912 1120906192 12.036 97 19 38 1 1208289408 83561312 3821904
12:32:32 124904 8192000000 10158698272 10641444864 1099231392 11.830 96 17 34 1 1155350848 52835552 3283904
12:35:38 53950 8192000000 10098653920 10520821760 1124283472 12.067 98 36 72 1 1152712880 21499968 6929440
12:37:33 144808 8192000000 10078743520 10611240960 1115011536 11.980 98 17 34 0 1173500208 54921440 3567232
12:41:08 66826 8192000000 10100681408 10573385728 1143186240 12.245 100 5 10 0 1216376096 72075280 1114576
12:43:56 53348 8192000000 10054177888 10521870336 1073623344 11.587 95 9 18 0 1115755264 40539136 1592784
12:45:49 62235 8192000000 10050019840 10494668800 1073889760 11.589 96 20 40 0 1129768800 51630320 4248720
12:47:52 62215 8192000000 10039385184 10479845376 1065612080 11.510 96 29 58 0 1134899648 63128160 6159408
12:49:54 98132 8192000000 10106275168 10623799296 1122965344 12.055 100 19 38 0 1261738384 134195824 4577216
12:52:35 54302 8192000000 10065688608 10488332288 1120146000 12.028 100 23 45 0 1158247696 33351504 4750192
12:54:33 23591 8192000000 10093842160 10540306432 1122764912 12.053 100 32 62 1 1197502352 68685632 6051808
12:56:00 124407 8192000000 10072471008 10534244352 1118324784 12.011 99 19 38 2 1172612144 50430128 3857232
12:59:19 61493 8192000000 10083705536 10543190016 1124964720 12.074 99 9 18 1 1185908944 59433792 1510432
13:01:21 83064 8192000000 10067324960 10527014912 1106774496 11.902 97 13 26 0 1159550864 50199584 2576784
13:03:51 33423 8192000000 10082840384 10520137728 1099172288 11.830 97 21 42 0 1149340688 45691264 4477136
13:05:33 97476 8192000000 10094862048 10577862656 1096309552 11.803 97 9 18 0 1176456688 78124208 2022928
13:08:11 62210 8192000000 10098665792 10544852992 1096589648 11.805 97 14 28 0 1148466448 48936448 2940352
13:10:19 69039 8192000000 10080614752 10534318080 1112699776 11.958 98 22 42 1 1151745520 34369344 4676400
13:12:31 75527 8192000000 10138422784 10657378304 1127845424 12.101 99 21 42 1 1213401184 81129328 4426432
13:16:21 81894 8192000000 10080192848 10571304960 1140641808 12.222 100 24 48 0 1183798560 38228112 4928640
13:18:45 51248 8192000000 10069021600 10551181312 1122676272 12.052 98 9 17 1 1159527584 35444640 1406672
13:22:08 70401 8192000000 10072265712 10518265856 1089918752 11.742 96 21 42 1 1135108816 40350192 4839872
13:24:27 36531 8192000000 10092238816 10543505408 1114456384 11.975 99 32 64 5 1166040976 44629664 6954928
13:26:11 45453 8192000000 9659738736 10047909888 736150448 8.245 65 25 48 1 818490512 76484960 5855104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-12-2020 13:28:23 to 04-12-2020 13:28:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2020 13:28:39 to 04-12-2020 13:28:41.

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          215343
MON$OLDEST_ACTIVE               215344
MON$OLDEST_SNAPSHOT             215344
MON$NEXT_TRANSACTION            215344
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                       2085280
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             770
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2288 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2288 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2288 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.2288 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 ---
--- end of diff output ---

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

04.12.20 13:28:42. 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-04 13:28:44 No severe PSQL-related problems occured
04.12.20 13:28:45. End of report.