Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2020 10: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 16958720 kB
MemAvailable 31331796 kB
Buffers 0 kB
Cached 14594480 kB
SwapCached 3700 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-25 10:26:55
DTS_END                         2020-12-25 10:26:55.0740
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/53382
TRN_ID                          226751
ATT_ID                          624
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: 8639.33 18947 1036719
customer order: creation 160.78 500 19294
customer order: refuse 13.73 323 1648
order to supplier: creation 42.19 1248 5063
order to supplier: removal 3.23 115156 388
invoice (draft): creation 43.88 3572 5265
invoice (draft): removal 49.73 785 5968
invoice accept: apply 39.16 16434 4699
invoice accept: cancel 44.68 837 5362
customer reserve: creation 3609.84 159 433181
customer reserve: removal 4354.43 70 522532
realization accept: apply 79.32 182 9518
realization accept: cancel 80.21 123 9625
payment from customer: creation 54.72 119 6566
payment from customer: removal 5.55 91 666
payment to supplier: creation 44.28 143 5313
payment to supplier: removal 3.13 64 375
service: total inventory turnovers 3.72 2851 446
service: total monetary turnovers 5.89 377 707
service: aggregate perf. data 0.83 16601 99
service: refresh index statistics 0.03 219308 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-12-2020 10:27:48 to 25-12-2020 10:27:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1554
TEST_TIME 2 3200
TEST_TIME 3 2701
TEST_TIME 4 2769
TEST_TIME 5 3053
TEST_TIME 6 3397
TEST_TIME 7 3643
TEST_TIME 8 4177
TEST_TIME 9 4440
TEST_TIME 10 4973
TEST_TIME 11 5235
TEST_TIME 12 6011
TEST_TIME 13 6355
TEST_TIME 14 6870
TEST_TIME 15 7004
TEST_TIME 16 7209
TEST_TIME 17 7538
TEST_TIME 18 8429
TEST_TIME 19 8349
TEST_TIME 20 8563
TEST_TIME 21 9054
TEST_TIME 22 10224
TEST_TIME 23 11214
TEST_TIME 24 10990
TEST_TIME 25 10940
TEST_TIME 26 10745
TEST_TIME 27 10441
TEST_TIME 28 10223
TEST_TIME 29 10281
TEST_TIME 30 10257
TEST_TIME 31 10191
TEST_TIME 32 10106
TEST_TIME 33 10087
TEST_TIME 34 9819
TEST_TIME 35 9700
TEST_TIME 36 9671
TEST_TIME 37 9670
TEST_TIME 38 9530
TEST_TIME 39 9402
TEST_TIME 40 9326
TEST_TIME 41 9255
TEST_TIME 42 9105
TEST_TIME 43 8991
TEST_TIME 44 8979
TEST_TIME 45 9022
TEST_TIME 46 8953
TEST_TIME 47 8935
TEST_TIME 48 8925
TEST_TIME 49 8985
TEST_TIME 50 9039
TEST_TIME 51 9259
TEST_TIME 52 9183
TEST_TIME 53 9151
TEST_TIME 54 9063
TEST_TIME 55 9175
TEST_TIME 56 9410
TEST_TIME 57 9337
TEST_TIME 58 9222
TEST_TIME 59 9139
TEST_TIME 60 9088
TEST_TIME 61 9014
TEST_TIME 62 9012
TEST_TIME 63 8965
TEST_TIME 64 8952
TEST_TIME 65 8917
TEST_TIME 66 8900
TEST_TIME 67 8941
TEST_TIME 68 9018
TEST_TIME 69 8930
TEST_TIME 70 9060
TEST_TIME 71 9023
TEST_TIME 72 8975
TEST_TIME 73 9055
TEST_TIME 74 9068
TEST_TIME 75 9083
TEST_TIME 76 9064
TEST_TIME 77 8982
TEST_TIME 78 8888
TEST_TIME 79 8920
TEST_TIME 80 8870
TEST_TIME 81 8859
TEST_TIME 82 8801
TEST_TIME 83 8867
TEST_TIME 84 8826
TEST_TIME 85 8827
TEST_TIME 86 8932
TEST_TIME 87 8909
TEST_TIME 88 8903
TEST_TIME 89 8931
TEST_TIME 90 8974
TEST_TIME 91 8943
TEST_TIME 92 8928
TEST_TIME 93 8838
TEST_TIME 94 8826
TEST_TIME 95 8778
TEST_TIME 96 8757
TEST_TIME 97 8719
TEST_TIME 98 8699
TEST_TIME 99 8732
TEST_TIME 100 8729
TEST_TIME 101 8732
TEST_TIME 102 8687
TEST_TIME 103 8678
TEST_TIME 104 8729
TEST_TIME 105 8810
TEST_TIME 106 8793
TEST_TIME 107 8853
TEST_TIME 108 8864
TEST_TIME 109 8826
TEST_TIME 110 8752
TEST_TIME 111 8722
TEST_TIME 112 8688
TEST_TIME 113 8678
TEST_TIME 114 8662
TEST_TIME 115 8641
TEST_TIME 116 8623
TEST_TIME 117 8650
TEST_TIME 118 8651
TEST_TIME 119 8658
TEST_TIME 120 8647

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2020 10:27:52 to 25-12-2020 10:27:55.

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 1439144 1439144 0 0.00 0 21468 6 0 0
doc_list_biud 1439140 1439140 0 0.00 0 94222 31 0 0
sp_add_invoice_to_stock 4789 4699 90 1.87 23 246880 16435 0 90
sp_cancel_adding_invoice 5362 5362 0 0.00 5 71029 838 0 0
sp_cancel_client_order 1648 1648 0 0.00 4 18500 323 0 0
sp_cancel_customer_reserve 522532 522532 0 0.00 1 70538 70 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 9861 91 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 1210 65 0 0
sp_cancel_supplier_invoice 5973 5968 5 0.08 2 94231 786 0 5
sp_cancel_supplier_order 388 388 0 0.00 9 1043932 115156 0 0
sp_cancel_write_off 9625 9625 0 0.00 1 8378 123 0 0
sp_client_order 19294 19294 0 0.00 4 34548 500 0 0
sp_customer_reserve 433181 433181 0 0.00 0 100944 160 0 0
sp_fill_shopping_cart 29605 29605 0 0.00 1 27587 51 0 0
sp_fill_shopping_cart_clo_res 433168 433168 0 0.00 0 27506 22 0 0
sp_get_clo_for_invoice 4684 4684 0 0.00 0 57580 592 0 0
sp_kill_cost_storno 16028 16028 0 0.00 0 6205 57 0 0
sp_kill_qstorno_ret_qs2qd 1061045 1061045 0 0.00 0 94220 37 0 0
sp_kill_qty_storno 559725 559725 0 0.00 0 94221 79 0 0
sp_lock_selected_doc 19661 19661 0 0.00 0 15475 7 0 0
sp_make_cost_storno 26081 26081 0 0.00 0 14991 58 0 0
sp_multiply_rows_for_pdistr 26081 26081 0 0.00 0 9437 31 0 0
sp_multiply_rows_for_qdistr 19294 19294 0 0.00 0 34529 318 0 0
sp_pay_from_customer 6566 6566 0 0.00 4 16448 120 0 0
sp_pay_to_supplier 5313 5313 0 0.00 5 16452 144 0 0
sp_payment_common 11879 11879 0 0.00 1 10448 31 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 18494 288 0 0
sp_qd_handle_on_invoice_upd_sts 10046 10046 0 0.00 0 21266 355 0 0
sp_qd_handle_on_reserve_upd_sts 9518 9518 0 0.00 0 14421 50 0 0
sp_reserve_write_off 9518 9518 0 0.00 7 21774 182 0 0
sp_supplier_invoice 5265 5265 0 0.00 6 107221 3572 0 0
sp_supplier_order 5063 5063 0 0.00 5 50389 1249 0 0
srv_aggregate_perf_data 99 99 0 0.00 2595 237622 16602 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1930 144463 53741 0 0
srv_find_qd_qs_mism 992986 992986 0 0.00 0 21467 5 0 0
srv_make_invnt_saldo 446 446 0 0.00 39 87333 2851 0 0
srv_make_money_saldo 707 707 0 0.00 1 9009 377 0 0
srv_recalc_idx_stat 4 4 0 0.00 74627 315987 219308 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4699 4699 0 0.00 0 9938 140 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3670 87 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 1560 8 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 1188 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 2918 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 4366 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 3695 21 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 20977 1312 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 4517 14 0 0
t$perf-norm:sp_client_order 19294 19294 0 0.00 0 6483 15 0 0
t$perf-norm:sp_customer_reserve 885 885 0 0.00 0 771 4 0 0
t$perf-norm:sp_pay_from_customer 6566 6566 0 0.00 0 5736 10 0 0
t$perf-norm:sp_pay_to_supplier 5313 5313 0 0.00 0 3604 7 0 0
t$perf-norm:sp_reserve_write_off 9518 9518 0 0.00 0 8341 11 0 0
t$perf-norm:sp_supplier_invoice 5265 5265 0 0.00 0 4142 13 0 0
t$perf-norm:sp_supplier_order 5063 5063 0 0.00 0 6459 13 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 119 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 5213 88 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 292 3 0 0
t$perf-norm:srv_make_money_saldo 707 707 0 0.00 0 3236 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9548 9368 180 1.88 0 10335 16 0 180
v_all_customers 35112 35112 0 0.00 0 169 1 0 0
v_all_suppliers 28418 28418 0 0.00 0 100 1 0 0
v_all_wares 38588 38588 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1213 9 0 0
v_cancel_client_order 3296 3296 0 0.00 0 5638 7 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 631 5 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 0 1488 5 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 956 5 0 0
v_cancel_supplier_invoice 842 832 10 1.18 0 587 12 0 10
v_cancel_supplier_order 776 776 0 0.00 0 840 4 0 0
v_cancel_write_off 1894 1894 0 0.00 0 765 11 0 0
v_max_id_clo_ord 5059 5059 0 0.00 0 786 1 0 0
v_max_id_clo_res 885 885 0 0.00 0 7419 26 0 0
v_max_id_ord_sup 5251 5251 0 0.00 0 6632 7 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 3757 4 0 0
v_max_non_paid_realizn 3435 3435 0 0.00 0 5335 6 0 0
v_min_id_clo_ord 5059 5059 0 0.00 0 5406 7 0 0
v_min_id_clo_res 885 885 0 0.00 0 2611 12 0 0
v_min_id_ord_sup 5251 5251 0 0.00 0 6039 10 0 0
v_min_non_paid_invoice 5313 5313 0 0.00 0 9393 26 0 0
v_min_non_paid_realizn 6566 6566 0 0.00 0 7591 20 0 0
v_our_firm 9738 9738 0 0.00 0 17 1 0 0
v_reserve_write_off 19036 19036 0 0.00 0 2971 5 0 0
x_lock_depdocs_on_canc_invoice 10914 10914 0 0.00 0 10363 103 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 3954 296 0 0
x_make_qstorno_1000_1200 5063 5063 0 0.00 2 50381 1181 0 0
x_make_qstorno_1200_2000 5265 5265 0 0.00 3 106945 3362 0 0
x_make_qstorno_2100_3300 433181 433181 0 0.00 0 100943 136 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2020 10:27:56 to 25-12-2020 10:28:00.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:58 1 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:00 32908 8192000000 9722786160 10022359040 802433312 8.921 100 87 174 0 831687440 7644128 21610000
07:59:36 12871 8192000000 9762680800 10071724032 841211408 9.312 100 86 173 0 874131712 12675552 20244752
08:00:52 19976 8192000000 9799829360 10117193728 862279056 9.523 100 77 154 1 901957936 22196032 17482848
08:02:15 14825 8192000000 9863607536 10194112512 911323856 10.010 100 78 156 1 975676480 47926656 16425968
08:03:39 8696 8192000000 9915776640 10261262336 953045504 10.421 100 75 147 1 1033204624 65296272 14862848
08:04:53 4516 8192000000 9958303104 10309267456 989946736 10.781 100 83 166 1 1075000640 67885040 17168864
08:06:00 2422 8192000000 9993533200 10354528256 1025474000 11.125 100 84 168 3 1100429584 58098928 16856656
08:07:04 1646 8192000000 10019577920 10386145280 1055239856 11.411 100 89 178 2 1138393968 64608624 18545488
08:08:07 1398 8192000000 10042499600 10412113920 1065411072 11.508 100 90 180 1 1146522992 62938320 18173600
08:09:09 5746 8192000000 9997376704 10399498240 1074109168 11.591 100 25 52 1 1129690160 49612832 5968160
08:10:16 46247 8192000000 10009222304 10423971840 1077331760 11.622 100 35 70 2 1114118640 28130752 8656128
08:12:06 6205 8192000000 10011273888 10402684928 1081865888 11.665 100 39 75 3 1116828368 27128960 7833520
08:13:15 5451 8192000000 10010721296 10409234432 1084811024 11.693 100 33 66 1 1131393376 38897440 7684912
08:14:21 64179 8192000000 10031268112 10444652544 1091757008 11.759 100 36 70 2 1122642016 23227200 7657808
08:16:28 7561 8192000000 10016129344 10409185280 1073546672 11.586 98 37 73 2 1105453296 23457216 8449408
08:17:36 6179 8192000000 9986132448 10376429568 1029197920 11.161 95 33 66 1 1061744992 25648224 6898848
08:18:44 66887 8192000000 9957633616 10358472704 1017006128 11.043 96 19 38 2 1045180544 24122064 4052352
08:20:56 8479 8192000000 9951111152 10335842304 1017249472 11.045 99 56 113 4 1049780336 19054272 13476592
08:22:05 5035 8192000000 9945114336 10321080320 1022286016 11.094 100 49 97 1 1046213056 12338032 11589008
08:23:11 4891 8192000000 9961667632 10356559872 1046850288 11.330 100 30 60 2 1081988720 28328432 6810000
08:24:17 27423 8192000000 9962870208 10370314240 1047152384 11.333 99 21 42 2 1065826208 14217728 4456096
08:25:48 5123 8192000000 9989888656 10382991360 1066770512 11.521 100 47 95 3 1099009936 21534304 10705120
08:26:54 69977 8192000000 10029693648 10423992320 1094300032 11.784 100 38 76 2 1128606016 25645760 8660224
08:29:09 80780 8192000000 10113639264 10525528064 1125134592 12.075 100 35 70 0 1196923920 63960528 7828800
08:31:38 21361 8192000000 10150979664 10560061440 1143936048 12.253 100 35 70 2 1260362000 108754816 7671136
08:33:09 18043 8192000000 10214332448 10629582848 1156170432 12.367 100 52 104 1 1323410512 155402784 11837296
08:34:31 9436 8192000000 10266289776 10685075456 1169110224 12.489 100 51 101 1 1367568448 186874800 11583424
08:35:46 3660 8192000000 10316310352 10722668544 1188458768 12.669 100 71 136 1 1418485040 214583072 15443200
08:36:54 2344 8192000000 10334638384 10737278976 1190848240 12.691 100 70 138 2 1434467312 227643600 15975472
08:38:01 4310 8192000000 10375435296 10812686336 1193536640 12.716 100 70 140 2 1492012960 281627936 16848384
08:39:10 1928 8192000000 10402484256 10815766528 1183861632 12.626 100 76 153 0 1497999376 296437584 17700160
08:40:18 4464 8192000000 10411543776 10818007040 1187159904 12.657 99 85 170 0 1507015168 300676384 19178880
08:41:27 3445 8192000000 10456642784 10868858880 1183947904 12.627 99 67 133 2 1545475776 345876416 15651456
08:42:33 3261 8192000000 10460946864 10887364608 1177685744 12.569 100 55 108 2 1567375008 376509152 13180112
08:43:41 15414 8192000000 10469378672 10894831616 1176290432 12.556 100 44 88 0 1560242544 372472592 11479520
08:45:07 21129 8192000000 10426395088 10860474368 1168108496 12.479 100 32 64 0 1527765104 351388304 8268304
08:46:32 23754 8192000000 10455252944 10907631616 1149878128 12.308 99 33 66 1 1545287792 386640688 8768976
08:48:01 41321 8192000000 10271668480 10845515776 1087903776 11.723 97 18 36 0 1410885872 317864912 5117184
08:49:46 54598 8192000000 10070882912 10517680128 1110376832 11.936 99 19 38 2 1177029440 62769408 3883200
08:51:46 31729 8192000000 10079312784 10525339648 1099245104 11.830 98 13 26 1 1155180032 53019504 2915424
08:53:18 87773 8192000000 10144689760 10575376384 1095457232 11.795 97 27 53 0 1197456528 96103488 5895808
08:55:56 80915 8192000000 10049796432 10484908032 1104414240 11.880 99 14 28 1 1196015312 88574368 3026704
08:58:17 74709 8192000000 10082772864 10542972928 1089886208 11.742 96 21 42 1 1158339248 64159872 4293168
09:00:48 50393 8192000000 10061095632 10525601792 1105105136 11.886 99 37 74 1 1170174976 57130592 7939248
09:02:45 65881 8192000000 10044604000 10512306176 1113654512 11.967 100 25 48 4 1162187312 43153888 5378912
09:04:52 50162 8192000000 10065571952 10518515712 1128618528 12.108 100 9 18 0 1183020256 52053232 2348496
09:07:12 71505 8192000000 10037306192 10480451584 1122953760 12.055 100 22 40 0 1148286832 21042096 4290976
09:09:25 68101 8192000000 10097556096 10545868800 1120829840 12.035 99 15 30 0 1181651680 57719280 3102560
09:11:33 47200 8192000000 10046869936 10495758336 1107079296 11.905 98 13 27 1 1176116032 66237904 2798832
09:13:21 34255 8192000000 10095547040 10534285312 1118798192 12.016 99 19 38 0 1206088304 82528816 4761296
09:14:58 55016 8192000000 10134920624 10598957056 1138396656 12.200 100 18 36 1 1226259920 83857504 4005760
09:16:55 79756 8192000000 10069557984 10563436544 1140796848 12.223 100 11 22 0 1238647088 95398704 2451536
09:19:15 26396 8192000000 10120247008 10558210048 1119824544 12.025 98 39 77 0 1195545008 67985872 7734592
09:20:42 45095 8192000000 10133685248 10600591360 1147955008 12.290 100 27 54 2 1275319792 121693424 5671360
09:22:46 47149 8192000000 10071283280 10517508096 1132504320 12.145 99 7 14 1 1176650096 42634640 1511136
09:24:37 118178 8192000000 10044606976 10513391616 1110076880 11.933 97 8 16 0 1134224416 22926720 1220816
09:27:57 78814 8192000000 10060749216 10551869440 1107349904 11.907 97 6 12 0 1149403648 41023440 1030304
09:30:16 46912 8192000000 10097545136 10545262592 1128102544 12.103 99 19 38 1 1164351488 32463760 3785184
09:32:09 35718 8192000000 10148998848 10598383616 1136588944 12.183 99 35 68 0 1221201968 77013200 7599824
09:33:59 72580 8192000000 10111612496 10547318784 1094773200 11.788 95 16 33 1 1151114208 53169232 3171776
09:36:17 88280 8192000000 10066491440 10483200000 1073281232 11.583 95 28 56 1 1147103200 67720864 6101104
09:38:46 94761 8192000000 10086678480 10549444608 1083712784 11.683 96 26 52 0 1175341440 85950144 5678512
09:41:27 64959 8192000000 10047119408 10482278400 1058749024 11.445 94 11 22 2 1097723104 36837568 2136512
09:43:36 144463 8192000000 10057793984 10547929088 1104181968 11.877 98 20 40 0 1207018240 98413312 4422960
09:47:18 42293 8192000000 10046869072 10522025984 1082239984 11.669 98 4 8 0 1125867696 43095936 531776
09:49:00 56516 8192000000 10068155536 10563031040 1113923120 11.970 99 25 50 0 1184494528 65080160 5491248
09:51:01 60476 8192000000 10155279536 10631249920 1123191936 12.057 99 39 77 3 1208880528 76293920 9394672
09:53:48 33914 8192000000 10062127920 10500333568 1108003104 11.914 99 22 43 2 1134735392 22140128 4592160
09:55:24 89252 8192000000 10067918480 10541305856 1088043568 11.724 97 26 52 2 1148801664 55693616 5064480
09:58:10 76472 8192000000 10078427392 10512900096 1106329824 11.898 98 31 61 4 1161438672 49027936 6080912
10:01:19 67081 8192000000 10050583984 10544226304 1111503344 11.947 98 5 10 0 1158279952 45700560 1076048
10:03:34 76760 8192000000 10094582800 10538086400 1117100656 12.000 98 16 32 1 1180251552 59731872 3419024
10:05:54 60987 8192000000 10069000208 10515419136 1113785520 11.968 98 18 36 1 1143162896 25738768 3638608
10:08:03 36455 8192000000 10090479424 10522480640 1143684016 12.250 100 30 61 1 1180107712 29459760 6963936
10:09:40 115759 8192000000 10115027584 10604425216 1139832544 12.214 99 23 46 1 1237255776 92341872 5081360
10:12:49 112967 8192000000 10091689312 10572337152 1126362000 12.087 98 9 18 0 1182937104 54579120 1995984
10:15:42 117452 8192000000 10083183456 10545852416 1098063856 11.819 96 12 24 0 1141698480 41328640 2305984
10:18:50 48960 8192000000 10062185152 10556985344 1131004368 12.131 100 5 10 0 1180823072 48616000 1202704
10:20:39 43181 8192000000 10055895344 10502750208 1112472496 11.956 98 21 42 1 1139255040 21918944 4863600
10:22:27 40606 8192000000 10111173104 10549616640 1140527312 12.220 100 36 71 4 1202547936 54649568 7371056
10:24:18 76787 8192000000 10058817040 10485633024 1073161472 11.582 94 20 40 0 1135029056 57949376 3918208
10:26:36 28439 8192000000 9060466224 9306681344 208608624 2.483 19 4 8 0 225037632 15888128 540880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2020 10:28:02 to 25-12-2020 10:28:17.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-12-2020 10:28:18 to 25-12-2020 10:28:20.

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          225977
MON$OLDEST_ACTIVE               227358
MON$OLDEST_SNAPSHOT             227358
MON$NEXT_TRANSACTION            227358
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                       2066912
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             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2303 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2303 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.2303 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Fri Dec 25 10:27:12 2020
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
--- end of diff output ---

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

25.12.20 10:28:21. 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-25 10:28:23 No severe PSQL-related problems occured
25.12.20 10:28:25. End of report.