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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17000748 kB
MemAvailable 31513144 kB
Buffers 20 kB
Cached 14576800 kB
SwapCached 1968 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 04:26:55
DTS_END                         2020-12-04 04:26:55.9190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56630
TRN_ID                          223594
ATT_ID                          596
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: 8514.42 21217 1021731
customer order: creation 161.95 411 19434
customer order: refuse 14.34 390 1721
order to supplier: creation 42.48 1405 5098
order to supplier: removal 3.36 114201 403
invoice (draft): creation 43.07 3956 5168
invoice (draft): removal 49.84 834 5981
invoice accept: apply 37.73 20828 4527
invoice accept: cancel 44.74 854 5369
customer reserve: creation 3447.48 206 413697
customer reserve: removal 4393.91 69 527269
realization accept: apply 78.25 181 9390
realization accept: cancel 75.88 126 9106
payment from customer: creation 55.83 121 6699
payment from customer: removal 5.24 88 629
payment to supplier: creation 46.78 138 5613
payment to supplier: removal 3.33 93 399
service: total inventory turnovers 3.52 3834 422
service: total monetary turnovers 5.72 616 686
service: aggregate perf. data 0.95 12873 114
service: refresh index statistics 0.05 263125 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-12-2020 04:29:48 to 04-12-2020 04:29:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2355
TEST_TIME 2 4333
TEST_TIME 3 4616
TEST_TIME 4 4756
TEST_TIME 5 4741
TEST_TIME 6 4888
TEST_TIME 7 5037
TEST_TIME 8 5095
TEST_TIME 9 5619
TEST_TIME 10 5718
TEST_TIME 11 5827
TEST_TIME 12 6044
TEST_TIME 13 6321
TEST_TIME 14 6684
TEST_TIME 15 6709
TEST_TIME 16 6846
TEST_TIME 17 7052
TEST_TIME 18 7119
TEST_TIME 19 7721
TEST_TIME 20 8441
TEST_TIME 21 8349
TEST_TIME 22 8575
TEST_TIME 23 8615
TEST_TIME 24 8727
TEST_TIME 25 8850
TEST_TIME 26 9090
TEST_TIME 27 9844
TEST_TIME 28 9957
TEST_TIME 29 10689
TEST_TIME 30 10433
TEST_TIME 31 10115
TEST_TIME 32 9934
TEST_TIME 33 9994
TEST_TIME 34 9915
TEST_TIME 35 9816
TEST_TIME 36 9706
TEST_TIME 37 9611
TEST_TIME 38 9768
TEST_TIME 39 9553
TEST_TIME 40 9423
TEST_TIME 41 9570
TEST_TIME 42 9416
TEST_TIME 43 9279
TEST_TIME 44 9164
TEST_TIME 45 9115
TEST_TIME 46 9076
TEST_TIME 47 9014
TEST_TIME 48 9000
TEST_TIME 49 9070
TEST_TIME 50 9101
TEST_TIME 51 9128
TEST_TIME 52 9132
TEST_TIME 53 9038
TEST_TIME 54 9110
TEST_TIME 55 9064
TEST_TIME 56 9154
TEST_TIME 57 9122
TEST_TIME 58 9231
TEST_TIME 59 9222
TEST_TIME 60 9214
TEST_TIME 61 9247
TEST_TIME 62 9248
TEST_TIME 63 9144
TEST_TIME 64 9015
TEST_TIME 65 8932
TEST_TIME 66 8942
TEST_TIME 67 8900
TEST_TIME 68 8849
TEST_TIME 69 8764
TEST_TIME 70 8789
TEST_TIME 71 8738
TEST_TIME 72 8717
TEST_TIME 73 8700
TEST_TIME 74 8712
TEST_TIME 75 8643
TEST_TIME 76 8689
TEST_TIME 77 8774
TEST_TIME 78 8898
TEST_TIME 79 8979
TEST_TIME 80 8921
TEST_TIME 81 8902
TEST_TIME 82 8859
TEST_TIME 83 8883
TEST_TIME 84 8902
TEST_TIME 85 8814
TEST_TIME 86 8736
TEST_TIME 87 8674
TEST_TIME 88 8607
TEST_TIME 89 8568
TEST_TIME 90 8562
TEST_TIME 91 8595
TEST_TIME 92 8582
TEST_TIME 93 8551
TEST_TIME 94 8511
TEST_TIME 95 8607
TEST_TIME 96 8579
TEST_TIME 97 8598
TEST_TIME 98 8595
TEST_TIME 99 8614
TEST_TIME 100 8618
TEST_TIME 101 8669
TEST_TIME 102 8648
TEST_TIME 103 8578
TEST_TIME 104 8562
TEST_TIME 105 8532
TEST_TIME 106 8487
TEST_TIME 107 8474
TEST_TIME 108 8470
TEST_TIME 109 8479
TEST_TIME 110 8470
TEST_TIME 111 8452
TEST_TIME 112 8430
TEST_TIME 113 8478
TEST_TIME 114 8499
TEST_TIME 115 8471
TEST_TIME 116 8510
TEST_TIME 117 8527
TEST_TIME 118 8534
TEST_TIME 119 8540
TEST_TIME 120 8516

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-12-2020 04:29:52 to 04-12-2020 04:29: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 1406450 1406450 0 0.00 0 48733 8 0 0
doc_list_biud 1406449 1406449 0 0.00 0 80582 33 0 0
sp_add_invoice_to_stock 4597 4527 70 1.52 28 206063 20828 0 70
sp_cancel_adding_invoice 5369 5369 0 0.00 5 66397 854 0 0
sp_cancel_client_order 1721 1721 0 0.00 4 15344 391 0 0
sp_cancel_customer_reserve 527269 527269 0 0.00 1 80583 70 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 2914 88 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 2 4866 93 0 0
sp_cancel_supplier_invoice 5984 5981 3 0.05 2 44631 835 0 3
sp_cancel_supplier_order 403 403 0 0.00 6 1073697 114201 0 0
sp_cancel_write_off 9106 9106 0 0.00 1 10750 127 0 0
sp_client_order 19434 19434 0 0.00 4 48940 411 0 0
sp_customer_reserve 413697 413697 0 0.00 0 58642 207 0 0
sp_fill_shopping_cart 29686 29686 0 0.00 1 27796 52 0 0
sp_fill_shopping_cart_clo_res 413697 413697 0 0.00 0 22620 20 0 0
sp_get_clo_for_invoice 4525 4525 0 0.00 0 36170 443 0 0
sp_kill_cost_storno 15503 15503 0 0.00 0 4327 61 0 0
sp_kill_qstorno_ret_qs2qd 1070028 1070028 0 0.00 0 69441 37 0 0
sp_kill_qty_storno 563764 563764 0 0.00 0 80582 81 0 0
sp_lock_selected_doc 19399 19399 0 0.00 0 7782 4 0 0
sp_make_cost_storno 26227 26227 0 0.00 0 11439 59 0 0
sp_multiply_rows_for_pdistr 26227 26227 0 0.00 0 8029 32 0 0
sp_multiply_rows_for_qdistr 19434 19434 0 0.00 0 48819 222 0 0
sp_pay_from_customer 6699 6699 0 0.00 4 12344 121 0 0
sp_pay_to_supplier 5613 5613 0 0.00 5 13088 139 0 0
sp_payment_common 12311 12311 0 0.00 1 8687 26 0 0
sp_qd_handle_on_cancel_clo 1721 1721 0 0.00 0 10575 355 0 0
sp_qd_handle_on_invoice_upd_sts 9894 9894 0 0.00 0 49116 429 0 0
sp_qd_handle_on_reserve_upd_sts 9390 9390 0 0.00 0 11242 54 0 0
sp_reserve_write_off 9390 9390 0 0.00 7 13538 182 0 0
sp_supplier_invoice 5168 5168 0 0.00 8 95997 3956 0 0
sp_supplier_order 5098 5098 0 0.00 6 32627 1406 0 0
srv_aggregate_perf_data 114 114 0 0.00 2755 139138 12873 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2659 127137 44423 0 0
srv_find_qd_qs_mism 978444 978444 0 0.00 0 48732 7 0 0
srv_make_invnt_saldo 422 422 0 0.00 84 94656 3834 0 0
srv_make_money_saldo 686 686 0 0.00 1 13972 617 0 0
srv_recalc_idx_stat 6 6 0 0.00 97943 441112 263125 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4527 4527 0 0.00 0 11999 187 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 1 6984 118 0 0
t$perf-norm:sp_cancel_client_order 1721 1721 0 0.00 0 10805 20 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 4251 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 10529 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 783 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 444 12 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 21777 1519 0 0
t$perf-norm:sp_cancel_write_off 921 921 0 0.00 0 2044 5 0 0
t$perf-norm:sp_client_order 19434 19434 0 0.00 0 8897 21 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 4109 12 0 0
t$perf-norm:sp_pay_from_customer 6699 6699 0 0.00 0 5202 12 0 0
t$perf-norm:sp_pay_to_supplier 5613 5613 0 0.00 0 20434 11 0 0
t$perf-norm:sp_reserve_write_off 9390 9390 0 0.00 0 8382 16 0 0
t$perf-norm:sp_supplier_invoice 5168 5168 0 0.00 0 4097 18 0 0
t$perf-norm:sp_supplier_order 5098 5098 0 0.00 0 4955 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 369 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 515 16 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 3056 17 0 0
t$perf-norm:srv_make_money_saldo 686 686 0 0.00 0 1470 6 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9188 9048 140 1.52 0 5452 17 0 140
v_all_customers 35586 35586 0 0.00 0 121 1 0 0
v_all_suppliers 28628 28628 0 0.00 0 16 1 0 0
v_all_wares 38868 38868 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 1995 12 0 0
v_cancel_client_order 3442 3442 0 0.00 0 15144 10 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 2114 10 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1209 5 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2257 8 0 0
v_cancel_supplier_invoice 722 716 6 0.83 0 338 12 0 6
v_cancel_supplier_order 806 806 0 0.00 0 1783 7 0 0
v_cancel_write_off 1842 1842 0 0.00 0 10484 19 0 0
v_max_id_clo_ord 5092 5092 0 0.00 0 2470 2 0 0
v_max_id_clo_res 947 947 0 0.00 0 3185 17 0 0
v_max_id_ord_sup 5160 5160 0 0.00 0 6397 9 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 458 3 0 0
v_max_non_paid_realizn 3490 3490 0 0.00 0 3272 5 0 0
v_min_id_clo_ord 5092 5092 0 0.00 0 1761 6 0 0
v_min_id_clo_res 947 947 0 0.00 0 779 4 0 0
v_min_id_ord_sup 5160 5160 0 0.00 0 5947 8 0 0
v_min_non_paid_invoice 5613 5613 0 0.00 0 7622 18 0 0
v_min_non_paid_realizn 6698 6698 0 0.00 0 8677 21 0 0
v_our_firm 9698 9698 0 0.00 0 22 1 0 0
v_reserve_write_off 18778 18778 0 0.00 0 5521 6 0 0
x_lock_depdocs_on_canc_invoice 10992 10992 0 0.00 0 12364 109 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7791 352 0 0
x_make_qstorno_1000_1200 5098 5098 0 0.00 2 32620 1322 0 0
x_make_qstorno_1200_2000 5168 5168 0 0.00 4 94213 3747 0 0
x_make_qstorno_2100_3300 413697 413697 0 0.00 0 58639 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2020 04:29:56 to 04-12-2020 04:30: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
01:56:58 7 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
01:57:59 13126 8192000000 9708590464 10002485248 786674048 8.761 100 88 176 0 815653872 7119760 21860064
01:59:16 32151 8192000000 9767382480 10070872064 835100320 9.251 100 89 179 1 867591856 11198272 21293264
02:00:51 17607 8192000000 9785712784 10100371456 856322192 9.463 100 84 168 0 894734144 18499600 19912352
02:02:15 18887 8192000000 9861386848 10190266368 899675600 9.895 100 80 160 1 960132944 42331760 18125584
02:03:40 9752 8192000000 9911777408 10252230656 946840048 10.360 100 78 156 1 1018081600 54517872 16723680
02:04:58 5961 8192000000 9944382112 10297569280 990138992 10.783 100 76 150 0 1067445696 62406080 14900624
02:06:06 4660 8192000000 9999423712 10358374400 1017756704 11.050 100 84 167 2 1112086512 76713648 17616160
02:07:13 2905 8192000000 9999593680 10370379776 1037377648 11.239 100 71 140 0 1114728448 63320800 14030000
02:08:19 2228 8192000000 10041226352 10412519424 1058866048 11.446 100 93 184 1 1159326064 81977440 18482576
02:09:24 1707 8192000000 10030161536 10400698368 1066114624 11.515 100 87 174 0 1139022192 54238704 18668864
02:10:27 4874 8192000000 9977924912 10364235776 1068125408 11.534 100 47 97 2 1092222592 13812320 10284864
02:11:34 6476 8192000000 9994347392 10385481728 1077567280 11.624 100 35 71 1 1109336928 24007792 7761856
02:12:44 11766 8192000000 10008333280 10408652800 1082505248 11.671 100 30 59 3 1128729968 39739392 6485328
02:13:57 44324 8192000000 10006971056 10418835456 1086202848 11.707 100 56 111 2 1116993072 18140704 12649520
02:15:52 4937 8192000000 10018245184 10406453248 1088640272 11.730 100 40 78 1 1128569904 31510272 8419360
02:16:59 7975 8192000000 10016987936 10420011008 1090133264 11.744 100 41 82 3 1139340336 39744272 9462800
02:18:08 7014 8192000000 10000373184 10399584256 1057456544 11.432 97 37 72 1 1087613072 22222208 7934320
02:19:17 47469 8192000000 9945929136 10335711232 995235728 10.832 92 31 60 4 1030855888 29984416 5635744
02:21:06 5012 8192000000 9930113904 10312781824 995345296 10.833 97 44 88 3 1041817696 36753504 9718896
02:22:12 6339 8192000000 9935744560 10311294976 1011660368 10.991 99 36 72 2 1047365888 28031168 7674352
02:23:21 16972 8192000000 9942282096 10344554496 1013923200 11.013 99 33 65 4 1042544016 22111280 6509536
02:25:12 6138 8192000000 9967803584 10361958400 1042882176 11.292 100 50 99 3 1074681248 20609136 11189936
02:26:20 6845 8192000000 9983776368 10387374080 1066491376 11.519 100 33 62 0 1088470688 15143728 6835584
02:27:37 2659 8192000000 10013271920 10402848768 1083681664 11.683 100 55 106 4 1110219696 15125552 11412480
02:28:43 39851 8192000000 10046887648 10452930560 1098463808 11.823 100 52 105 3 1153630720 43806688 11360224
02:30:32 16361 8192000000 10106349200 10514247680 1114772608 11.978 100 54 108 0 1205252304 79141584 11338112
02:31:54 7621 8192000000 10128521056 10535428096 1125364832 12.078 100 51 101 1 1235786832 99825952 10596048
02:33:07 7761 8192000000 10148304896 10554392576 1139954928 12.215 100 39 77 3 1255087856 106451440 8681488
02:34:22 11553 8192000000 10185217584 10596929536 1142440064 12.238 100 48 95 2 1290701552 136823664 11437824
02:35:41 4660 8192000000 10223973584 10633682944 1148465328 12.295 100 73 144 2 1332194496 167353248 16375920
02:37:00 4988 8192000000 10229620768 10634207232 1159474720 12.398 100 64 123 5 1323252720 149024912 14753088
02:38:08 4310 8192000000 10239833008 10653462528 1172620848 12.521 100 71 140 2 1340344512 151647440 16076224
02:39:17 2848 8192000000 10299010912 10706350080 1177287008 12.565 100 82 163 0 1397251936 202273280 17691648
02:40:24 5689 8192000000 10277386928 10699558912 1167054880 12.469 100 73 142 1 1374346400 190376256 16915264
02:41:33 4626 8192000000 10308373376 10713845760 1156983152 12.375 100 85 168 2 1412629008 236753552 18892304
02:42:46 5943 8192000000 10322561072 10735644672 1153059360 12.338 100 59 118 2 1421261440 254360832 13841248
02:43:57 5463 8192000000 10351326048 10755035136 1149919840 12.309 100 68 135 0 1448847296 282953344 15974112
02:45:07 2724 8192000000 10377350480 10811428864 1160909312 12.412 100 81 161 1 1480079968 299574624 19596032
02:46:12 23299 8192000000 10283385360 10758356992 1146208816 12.274 99 32 64 1 1425838224 271503888 8125520
02:47:38 16108 8192000000 10289055296 10720247808 1139808160 12.214 100 28 56 0 1382571840 235782576 6981104
02:48:59 18446 8192000000 10301958272 10743070720 1128506480 12.107 99 29 58 2 1392042496 256019392 7516624
02:50:23 12444 8192000000 10292944800 10723069952 1112237424 11.954 98 36 72 0 1384716176 263780832 8697920
02:51:47 5839 8192000000 10306584816 10737606656 1126320640 12.087 99 44 88 1 1404027168 266814624 10891904
02:52:59 17386 8192000000 10273426512 10766282752 1112860272 11.959 99 28 56 0 1399064144 279064304 7139568
02:54:23 20789 8192000000 10197902256 10682257408 1125564384 12.080 100 14 28 2 1298513600 169354624 3594592
02:56:29 107980 8192000000 10100821376 10520166400 1088887568 11.732 97 40 79 0 1160717760 63701808 8128384
02:59:25 22291 8192000000 10085162640 10555105280 1117054992 11.999 100 14 28 0 1226557200 106635568 2866640
03:01:52 93690 8192000000 10117600800 10568007680 1119413680 12.021 99 20 40 1 1217538704 93824416 4300608
03:04:35 63527 8192000000 10070822160 10509561856 1116556848 11.994 99 12 24 0 1155863440 37101216 2205376
03:06:44 61673 8192000000 10106931792 10595913728 1136544224 12.183 100 34 67 0 1213168720 68976016 7648480
03:08:51 8004 8192000000 10092775904 10545152000 1146929504 12.281 100 53 107 4 1193218640 33646256 12642880
03:10:02 54462 8192000000 10170393152 10605801472 1139834368 12.214 99 27 54 4 1227587440 82259984 5493088
03:11:57 73433 8192000000 10206421088 10688114688 1159443552 12.398 100 22 44 1 1304452704 139760688 5248464
03:14:11 53449 8192000000 10147267632 10658959360 1144584416 12.259 100 12 25 0 1263650528 115935712 3130400
03:16:17 64477 8192000000 10098558000 10556690432 1135756416 12.176 99 15 30 0 1220808704 81696976 3355312
03:18:23 53633 8192000000 10079781376 10528563200 1135915888 12.177 99 27 54 2 1172632512 30798528 5918096
03:20:22 38301 8192000000 10152966480 10644344832 1141960240 12.234 99 31 62 4 1247594464 99428944 6205280
03:22:55 113180 8192000000 10083517152 10560315392 1135745536 12.175 98 12 24 0 1196515120 57911152 2858432
03:25:55 51114 8192000000 10122301792 10575175680 1132016704 12.140 98 26 52 1 1184319792 47243312 5059776
03:27:49 36491 8192000000 10089447488 10527924224 1125540640 12.079 98 14 29 1 1166384016 38078064 2765312
03:29:26 125727 8192000000 10070851088 10509701120 1109090112 11.924 97 23 46 1 1172372928 58794048 4488768
03:32:39 56432 8192000000 10084043648 10556387328 1116744848 11.996 99 9 18 0 1169228992 50712160 1771984
03:34:42 47320 8192000000 10056471744 10542391296 1117115888 12.000 99 5 10 0 1158110592 39778736 1215968
03:37:15 39022 8192000000 10074368000 10520469504 1125462288 12.079 99 25 49 1 1160927600 30196704 5268608
03:38:54 47991 8192000000 10136082736 10573287424 1112970800 11.961 97 33 66 0 1189873280 69731440 7171040
03:40:44 25354 8192000000 10154860064 10603245568 1142434384 12.238 100 23 46 1 1246619760 98853776 5331600
03:42:20 116259 8192000000 10187933920 10723950592 1142651936 12.240 99 23 46 1 1336857600 188289488 5916176
03:45:17 105071 8192000000 10066477328 10501619712 1105829728 11.893 97 23 44 0 1156879520 46375312 4674480
03:48:02 52753 8192000000 10063651952 10488086528 1080408496 11.651 96 27 54 2 1131063136 45062704 5591936
03:49:57 78497 8192000000 10039882240 10490171392 1047214080 11.334 94 17 34 2 1082415584 31637984 3563520
03:53:38 65337 8192000000 10058745200 10502959104 1095916512 11.799 98 11 23 0 1148683728 50371520 2395696
03:55:44 51866 8192000000 10129577472 10601558016 1102274864 11.859 99 11 22 3 1196421168 91496688 2649616
03:57:37 53612 8192000000 10030504464 10485030912 1063451536 11.490 95 15 31 2 1110660160 43686432 3522192
03:59:36 41110 8192000000 10088194448 10524622848 1101368256 11.851 99 15 30 1 1160700208 56079392 3252560
04:02:07 36907 8192000000 10063458560 10493915136 1098717408 11.825 98 25 50 1 1136629184 32371744 5540032
04:03:45 49773 8192000000 10077962688 10503499776 1122387792 12.050 99 23 46 0 1164388544 37129888 4870864
04:05:38 55241 8192000000 10108248560 10578722816 1131486400 12.135 100 28 56 1 1188508368 51494032 5527936
04:07:45 44244 8192000000 10096108112 10534346752 1134188064 12.161 99 27 54 1 1195204240 55770640 5245536
04:09:37 127137 8192000000 10061943248 10537836544 1139713984 12.213 99 37 74 0 1208444208 60854880 7875344
04:15:13 92187 8192000000 10074027472 10517725184 1120491328 12.032 97 11 22 1 1174806272 52023008 2291936
04:17:51 30961 8192000000 10131420784 10574778368 1148732736 12.298 99 38 74 2 1218382160 62195216 7454208
04:19:24 87215 8192000000 10081759920 10544713728 1149074816 12.301 100 13 26 1 1212750512 60862704 2812992
04:22:00 54474 8192000000 10096064368 10579750912 1138749296 12.204 99 23 44 4 1196250848 52465312 5036240
04:23:58 53451 8192000000 10116998272 10572419072 1136977920 12.187 98 14 27 2 1182954032 43230048 2746064
04:26:03 79096 8192000000 9769713792 10184146944 823575808 9.135 72 6 12 0 852002864 27054352 1372704

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 04:30:02 to 04-12-2020 04:30:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2020 04:30:18 to 04-12-2020 04:30: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          223888
MON$OLDEST_ACTIVE               223889
MON$OLDEST_SNAPSHOT             223889
MON$NEXT_TRANSACTION            223889
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                       2081312
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             776
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2287 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.2287 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 ---
:117: 
:118: mscs-test-161.budzdorov.ru    Fri Dec  4 04:28:05 2020
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
--- end of diff output ---

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

04.12.20 04:30: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-04 04:30:23 No severe PSQL-related problems occured
04.12.20 04:30:25. End of report.