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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 15717508 kB
MemAvailable 30751568 kB
Buffers 20 kB
Cached 15950300 kB
SwapCached 2944 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.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-30 04:37:08
DTS_END                         2021-09-30 04:37:08.9670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46074
TRN_ID                          240263
ATT_ID                          645
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: 9264.36 19547 1111723
customer order: creation 174.52 398 20942
customer order: refuse 14.93 292 1791
order to supplier: creation 45.88 1461 5506
order to supplier: removal 3.47 112078 416
invoice (draft): creation 45.98 3707 5518
invoice (draft): removal 53.14 1004 6377
invoice accept: apply 42.00 19994 5040
invoice accept: cancel 47.73 815 5727
customer reserve: creation 3874.78 199 464973
customer reserve: removal 4655.37 64 558644
realization accept: apply 83.45 176 10014
realization accept: cancel 91.48 121 10977
payment from customer: creation 60.62 114 7274
payment from customer: removal 5.72 70 686
payment to supplier: creation 50.45 125 6054
payment to supplier: removal 3.48 98 418
service: total inventory turnovers 3.93 3365 471
service: total monetary turnovers 6.53 468 783
service: aggregate perf. data 0.90 19425 108
service: refresh index statistics 0.03 226965 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-09-2021 04:39:10 to 30-09-2021 04:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6382
TEST_TIME 2 5551
TEST_TIME 3 5825
TEST_TIME 4 6003
TEST_TIME 5 5677
TEST_TIME 6 5868
TEST_TIME 7 6388
TEST_TIME 8 6968
TEST_TIME 9 7102
TEST_TIME 10 7328
TEST_TIME 11 7356
TEST_TIME 12 7423
TEST_TIME 13 7812
TEST_TIME 14 7806
TEST_TIME 15 7885
TEST_TIME 16 8170
TEST_TIME 17 8737
TEST_TIME 18 8734
TEST_TIME 19 8723
TEST_TIME 20 9198
TEST_TIME 21 9210
TEST_TIME 22 9011
TEST_TIME 23 9374
TEST_TIME 24 10346
TEST_TIME 25 11503
TEST_TIME 26 11291
TEST_TIME 27 11005
TEST_TIME 28 11086
TEST_TIME 29 11269
TEST_TIME 30 11161
TEST_TIME 31 10905
TEST_TIME 32 10846
TEST_TIME 33 10530
TEST_TIME 34 10635
TEST_TIME 35 10447
TEST_TIME 36 10374
TEST_TIME 37 10345
TEST_TIME 38 10240
TEST_TIME 39 10187
TEST_TIME 40 10128
TEST_TIME 41 10123
TEST_TIME 42 10082
TEST_TIME 43 9953
TEST_TIME 44 9930
TEST_TIME 45 10067
TEST_TIME 46 10033
TEST_TIME 47 10056
TEST_TIME 48 9962
TEST_TIME 49 9934
TEST_TIME 50 10004
TEST_TIME 51 9993
TEST_TIME 52 10164
TEST_TIME 53 10166
TEST_TIME 54 10296
TEST_TIME 55 10291
TEST_TIME 56 10285
TEST_TIME 57 10192
TEST_TIME 58 10037
TEST_TIME 59 9995
TEST_TIME 60 9916
TEST_TIME 61 9944
TEST_TIME 62 9919
TEST_TIME 63 9890
TEST_TIME 64 9846
TEST_TIME 65 9776
TEST_TIME 66 9788
TEST_TIME 67 9823
TEST_TIME 68 9782
TEST_TIME 69 9770
TEST_TIME 70 9761
TEST_TIME 71 9694
TEST_TIME 72 9627
TEST_TIME 73 9640
TEST_TIME 74 9679
TEST_TIME 75 9680
TEST_TIME 76 9659
TEST_TIME 77 9747
TEST_TIME 78 9650
TEST_TIME 79 9591
TEST_TIME 80 9539
TEST_TIME 81 9545
TEST_TIME 82 9504
TEST_TIME 83 9491
TEST_TIME 84 9429
TEST_TIME 85 9407
TEST_TIME 86 9378
TEST_TIME 87 9364
TEST_TIME 88 9402
TEST_TIME 89 9395
TEST_TIME 90 9361
TEST_TIME 91 9406
TEST_TIME 92 9464
TEST_TIME 93 9483
TEST_TIME 94 9458
TEST_TIME 95 9519
TEST_TIME 96 9512
TEST_TIME 97 9545
TEST_TIME 98 9591
TEST_TIME 99 9529
TEST_TIME 100 9451
TEST_TIME 101 9421
TEST_TIME 102 9386
TEST_TIME 103 9317
TEST_TIME 104 9302
TEST_TIME 105 9308
TEST_TIME 106 9273
TEST_TIME 107 9240
TEST_TIME 108 9205
TEST_TIME 109 9174
TEST_TIME 110 9166
TEST_TIME 111 9183
TEST_TIME 112 9303
TEST_TIME 113 9350
TEST_TIME 114 9318
TEST_TIME 115 9391
TEST_TIME 116 9379
TEST_TIME 117 9415
TEST_TIME 118 9415
TEST_TIME 119 9344
TEST_TIME 120 9270

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-09-2021 04:39:13 to 30-09-2021 04:39:17.

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 1540599 1540599 0 0.00 0 25204 8 0 0
doc_list_biud 1540597 1540597 0 0.00 0 69119 30 0 0
sp_add_invoice_to_stock 5161 5040 121 2.34 44 222655 19994 0 121
sp_cancel_adding_invoice 5727 5727 0 0.00 6 81571 816 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 18188 292 0 0
sp_cancel_customer_reserve 558644 558644 0 0.00 1 37151 65 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 5360 71 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 7204 99 0 0
sp_cancel_supplier_invoice 6384 6377 7 0.10 2 69126 1005 0 7
sp_cancel_supplier_order 416 416 0 0.00 6 1215034 112078 0 0
sp_cancel_write_off 10977 10977 0 0.00 1 13579 122 0 0
sp_client_order 20942 20942 0 0.00 4 35366 399 0 0
sp_customer_reserve 464973 464973 0 0.00 0 79942 199 0 0
sp_fill_shopping_cart 31963 31963 0 0.00 1 26939 46 0 0
sp_fill_shopping_cart_clo_res 464965 464965 0 0.00 0 14740 17 0 0
sp_get_clo_for_invoice 5029 5029 0 0.00 0 33996 333 0 0
sp_kill_cost_storno 17808 17808 0 0.00 0 7232 54 0 0
sp_kill_qstorno_ret_qs2qd 1135058 1135058 0 0.00 0 69117 36 0 0
sp_kill_qty_storno 598973 598973 0 0.00 0 69118 76 0 0
sp_lock_selected_doc 20962 20962 0 0.00 0 6342 4 0 0
sp_make_cost_storno 28370 28370 0 0.00 0 17721 53 0 0
sp_multiply_rows_for_pdistr 28370 28370 0 0.00 0 8455 31 0 0
sp_multiply_rows_for_qdistr 20942 20942 0 0.00 0 35357 202 0 0
sp_pay_from_customer 7274 7274 0 0.00 4 9353 114 0 0
sp_pay_to_supplier 6054 6054 0 0.00 5 9799 125 0 0
sp_payment_common 13328 13328 0 0.00 1 6015 22 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 18183 273 0 0
sp_qd_handle_on_invoice_upd_sts 10754 10754 0 0.00 0 41332 346 0 0
sp_qd_handle_on_reserve_upd_sts 10014 10014 0 0.00 0 19047 59 0 0
sp_reserve_write_off 10014 10014 0 0.00 7 19081 176 0 0
sp_supplier_invoice 5518 5518 0 0.00 7 105207 3707 0 0
sp_supplier_order 5506 5506 0 0.00 5 24555 1462 0 0
srv_aggregate_perf_data 108 108 0 0.00 2954 503413 19426 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1841 133447 44806 0 0
srv_find_qd_qs_mism 1062575 1062575 0 0.00 0 25204 7 0 0
srv_make_invnt_saldo 471 471 0 0.00 62 102287 3366 0 0
srv_make_money_saldo 783 783 0 0.00 1 10365 468 0 0
srv_recalc_idx_stat 4 4 0 0.00 126128 309763 226965 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5040 5040 0 0.00 0 13383 168 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 5544 109 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 9589 13 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 3688 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 2128 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 478 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 1040 16 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 16469 1445 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 2337 8 0 0
t$perf-norm:sp_client_order 20942 20942 0 0.00 0 12615 20 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 2339 9 0 0
t$perf-norm:sp_pay_from_customer 7274 7274 0 0.00 0 8031 11 0 0
t$perf-norm:sp_pay_to_supplier 6054 6054 0 0.00 0 7996 11 0 0
t$perf-norm:sp_reserve_write_off 10014 10014 0 0.00 0 21048 13 0 0
t$perf-norm:sp_supplier_invoice 5518 5518 0 0.00 0 8027 16 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 7073 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1755 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 91 3 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 1576 10 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 2421 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10294 10052 242 2.35 0 7920 17 0 242
v_all_customers 38174 38174 0 0.00 0 88 1 0 0
v_all_suppliers 30712 30712 0 0.00 0 27 1 0 0
v_all_wares 41884 41884 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1196 7 0 0
v_cancel_client_order 3582 3582 0 0.00 0 1052 3 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 2764 8 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 241 3 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 4676 15 0 0
v_cancel_supplier_invoice 912 898 14 1.53 0 1983 19 0 14
v_cancel_supplier_order 832 832 0 0.00 0 1612 7 0 0
v_cancel_write_off 1978 1978 0 0.00 0 2117 14 0 0
v_max_id_clo_ord 5505 5505 0 0.00 0 1080 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1572 12 0 0
v_max_id_ord_sup 5516 5516 0 0.00 0 5381 4 0 0
v_max_non_paid_invoice 1719 1719 0 0.00 0 41 2 0 0
v_max_non_paid_realizn 3892 3892 0 0.00 0 1488 4 0 0
v_min_id_clo_ord 5505 5505 0 0.00 0 2505 5 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 7286 13 0 0
v_min_id_ord_sup 5516 5516 0 0.00 0 13628 13 0 0
v_min_non_paid_invoice 6054 6054 0 0.00 0 6012 13 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 5949 18 0 0
v_our_firm 10474 10474 0 0.00 0 15 1 0 0
v_reserve_write_off 20028 20028 0 0.00 0 4285 5 0 0
x_lock_depdocs_on_canc_invoice 11655 11655 0 0.00 0 21342 106 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 25209 476 0 0
x_make_qstorno_1000_1200 5506 5506 0 0.00 2 24546 1380 0 0
x_make_qstorno_1200_2000 5518 5518 0 0.00 2 87117 3531 0 0
x_make_qstorno_2100_3300 464973 464973 0 0.00 0 79939 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-09-2021 04:39:18 to 30-09-2021 04:39:22.

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
02:07:12 11 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
02:08:13 23693 8192000000 9732489808 10035195904 814370480 9.042 100 81 161 0 841253472 7602400 19280592
02:09:39 29606 8192000000 9780143696 10093776896 859418864 9.494 100 90 180 0 893484432 13105136 20960432
02:11:13 20606 8192000000 9827326352 10150178816 891056928 9.810 100 86 172 1 935394304 25483328 18854048
02:12:49 11263 8192000000 9896791920 10234388480 938976512 10.283 100 84 168 1 1009282784 52165680 18140592
02:14:03 9445 8192000000 9932075168 10280624128 973808624 10.624 100 76 152 1 1045838160 55991008 16038528
02:15:15 3909 8192000000 9959545296 10320367616 1004489712 10.922 100 79 157 0 1083374176 63086336 15798128
02:16:21 2572 8192000000 9997448960 10358468608 1023951024 11.110 100 89 175 1 1107222080 65299968 17971088
02:17:25 1843 8192000000 10006830496 10378838016 1044757920 11.310 100 85 170 0 1123994448 61961680 17274848
02:18:28 6390 8192000000 10021908512 10400194560 1053914992 11.398 100 71 142 1 1131760432 62546464 15298976
02:19:35 6505 8192000000 9977558624 10370543616 1061694208 11.473 100 30 60 2 1093074048 25204400 6175440
02:20:42 10886 8192000000 9979625728 10374721536 1069115920 11.544 100 28 54 2 1108474016 33716256 5641840
02:21:56 5021 8192000000 9994745072 10390822912 1074290512 11.593 100 39 78 1 1114062496 31141696 8630288
02:23:01 17941 8192000000 9987496800 10398806016 1074851632 11.598 100 29 58 0 1099986416 18590464 6544320
02:24:22 3492 8192000000 10017608272 10422480896 1085209392 11.697 100 39 79 0 1130768496 37182064 8377040
02:25:27 5714 8192000000 10012023056 10414813184 1095911584 11.799 100 35 69 0 1118250272 14681200 7657488
02:26:34 70020 8192000000 9990770016 10399895552 1048799408 11.349 96 29 59 0 1070929408 16135744 5994256
02:28:47 3349 8192000000 9964468944 10350002176 1024769376 11.118 97 62 123 2 1050333264 11550080 14013808
02:29:55 3626 8192000000 9948637040 10335211520 1034182528 11.209 100 42 85 1 1070069856 26009744 9877584
02:31:00 73518 8192000000 9938856688 10335436800 962041392 10.509 93 32 60 1 985574592 16816880 6716320
02:33:14 6565 8192000000 9972427552 10371620864 1037604192 11.242 99 48 94 2 1078433008 29798032 11030784
02:34:20 6896 8192000000 9995574160 10392481792 1065095552 11.505 100 49 98 1 1094785008 18329728 11359728
02:35:29 82335 8192000000 9980637264 10392715264 1078661312 11.635 100 38 74 2 1105283824 18192384 8430128
02:38:01 27434 8192000000 10065233280 10464301056 1108408192 11.917 100 58 113 1 1177040864 55727696 12904976
02:39:32 25236 8192000000 10077988896 10484809728 1126021520 12.084 100 45 90 2 1184604864 48873712 9709632
02:41:00 50779 8192000000 10118887360 10532995072 1137327328 12.190 100 43 86 3 1234923920 87846896 9749696
02:42:55 20689 8192000000 10145183696 10571624448 1149971856 12.309 100 35 70 0 1242761072 85145712 7643504
02:44:21 3960 8192000000 10181378688 10587643904 1149653360 12.306 100 66 129 0 1274735680 111263616 13818704
02:45:28 1834 8192000000 10205966608 10609639424 1143500032 12.248 99 70 138 0 1302968096 144408128 15059936
02:46:33 7294 8192000000 10232259616 10641330176 1161761040 12.420 100 76 150 0 1331818912 153818624 16239248
02:47:46 4808 8192000000 10264361824 10677866496 1183019744 12.618 100 73 144 1 1370044304 170860736 16163824
02:48:56 2289 8192000000 10294125296 10705367040 1183796096 12.626 100 77 154 0 1388647280 187457856 17393328
02:50:03 2552 8192000000 10279690624 10692890624 1157036192 12.375 99 81 162 2 1379853728 203547680 19269856
02:51:08 3227 8192000000 10303645872 10704523264 1153184768 12.339 100 77 154 0 1396086016 224859760 18041488
02:52:14 4301 8192000000 10350134768 10749030400 1146261456 12.274 99 87 171 1 1443327392 277390528 19675408
02:53:23 3125 8192000000 10382151392 10796371968 1145323232 12.266 100 74 146 2 1479729744 316926576 17479936
02:54:30 4635 8192000000 10322035216 10736750592 1146951984 12.281 100 56 112 1 1422255392 261503456 13799952
02:55:39 2039 8192000000 10364816320 10762870784 1149510432 12.305 100 81 161 1 1451898720 283721936 18666352
02:56:45 4718 8192000000 10376472592 10796318720 1127212064 12.095 99 48 96 0 1476540640 336994688 12333888
02:57:56 16072 8192000000 10387146512 10849767424 1148152368 12.292 100 40 80 0 1474543808 316477760 9913680
02:59:13 23905 8192000000 10423055008 10851991552 1135617088 12.174 100 30 60 1 1498155072 353722240 8815744
03:00:45 15326 8192000000 10377441600 10830872576 1115505824 11.985 100 31 62 0 1495206032 370616096 9084112
03:02:07 24120 8192000000 10181918576 10684485632 1103942480 11.875 98 16 32 0 1315057072 207027424 4087168
03:03:35 69596 8192000000 10102727184 10528325632 1098367136 11.822 98 20 41 0 1148616720 45781328 4468256
03:05:46 26050 8192000000 10103886496 10582253568 1112177776 11.953 99 8 16 0 1203526880 89503024 1846080
03:07:54 35731 8192000000 10084178256 10507821056 1128576704 12.108 100 18 36 0 1158252976 26335632 3340640
03:09:31 116276 8192000000 10095927952 10535108608 1135442320 12.173 100 31 58 0 1204109104 61925456 6741328
03:12:29 51966 8192000000 10089532992 10514350080 1132148208 12.142 99 55 108 3 1180408128 37955120 10304800
03:14:22 20098 8192000000 10097297632 10546700288 1133520928 12.155 100 43 85 1 1200091680 57108928 9461824
03:15:53 14679 8192000000 10107910048 10543579136 1124851056 12.073 99 55 107 1 1197635344 60878128 11906160
03:17:08 26891 8192000000 10113799760 10576961536 1141562000 12.230 100 14 29 0 1222275312 77496352 3216960
03:18:36 56464 8192000000 10117437888 10592006144 1143762960 12.251 100 14 28 1 1217756112 70354976 3638176
03:20:56 72809 8192000000 10154423008 10622574592 1154214064 12.349 100 24 48 1 1288015056 128466640 5334352
03:24:04 43678 8192000000 10081644640 10553225216 1141933904 12.234 99 18 36 0 1188994704 43797888 3262912
03:25:50 43351 8192000000 10154636240 10622394368 1157567040 12.380 100 40 80 0 1236719632 70093728 9058864
03:27:35 79935 8192000000 10152131344 10633392128 1095385312 11.794 94 16 32 2 1244165520 144780624 3999584
03:30:06 32437 8192000000 10139801248 10594680832 1132612976 12.146 99 34 68 0 1219730384 80117872 6999536
03:31:42 67586 8192000000 10066998016 10526928896 1110823984 11.940 97 12 24 0 1160216896 46748864 2644048
03:33:49 109918 8192000000 10088266928 10518224896 1095607376 11.796 97 25 50 1 1134990320 34562080 4820864
03:36:41 35793 8192000000 10082936432 10536419328 1128732608 12.109 100 28 54 0 1192261616 57823136 5705872
03:38:22 71105 8192000000 10058232400 10564808704 1105108384 11.886 98 17 35 0 1197769712 88586720 4074608
03:40:38 24501 8192000000 10054849632 10501775360 1104925104 11.884 98 15 28 0 1129491728 21810592 2756032
03:42:03 50263 8192000000 10084640624 10528059392 1111723936 11.949 98 23 46 0 1172746304 56217376 4804992
03:43:54 32811 8192000000 10101841200 10543308800 1119928656 12.026 99 25 50 0 1180860896 55975472 4956768
03:45:33 42161 8192000000 10060929840 10495803392 1077014576 11.619 96 32 64 1 1126671840 42629136 7028128
03:47:21 82465 8192000000 10066884496 10513092608 1076669440 11.616 97 21 42 0 1125769344 44974432 4125472
03:49:49 44745 8192000000 10111210576 10559897600 1111741792 11.949 100 50 99 0 1205263872 83754048 9768032
03:51:49 27535 8192000000 10091874576 10518917120 1114478192 11.975 99 41 82 1 1177971088 54978128 8514768
03:53:17 45672 8192000000 10094317232 10564145152 1132497200 12.145 100 36 72 1 1237956544 98019120 7440224
03:55:04 133447 8192000000 10060965184 10492788736 1119197664 12.019 99 43 86 0 1162137696 33960272 8979760
03:58:21 53007 8192000000 10098064112 10581651456 1142620752 12.240 100 17 32 2 1207053152 60045104 4387296
04:00:16 42580 8192000000 10058144544 10544168960 1126926192 12.092 100 8 16 0 1157640048 29077600 1636256
04:03:08 95583 8192000000 10061254672 10525085696 1070403360 11.556 94 23 46 0 1132272224 56840400 5028464
04:05:48 44168 8192000000 10076998256 10534064128 1103924800 11.875 97 27 52 0 1144768096 35724176 5119120
04:07:33 45802 8192000000 10134675488 10604310528 1139430608 12.210 99 38 76 0 1222731024 75280832 8019584
04:09:40 52262 8192000000 10092887152 10552647680 1145582128 12.268 99 24 49 0 1198575760 48567456 4426176
04:11:36 27822 8192000000 10143481616 10583040000 1146995312 12.281 99 54 107 0 1220499168 62399536 11104320
04:13:09 124307 8192000000 10114121136 10625445888 1099989792 11.838 95 19 38 0 1171927984 68147808 3790384
04:16:13 108385 8192000000 10064308848 10506981376 1127503936 12.098 99 17 34 0 1167740064 37059024 3177104
04:19:02 94334 8192000000 10115938752 10592104448 1140134320 12.217 99 15 30 1 1217067088 73664416 3268352
04:21:47 80157 8192000000 10068813744 10554007552 1130494176 12.126 99 5 10 0 1168106736 36454096 1158464
04:24:07 37667 8192000000 10097054736 10544054272 1150763472 12.317 99 24 48 1 1191141440 35142624 5235344
04:25:46 51095 8192000000 10183301104 10627903488 1148643920 12.297 98 34 66 1 1247621968 91634480 7343568
04:27:42 70618 8192000000 10195333872 10681647104 1136776144 12.185 98 19 36 2 1292435952 150998416 4661392
04:29:52 62194 8192000000 10061112544 10541867008 1109677632 11.929 97 5 10 1 1165231440 54335280 1218528
04:31:58 57650 8192000000 10075988016 10564464640 1125575744 12.080 98 11 22 0 1198501664 70605152 2320768
04:33:56 65767 8192000000 10083254304 10568073216 1069843344 11.551 94 27 52 0 1135691936 60586736 5261856
04:36:02 92580 8192000000 9389299824 9766146048 498172640 5.732 44 2 4 0 542374128 43889472 312016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-09-2021 04:39:24 to 30-09-2021 04:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-09-2021 04:39:40 to 30-09-2021 04:39:42.

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          240510
MON$OLDEST_ACTIVE               240511
MON$OLDEST_SNAPSHOT             240511
MON$NEXT_TRANSACTION            240511
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                       2064224
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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'

30.09.21 04:39:43. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-30 04:39:45 No severe PSQL-related problems occured
30.09.21 04:39:47. End of report.