builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1486540127.81 results: success (0) revision: 82c75fd3a2de796351296592c459ab4aa4cd0baf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.814359) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.814850) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.815122) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.828634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.828924) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-07 23:48:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 17.5M=0.001s 2017-02-07 23:48:48 (17.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118112 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.958153) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:47.958472) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014518 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:48.008358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 23:48:48.008696) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 82c75fd3a2de796351296592c459ab4aa4cd0baf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 82c75fd3a2de796351296592c459ab4aa4cd0baf --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-07 23:48:48,327 truncating revision to first 12 chars 2017-02-07 23:48:48,327 Setting DEBUG logging. 2017-02-07 23:48:48,327 attempt 1/10 2017-02-07 23:48:48,327 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/82c75fd3a2de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 23:48:49,517 unpacking tar archive at: mozilla-aurora-82c75fd3a2de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.488177 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 23:48:49.509654) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:49.509977) ========= script_repo_revision: 82c75fd3a2de796351296592c459ab4aa4cd0baf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:49.510366) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:49.510653) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 23:48:49.519218) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-07 23:48:49.519555) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:48:49 INFO - MultiFileLogger online at 20170207 23:48:49 in /builds/slave/test 23:48:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 23:48:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:48:49 INFO - {'append_to_log': False, 23:48:49 INFO - 'base_work_dir': '/builds/slave/test', 23:48:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 23:48:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:48:49 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 23:48:49 INFO - 'buildbot_json_path': 'buildprops.json', 23:48:49 INFO - 'config_files': ('talos/linux_config.py',), 23:48:49 INFO - 'default_actions': ('clobber', 23:48:49 INFO - 'read-buildbot-config', 23:48:49 INFO - 'download-and-extract', 23:48:49 INFO - 'populate-webroot', 23:48:49 INFO - 'create-virtualenv', 23:48:49 INFO - 'install', 23:48:49 INFO - 'run-tests'), 23:48:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:48:49 INFO - 'download_minidump_stackwalk': True, 23:48:49 INFO - 'download_symbols': 'ondemand', 23:48:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:48:49 INFO - 'tooltool.py': '/tools/tooltool.py', 23:48:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:48:49 INFO - '/tools/misc-python/virtualenv.py')}, 23:48:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:48:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:48:49 INFO - 'installer_path': 'installer.exe', 23:48:49 INFO - 'log_level': 'info', 23:48:49 INFO - 'log_name': 'talos', 23:48:49 INFO - 'log_to_console': True, 23:48:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:48:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:48:49 INFO - 'opt_config_files': (), 23:48:49 INFO - 'pip_index': False, 23:48:49 INFO - 'sps_profile': False, 23:48:49 INFO - 'sps_profile_interval': 0, 23:48:49 INFO - 'suite': 'dromaeojs', 23:48:49 INFO - 'system_bits': '32', 23:48:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:48:49 INFO - 'title': 'talos-linux64-ix-004', 23:48:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:48:49 INFO - 'use_talos_json': True, 23:48:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:48:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:48:49 INFO - 'work_dir': 'build'} 23:48:49 INFO - [mozharness: 2017-02-08 07:48:49.937880Z] Running clobber step. 23:48:49 INFO - Running pre-action listener: _resource_record_pre_action 23:48:49 INFO - Running main action method: clobber 23:48:49 INFO - rmtree: /builds/slave/test/build 23:48:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:48:50 INFO - Running post-action listener: _resource_record_post_action 23:48:50 INFO - [mozharness: 2017-02-08 07:48:50.987741Z] Finished clobber step (success) 23:48:50 INFO - [mozharness: 2017-02-08 07:48:50.987841Z] Running read-buildbot-config step. 23:48:50 INFO - Running pre-action listener: _resource_record_pre_action 23:48:50 INFO - Running main action method: read_buildbot_config 23:48:50 INFO - Using buildbot properties: 23:48:50 INFO - { 23:48:50 INFO - "project": "", 23:48:50 INFO - "product": "firefox", 23:48:50 INFO - "who": "hikezoe@mozilla.com", 23:48:50 INFO - "installer_path": "public/build/target.tar.bz2", 23:48:50 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 23:48:50 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 23:48:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 23:48:50 INFO - "stage_platform": "linux64", 23:48:50 INFO - "basedir": "/builds/slave/test", 23:48:50 INFO - "buildnumber": 23, 23:48:50 INFO - "platform": "ubuntu64_hw", 23:48:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:48:50 INFO - "slavebuilddir": "test", 23:48:50 INFO - "taskId": "ZgPYkK97TPyQBw5DfwNf1w", 23:48:50 INFO - "branch": "mozilla-aurora", 23:48:50 INFO - "script_repo_revision": "production", 23:48:50 INFO - "revision": "82c75fd3a2de796351296592c459ab4aa4cd0baf", 23:48:50 INFO - "slavename": "talos-linux64-ix-004", 23:48:50 INFO - "repo_path": "releases/mozilla-aurora" 23:48:50 INFO - } 23:48:50 INFO - Finding installer, test and symbols from parent task. 23:48:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZgPYkK97TPyQBw5DfwNf1w'}, attempt #1 23:48:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZgPYkK97TPyQBw5DfwNf1w'}, attempt #1 23:48:51 INFO - Task dependencies: FXxDmAlYRxWRkiMnCupXTw 23:48:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw'}, attempt #1 23:48:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2 23:48:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/test_packages.json 23:48:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.crashreporter-symbols.zip 23:48:52 INFO - Running post-action listener: _resource_record_post_action 23:48:52 INFO - [mozharness: 2017-02-08 07:48:52.422255Z] Finished read-buildbot-config step (success) 23:48:52 INFO - [mozharness: 2017-02-08 07:48:52.422473Z] Running download-and-extract step. 23:48:52 INFO - Running pre-action listener: _resource_record_pre_action 23:48:52 INFO - Running main action method: download_and_extract 23:48:52 INFO - mkdir: /builds/slave/test/build/tests 23:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:52 INFO - trying https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.test_packages.json 23:48:52 INFO - Downloading https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 23:48:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 23:48:52 INFO - Downloaded 896 bytes. 23:48:52 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 23:48:52 INFO - Using the following test package requirements: 23:48:52 INFO - {u'common': [u'target.common.tests.zip'], 23:48:52 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 23:48:52 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 23:48:52 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 23:48:52 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 23:48:52 INFO - u'mozbase': [u'target.common.tests.zip'], 23:48:52 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 23:48:52 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 23:48:52 INFO - u'web-platform': [u'target.common.tests.zip', 23:48:52 INFO - u'target.web-platform.tests.zip'], 23:48:52 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 23:48:52 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 23:48:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.common.tests.zip 23:48:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.common.tests.zip'}, attempt #1 23:48:52 INFO - Fetch https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.common.tests.zip into memory 23:48:53 INFO - Expected file size: 21861365 23:48:53 INFO - Obtained file size: 21861365 23:48:57 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 23:48:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.common.tests.zip 23:48:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.common.tests.zip'}, attempt #1 23:48:57 INFO - Fetch https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.common.tests.zip into memory 23:48:58 INFO - Expected file size: 21861365 23:48:58 INFO - Obtained file size: 21861365 23:49:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.talos.tests.zip 23:49:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 23:49:03 INFO - Fetch https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.talos.tests.zip into memory 23:49:03 INFO - Expected file size: 14403485 23:49:03 INFO - Obtained file size: 14403485 23:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:04 INFO - trying https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2 23:49:04 INFO - Downloading https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 23:49:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 23:49:05 INFO - Downloaded 66225259 bytes. 23:49:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2 23:49:05 INFO - mkdir: /builds/slave/test/properties 23:49:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:49:05 INFO - Writing to file /builds/slave/test/properties/build_url 23:49:05 INFO - Contents: 23:49:05 INFO - build_url:https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2 23:49:05 INFO - Running post-action listener: _resource_record_post_action 23:49:05 INFO - Running post-action listener: set_extra_try_arguments 23:49:05 INFO - [mozharness: 2017-02-08 07:49:05.308152Z] Finished download-and-extract step (success) 23:49:05 INFO - [mozharness: 2017-02-08 07:49:05.308249Z] Running populate-webroot step. 23:49:05 INFO - Running pre-action listener: _resource_record_pre_action 23:49:05 INFO - Running main action method: populate_webroot 23:49:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:49:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:49:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:49:05 INFO - '--tppagecycles', 23:49:05 INFO - '7'], 23:49:05 INFO - 'tests': ['tsvgm']}}, 23:49:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:49:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:49:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:49:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:49:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:49:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:49:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['damp', 'tps']}, 23:49:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'tests': ['damp', 'tps']}, 23:49:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['dromaeo_dom']}, 23:49:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:49:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['basic_compositor_video']}, 23:49:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:49:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['a11yr', 23:49:05 INFO - 'ts_paint', 23:49:05 INFO - 'tpaint', 23:49:05 INFO - 'sessionrestore', 23:49:05 INFO - 'sessionrestore_no_auto_restore', 23:49:05 INFO - 'tabpaint']}, 23:49:05 INFO - 'other-e10s': {'tests': ['a11yr', 23:49:05 INFO - 'ts_paint', 23:49:05 INFO - 'tpaint', 23:49:05 INFO - 'sessionrestore', 23:49:05 INFO - 'sessionrestore_no_auto_restore', 23:49:05 INFO - 'tabpaint']}, 23:49:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:49:05 INFO - 'ts_paint', 23:49:05 INFO - 'tpaint', 23:49:05 INFO - 'sessionrestore', 23:49:05 INFO - 'sessionrestore_no_auto_restore', 23:49:05 INFO - 'tabpaint']}, 23:49:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:49:05 INFO - 'ts_paint', 23:49:05 INFO - 'tpaint', 23:49:05 INFO - 'sessionrestore', 23:49:05 INFO - 'sessionrestore_no_auto_restore', 23:49:05 INFO - 'tabpaint']}, 23:49:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['a11yr', 23:49:05 INFO - 'ts_paint', 23:49:05 INFO - 'tpaint', 23:49:05 INFO - 'sessionrestore', 23:49:05 INFO - 'sessionrestore_no_auto_restore', 23:49:05 INFO - 'tabpaint']}, 23:49:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['a11yr', 23:49:05 INFO - 'ts_paint', 23:49:05 INFO - 'tpaint', 23:49:05 INFO - 'sessionrestore', 23:49:05 INFO - 'sessionrestore_no_auto_restore', 23:49:05 INFO - 'tabpaint']}, 23:49:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['tsvgx', 23:49:05 INFO - 'tsvgr_opacity', 23:49:05 INFO - 'tart', 23:49:05 INFO - 'tscrollx', 23:49:05 INFO - 'cart', 23:49:05 INFO - 'tsvg_static']}, 23:49:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:49:05 INFO - 'tsvgr_opacity', 23:49:05 INFO - 'tart', 23:49:05 INFO - 'tscrollx', 23:49:05 INFO - 'cart', 23:49:05 INFO - 'tsvg_static']}, 23:49:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'talos_options': ['--disable-e10s'], 23:49:05 INFO - 'tests': ['tp5o']}, 23:49:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:49:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'talos_options': ['--disable-e10s', 23:49:05 INFO - '--xperf_path', 23:49:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:49:05 INFO - 'tests': ['tp5n']}, 23:49:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:49:05 INFO - 'talos_options': ['--xperf_path', 23:49:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:49:05 INFO - 'tests': ['tp5n']}}, 23:49:05 INFO - 'talos.zip': {'path': '', 23:49:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:49:05 INFO - Running post-action listener: _resource_record_post_action 23:49:05 INFO - [mozharness: 2017-02-08 07:49:05.321462Z] Finished populate-webroot step (success) 23:49:05 INFO - [mozharness: 2017-02-08 07:49:05.321561Z] Running create-virtualenv step. 23:49:05 INFO - Running pre-action listener: _resource_record_pre_action 23:49:05 INFO - Running main action method: create_virtualenv 23:49:05 INFO - Creating virtualenv /builds/slave/test/build/venv 23:49:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:49:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:49:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:49:05 INFO - Using real prefix '/usr' 23:49:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:49:05 INFO - Installing distribute.............................................................................................................................................................................................done. 23:49:07 INFO - Installing pip.................done. 23:49:07 INFO - Return code: 0 23:49:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:49:08 INFO - Reading from file tmpfile_stdout 23:49:08 INFO - Output received: 23:49:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:49:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:49:08 INFO - Using env: {'DISPLAY': ':0', 23:49:08 INFO - 'HOME': '/home/cltbld', 23:49:08 INFO - 'LANG': 'en_US.UTF-8', 23:49:08 INFO - 'LANGUAGE': 'en_US:en', 23:49:08 INFO - 'LOGNAME': 'cltbld', 23:49:08 INFO - 'MAIL': '/var/mail/cltbld', 23:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:08 INFO - 'MOZ_NO_REMOTE': '1', 23:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:08 INFO - 'NO_EM_RESTART': '1', 23:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:08 INFO - 'PWD': '/builds/slave/test', 23:49:08 INFO - 'SHELL': '/bin/bash', 23:49:08 INFO - 'SHLVL': '1', 23:49:08 INFO - 'TERM': 'linux', 23:49:08 INFO - 'TMOUT': '86400', 23:49:08 INFO - 'USER': 'cltbld', 23:49:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:08 INFO - '_': '/tools/buildbot/bin/python'} 23:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:49:08 INFO - Cleaning up... 23:49:08 INFO - Return code: 0 23:49:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:49:08 INFO - Using env: {'DISPLAY': ':0', 23:49:08 INFO - 'HOME': '/home/cltbld', 23:49:08 INFO - 'LANG': 'en_US.UTF-8', 23:49:08 INFO - 'LANGUAGE': 'en_US:en', 23:49:08 INFO - 'LOGNAME': 'cltbld', 23:49:08 INFO - 'MAIL': '/var/mail/cltbld', 23:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:08 INFO - 'MOZ_NO_REMOTE': '1', 23:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:08 INFO - 'NO_EM_RESTART': '1', 23:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:08 INFO - 'PWD': '/builds/slave/test', 23:49:08 INFO - 'SHELL': '/bin/bash', 23:49:08 INFO - 'SHLVL': '1', 23:49:08 INFO - 'TERM': 'linux', 23:49:08 INFO - 'TMOUT': '86400', 23:49:08 INFO - 'USER': 'cltbld', 23:49:08 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:08 INFO - '_': '/tools/buildbot/bin/python'} 23:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:08 INFO - Downloading/unpacking psutil>=3.1.1 23:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:49:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:49:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:49:10 INFO - Installing collected packages: psutil 23:49:10 INFO - Running setup.py install for psutil 23:49:10 INFO - building 'psutil._psutil_linux' extension 23:49:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 23:49:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:49:10 INFO - building 'psutil._psutil_posix' extension 23:49:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:49:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:49:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:49:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:49:11 INFO - Successfully installed psutil 23:49:11 INFO - Cleaning up... 23:49:11 INFO - Return code: 0 23:49:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:49:11 INFO - Using env: {'DISPLAY': ':0', 23:49:11 INFO - 'HOME': '/home/cltbld', 23:49:11 INFO - 'LANG': 'en_US.UTF-8', 23:49:11 INFO - 'LANGUAGE': 'en_US:en', 23:49:11 INFO - 'LOGNAME': 'cltbld', 23:49:11 INFO - 'MAIL': '/var/mail/cltbld', 23:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:11 INFO - 'MOZ_NO_REMOTE': '1', 23:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:11 INFO - 'NO_EM_RESTART': '1', 23:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:11 INFO - 'PWD': '/builds/slave/test', 23:49:11 INFO - 'SHELL': '/bin/bash', 23:49:11 INFO - 'SHLVL': '1', 23:49:11 INFO - 'TERM': 'linux', 23:49:11 INFO - 'TMOUT': '86400', 23:49:11 INFO - 'USER': 'cltbld', 23:49:11 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:11 INFO - '_': '/tools/buildbot/bin/python'} 23:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:49:13 INFO - Installing collected packages: mozsystemmonitor 23:49:13 INFO - Running setup.py install for mozsystemmonitor 23:49:13 INFO - Successfully installed mozsystemmonitor 23:49:13 INFO - Cleaning up... 23:49:13 INFO - Return code: 0 23:49:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:49:13 INFO - Using env: {'DISPLAY': ':0', 23:49:13 INFO - 'HOME': '/home/cltbld', 23:49:13 INFO - 'LANG': 'en_US.UTF-8', 23:49:13 INFO - 'LANGUAGE': 'en_US:en', 23:49:13 INFO - 'LOGNAME': 'cltbld', 23:49:13 INFO - 'MAIL': '/var/mail/cltbld', 23:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:13 INFO - 'MOZ_NO_REMOTE': '1', 23:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:13 INFO - 'NO_EM_RESTART': '1', 23:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:13 INFO - 'PWD': '/builds/slave/test', 23:49:13 INFO - 'SHELL': '/bin/bash', 23:49:13 INFO - 'SHLVL': '1', 23:49:13 INFO - 'TERM': 'linux', 23:49:13 INFO - 'TMOUT': '86400', 23:49:13 INFO - 'USER': 'cltbld', 23:49:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:13 INFO - '_': '/tools/buildbot/bin/python'} 23:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:13 INFO - Downloading/unpacking jsonschema==2.5.1 23:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:49:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:16 INFO - Downloading functools32-3.2.3-2.tar.gz 23:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:49:16 INFO - warning: no files found matching '*.txt' 23:49:16 INFO - no previously-included directories found matching 'build' 23:49:16 INFO - no previously-included directories found matching 'dist' 23:49:16 INFO - no previously-included directories found matching '.git*' 23:49:16 INFO - Installing collected packages: jsonschema, functools32 23:49:16 INFO - Running setup.py install for functools32 23:49:16 INFO - warning: no files found matching '*.txt' 23:49:16 INFO - no previously-included directories found matching 'build' 23:49:16 INFO - no previously-included directories found matching 'dist' 23:49:16 INFO - no previously-included directories found matching '.git*' 23:49:16 INFO - Successfully installed jsonschema functools32 23:49:16 INFO - Cleaning up... 23:49:16 INFO - Return code: 0 23:49:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:49:16 INFO - Using env: {'DISPLAY': ':0', 23:49:16 INFO - 'HOME': '/home/cltbld', 23:49:16 INFO - 'LANG': 'en_US.UTF-8', 23:49:16 INFO - 'LANGUAGE': 'en_US:en', 23:49:16 INFO - 'LOGNAME': 'cltbld', 23:49:16 INFO - 'MAIL': '/var/mail/cltbld', 23:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:16 INFO - 'MOZ_NO_REMOTE': '1', 23:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:16 INFO - 'NO_EM_RESTART': '1', 23:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:16 INFO - 'PWD': '/builds/slave/test', 23:49:16 INFO - 'SHELL': '/bin/bash', 23:49:16 INFO - 'SHLVL': '1', 23:49:16 INFO - 'TERM': 'linux', 23:49:16 INFO - 'TMOUT': '86400', 23:49:16 INFO - 'USER': 'cltbld', 23:49:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:16 INFO - '_': '/tools/buildbot/bin/python'} 23:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:49:16 INFO - Cleaning up... 23:49:16 INFO - Return code: 0 23:49:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:49:16 INFO - Using env: {'DISPLAY': ':0', 23:49:16 INFO - 'HOME': '/home/cltbld', 23:49:16 INFO - 'LANG': 'en_US.UTF-8', 23:49:16 INFO - 'LANGUAGE': 'en_US:en', 23:49:16 INFO - 'LOGNAME': 'cltbld', 23:49:16 INFO - 'MAIL': '/var/mail/cltbld', 23:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:16 INFO - 'MOZ_NO_REMOTE': '1', 23:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:16 INFO - 'NO_EM_RESTART': '1', 23:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:16 INFO - 'PWD': '/builds/slave/test', 23:49:16 INFO - 'SHELL': '/bin/bash', 23:49:16 INFO - 'SHLVL': '1', 23:49:16 INFO - 'TERM': 'linux', 23:49:16 INFO - 'TMOUT': '86400', 23:49:16 INFO - 'USER': 'cltbld', 23:49:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:16 INFO - '_': '/tools/buildbot/bin/python'} 23:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:16 INFO - Downloading/unpacking blobuploader==1.2.4 23:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:18 INFO - Downloading blobuploader-1.2.4.tar.gz 23:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:49:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:49:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:19 INFO - Downloading docopt-0.6.1.tar.gz 23:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:49:19 INFO - Installing collected packages: blobuploader, requests, docopt 23:49:19 INFO - Running setup.py install for blobuploader 23:49:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:49:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:49:19 INFO - Running setup.py install for requests 23:49:20 INFO - Running setup.py install for docopt 23:49:20 INFO - Successfully installed blobuploader requests docopt 23:49:20 INFO - Cleaning up... 23:49:20 INFO - Return code: 0 23:49:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:49:20 INFO - Using env: {'DISPLAY': ':0', 23:49:20 INFO - 'HOME': '/home/cltbld', 23:49:20 INFO - 'LANG': 'en_US.UTF-8', 23:49:20 INFO - 'LANGUAGE': 'en_US:en', 23:49:20 INFO - 'LOGNAME': 'cltbld', 23:49:20 INFO - 'MAIL': '/var/mail/cltbld', 23:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:20 INFO - 'MOZ_NO_REMOTE': '1', 23:49:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:20 INFO - 'NO_EM_RESTART': '1', 23:49:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:20 INFO - 'PWD': '/builds/slave/test', 23:49:20 INFO - 'SHELL': '/bin/bash', 23:49:20 INFO - 'SHLVL': '1', 23:49:20 INFO - 'TERM': 'linux', 23:49:20 INFO - 'TMOUT': '86400', 23:49:20 INFO - 'USER': 'cltbld', 23:49:20 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:20 INFO - '_': '/tools/buildbot/bin/python'} 23:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:49:20 INFO - Running setup.py (path:/tmp/pip-I7Wn8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:49:20 INFO - Running setup.py (path:/tmp/pip-xMj5F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:49:20 INFO - Running setup.py (path:/tmp/pip-k2b8si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:49:20 INFO - Running setup.py (path:/tmp/pip-JKkouL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:49:20 INFO - Running setup.py (path:/tmp/pip-NMFyKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:49:20 INFO - Running setup.py (path:/tmp/pip-VRc5QX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:49:20 INFO - Running setup.py (path:/tmp/pip-k1B8lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:49:21 INFO - Running setup.py (path:/tmp/pip-E3Yh4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:49:21 INFO - Running setup.py (path:/tmp/pip-9V2Rok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:49:21 INFO - Running setup.py (path:/tmp/pip-WAemNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:49:21 INFO - Running setup.py (path:/tmp/pip-eFMlXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:49:21 INFO - Running setup.py (path:/tmp/pip-3ED6g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:49:21 INFO - Running setup.py (path:/tmp/pip-VJghd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:49:21 INFO - Running setup.py (path:/tmp/pip-KrrinP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:21 INFO - Running setup.py (path:/tmp/pip-kRz75s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:49:21 INFO - Running setup.py (path:/tmp/pip-3TGtbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:49:21 INFO - Running setup.py (path:/tmp/pip-T0zVv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:49:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:49:21 INFO - Running setup.py install for manifestparser 23:49:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:49:21 INFO - Running setup.py install for mozcrash 23:49:21 INFO - Running setup.py install for mozdebug 23:49:22 INFO - Running setup.py install for mozdevice 23:49:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Running setup.py install for mozfile 23:49:22 INFO - Running setup.py install for mozhttpd 23:49:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Running setup.py install for mozinfo 23:49:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Running setup.py install for mozInstall 23:49:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Running setup.py install for mozleak 23:49:22 INFO - Running setup.py install for mozlog 23:49:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Running setup.py install for moznetwork 23:49:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:49:22 INFO - Running setup.py install for mozprocess 23:49:23 INFO - Running setup.py install for mozprofile 23:49:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:49:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:49:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:49:23 INFO - Running setup.py install for mozrunner 23:49:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:49:23 INFO - Running setup.py install for mozscreenshot 23:49:23 INFO - Running setup.py install for moztest 23:49:23 INFO - Running setup.py install for mozversion 23:49:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:49:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:49:23 INFO - Cleaning up... 23:49:23 INFO - Return code: 0 23:49:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:49:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:49:23 INFO - Using env: {'DISPLAY': ':0', 23:49:23 INFO - 'HOME': '/home/cltbld', 23:49:23 INFO - 'LANG': 'en_US.UTF-8', 23:49:23 INFO - 'LANGUAGE': 'en_US:en', 23:49:23 INFO - 'LOGNAME': 'cltbld', 23:49:23 INFO - 'MAIL': '/var/mail/cltbld', 23:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:23 INFO - 'MOZ_NO_REMOTE': '1', 23:49:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:23 INFO - 'NO_EM_RESTART': '1', 23:49:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:23 INFO - 'PWD': '/builds/slave/test', 23:49:23 INFO - 'SHELL': '/bin/bash', 23:49:23 INFO - 'SHLVL': '1', 23:49:23 INFO - 'TERM': 'linux', 23:49:23 INFO - 'TMOUT': '86400', 23:49:23 INFO - 'USER': 'cltbld', 23:49:23 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:23 INFO - '_': '/tools/buildbot/bin/python'} 23:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:49:23 INFO - Running setup.py (path:/tmp/pip-ZtWoUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:49:23 INFO - Running setup.py (path:/tmp/pip-zgFPuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:49:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:49:23 INFO - Running setup.py (path:/tmp/pip-gNnwCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:49:24 INFO - Running setup.py (path:/tmp/pip-eeOjDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:49:24 INFO - Running setup.py (path:/tmp/pip-JvWXOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:49:24 INFO - Running setup.py (path:/tmp/pip-5pCa3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:49:24 INFO - Running setup.py (path:/tmp/pip-GpIvO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:49:24 INFO - Running setup.py (path:/tmp/pip-2Qfxcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:49:24 INFO - Running setup.py (path:/tmp/pip-dhmzsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:49:24 INFO - Running setup.py (path:/tmp/pip-Yzwxcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:49:24 INFO - Running setup.py (path:/tmp/pip-ItVfEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:49:24 INFO - Running setup.py (path:/tmp/pip-vo8xPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:49:24 INFO - Running setup.py (path:/tmp/pip-Nvk5dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:49:24 INFO - Running setup.py (path:/tmp/pip-kcOdX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:24 INFO - Running setup.py (path:/tmp/pip-e0LbXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:49:24 INFO - Running setup.py (path:/tmp/pip-qy_Mkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:49:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:49:25 INFO - Running setup.py (path:/tmp/pip-fCIUj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 23:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:49:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:27 INFO - Downloading blessings-1.6.tar.gz 23:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:49:27 INFO - Installing collected packages: blessings 23:49:27 INFO - Running setup.py install for blessings 23:49:27 INFO - Successfully installed blessings 23:49:27 INFO - Cleaning up... 23:49:27 INFO - Return code: 0 23:49:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:49:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:49:27 INFO - Reading from file tmpfile_stdout 23:49:27 INFO - Current package versions: 23:49:27 INFO - argparse == 1.2.1 23:49:27 INFO - blessings == 1.6 23:49:27 INFO - blobuploader == 1.2.4 23:49:27 INFO - docopt == 0.6.1 23:49:27 INFO - functools32 == 3.2.3-2 23:49:27 INFO - jsonschema == 2.5.1 23:49:27 INFO - manifestparser == 1.1 23:49:27 INFO - mozInstall == 1.12 23:49:27 INFO - mozcrash == 1.0 23:49:27 INFO - mozdebug == 0.1 23:49:27 INFO - mozdevice == 0.49 23:49:27 INFO - mozfile == 1.2 23:49:27 INFO - mozhttpd == 0.7 23:49:27 INFO - mozinfo == 0.9 23:49:27 INFO - mozleak == 0.1 23:49:27 INFO - mozlog == 3.4 23:49:27 INFO - moznetwork == 0.27 23:49:27 INFO - mozprocess == 0.24 23:49:27 INFO - mozprofile == 0.28 23:49:27 INFO - mozrunner == 6.13 23:49:27 INFO - mozscreenshot == 0.1 23:49:27 INFO - mozsystemmonitor == 0.3 23:49:27 INFO - moztest == 0.8 23:49:27 INFO - mozversion == 1.4 23:49:27 INFO - psutil == 3.1.1 23:49:27 INFO - requests == 1.2.3 23:49:27 INFO - wsgiref == 0.1.2 23:49:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:49:27 INFO - Using env: {'DISPLAY': ':0', 23:49:27 INFO - 'HOME': '/home/cltbld', 23:49:27 INFO - 'LANG': 'en_US.UTF-8', 23:49:27 INFO - 'LANGUAGE': 'en_US:en', 23:49:27 INFO - 'LOGNAME': 'cltbld', 23:49:27 INFO - 'MAIL': '/var/mail/cltbld', 23:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:27 INFO - 'MOZ_NO_REMOTE': '1', 23:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:27 INFO - 'NO_EM_RESTART': '1', 23:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:27 INFO - 'PWD': '/builds/slave/test', 23:49:27 INFO - 'SHELL': '/bin/bash', 23:49:27 INFO - 'SHLVL': '1', 23:49:27 INFO - 'TERM': 'linux', 23:49:27 INFO - 'TMOUT': '86400', 23:49:27 INFO - 'USER': 'cltbld', 23:49:27 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:27 INFO - '_': '/tools/buildbot/bin/python'} 23:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:49:27 INFO - Cleaning up... 23:49:27 INFO - Return code: 0 23:49:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:49:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16721f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x183fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19e4980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 23:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:49:27 INFO - Using env: {'DISPLAY': ':0', 23:49:27 INFO - 'HOME': '/home/cltbld', 23:49:27 INFO - 'LANG': 'en_US.UTF-8', 23:49:27 INFO - 'LANGUAGE': 'en_US:en', 23:49:27 INFO - 'LOGNAME': 'cltbld', 23:49:27 INFO - 'MAIL': '/var/mail/cltbld', 23:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:27 INFO - 'MOZ_NO_REMOTE': '1', 23:49:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:27 INFO - 'NO_EM_RESTART': '1', 23:49:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:27 INFO - 'PWD': '/builds/slave/test', 23:49:27 INFO - 'SHELL': '/bin/bash', 23:49:27 INFO - 'SHLVL': '1', 23:49:27 INFO - 'TERM': 'linux', 23:49:27 INFO - 'TMOUT': '86400', 23:49:27 INFO - 'USER': 'cltbld', 23:49:27 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:27 INFO - '_': '/tools/buildbot/bin/python'} 23:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:49:27 INFO - Cleaning up... 23:49:27 INFO - Return code: 0 23:49:27 INFO - Running post-action listener: _resource_record_post_action 23:49:28 INFO - Running post-action listener: _start_resource_monitoring 23:49:28 INFO - Starting resource monitoring. 23:49:28 INFO - [mozharness: 2017-02-08 07:49:28.034018Z] Finished create-virtualenv step (success) 23:49:28 INFO - [mozharness: 2017-02-08 07:49:28.034513Z] Running install step. 23:49:28 INFO - Running pre-action listener: _resource_record_pre_action 23:49:28 INFO - Running main action method: install 23:49:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:49:28 INFO - Reading from file tmpfile_stdout 23:49:28 INFO - Detecting whether we're running mozinstall >=1.0... 23:49:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:49:28 INFO - Reading from file tmpfile_stdout 23:49:28 INFO - Output received: 23:49:28 INFO - Usage: mozinstall [options] installer 23:49:28 INFO - Options: 23:49:28 INFO - -h, --help show this help message and exit 23:49:28 INFO - -d DEST, --destination=DEST 23:49:28 INFO - Directory to install application into. [default: 23:49:28 INFO - "/builds/slave/test"] 23:49:28 INFO - --app=APP Application being installed. [default: firefox] 23:49:28 INFO - mkdir: /builds/slave/test/build/application 23:49:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 23:49:44 INFO - Reading from file tmpfile_stdout 23:49:44 INFO - Output received: 23:49:44 INFO - /builds/slave/test/build/application/firefox/firefox 23:49:44 INFO - Running post-action listener: _resource_record_post_action 23:49:44 INFO - [mozharness: 2017-02-08 07:49:44.808448Z] Finished install step (success) 23:49:44 INFO - [mozharness: 2017-02-08 07:49:44.808585Z] Running run-tests step. 23:49:44 INFO - Running pre-action listener: _resource_record_pre_action 23:49:44 INFO - Running main action method: run_tests 23:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:49:44 INFO - Python 2.7.3 23:49:44 INFO - Return code: 0 23:49:44 INFO - grabbing minidump binary from tooltool 23:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:49:44 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e1d00>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19e2a00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e2eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:49:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:49:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 23:49:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:49:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:49:44 INFO - Return code: 0 23:49:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:49:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:49:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:49:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:49:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:49:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:49:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:49:44 INFO - Using env: {'DISPLAY': ':0', 23:49:44 INFO - 'HOME': '/home/cltbld', 23:49:44 INFO - 'LANG': 'en_US.UTF-8', 23:49:44 INFO - 'LANGUAGE': 'en_US:en', 23:49:44 INFO - 'LOGNAME': 'cltbld', 23:49:44 INFO - 'MAIL': '/var/mail/cltbld', 23:49:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:49:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:44 INFO - 'MOZ_NO_REMOTE': '1', 23:49:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:49:44 INFO - 'NO_EM_RESTART': '1', 23:49:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:44 INFO - 'PWD': '/builds/slave/test', 23:49:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:49:44 INFO - 'SHELL': '/bin/bash', 23:49:44 INFO - 'SHLVL': '1', 23:49:44 INFO - 'TERM': 'linux', 23:49:44 INFO - 'TMOUT': '86400', 23:49:44 INFO - 'USER': 'cltbld', 23:49:44 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230', 23:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:44 INFO - '_': '/tools/buildbot/bin/python'} 23:49:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:49:45 INFO - mozversion application_buildid: 20170208065056 23:49:45 INFO - mozversion application_changeset: 82c75fd3a2de796351296592c459ab4aa4cd0baf 23:49:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:49:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:49:45 INFO - mozversion application_name: Firefox 23:49:45 INFO - mozversion application_remotingname: firefox-dev 23:49:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:49:45 INFO - mozversion application_vendor: Mozilla 23:49:45 INFO - mozversion application_version: 53.0a2 23:49:45 INFO - mozversion platform_buildid: 20170208065056 23:49:45 INFO - mozversion platform_changeset: 82c75fd3a2de796351296592c459ab4aa4cd0baf 23:49:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:49:45 INFO - mozversion platform_version: 53.0a2 23:49:45 INFO - using testdate: 1486540185 23:49:45 INFO - actual date: 1486540185 23:49:45 INFO - starting webserver on 'localhost:41536' 23:49:45 INFO - SUITE-START | Running 2 tests 23:49:45 INFO - TEST-START | dromaeo_css 23:49:45 INFO - operating with platform_type : linux_ 23:49:45 INFO - Initialising browser for dromaeo_css test... 23:49:45 INFO - TEST-INFO | started process 993 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphWU3mq/profile http://localhost:41536/getInfo.html) 23:49:49 INFO - PROCESS | 993 | __metrics Screen width/height:1600/1200 23:49:49 INFO - PROCESS | 993 | colorDepth:24 23:49:49 INFO - PROCESS | 993 | Browser inner width/height: 1024/697 23:49:49 INFO - PROCESS | 993 | __metrics 23:49:49 INFO - PROCESS | 993 | 1486540189587 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 23:49:49 INFO - PROCESS | 993 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:49:49 INFO - PROCESS | 993 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:49:50 INFO - PROCESS | 993 | console.error: PushService: 23:49:50 INFO - PROCESS | 993 | stateChangeProcessEnqueue: Error transitioning state 23:49:50 INFO - PROCESS | 993 | UnknownError 23:49:50 INFO - TEST-INFO | 993: exit 0 23:49:50 INFO - Browser initialized. 23:49:50 INFO - Running cycle 1/1 for dromaeo_css test... 23:49:50 INFO - TEST-INFO | started process 1096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphWU3mq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:50:02 INFO - PROCESS | 1096 | RSS: Main: 138231808 23:50:02 INFO - PROCESS | 1096 | 23:53:02 INFO - PROCESS | 1096 | Cycle 1(1): loaded http://localhost:41536/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41536/tests/dromaeo/cssquery-ext.html) 23:53:04 INFO - PROCESS | 1096 | RSS: Main: 243761152 23:53:04 INFO - PROCESS | 1096 | 23:54:52 INFO - PROCESS | 1096 | ************************* 23:54:52 INFO - PROCESS | 1096 | A coding exception was thrown and uncaught in a Task. 23:54:52 INFO - PROCESS | 1096 | 23:54:52 ERROR - PROCESS | 1096 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:54:52 INFO - PROCESS | 1096 | Full stack: 23:54:52 INFO - PROCESS | 1096 | ************************* 23:56:06 INFO - PROCESS | 1096 | Cycle 1(1): loaded http://localhost:41536/tests/dromaeo/cssquery-ext.html (next: http://localhost:41536/tests/dromaeo/cssquery-jquery.html) 23:56:06 INFO - PROCESS | 1096 | RSS: Main: 233213952 23:56:06 INFO - PROCESS | 1096 | 23:59:08 INFO - PROCESS | 1096 | Cycle 1(1): loaded http://localhost:41536/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41536/tests/dromaeo/cssquery-mootools.html) 23:59:09 INFO - PROCESS | 1096 | RSS: Main: 219033600 23:59:09 INFO - PROCESS | 1096 | 00:02:11 INFO - PROCESS | 1096 | Cycle 1(1): loaded http://localhost:41536/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41536/tests/dromaeo/cssquery-prototype.html) 00:02:11 INFO - PROCESS | 1096 | RSS: Main: 212234240 00:02:11 INFO - PROCESS | 1096 | 00:05:14 INFO - PROCESS | 1096 | Cycle 1(1): loaded http://localhost:41536/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41536/tests/dromaeo/cssquery-yui.html) 00:05:15 INFO - PROCESS | 1096 | RSS: Main: 172290048 00:05:15 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | Cycle 1(1): loaded http://localhost:41536/tests/dromaeo/cssquery-yui.html (next: http://localhost:41536/tests/dromaeo/cssquery-dojo.html) 00:08:17 INFO - PROCESS | 1096 | RSS: Main: 258420736 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | __start_tp_report 00:08:17 INFO - PROCESS | 1096 | _x_x_mozilla_page_load 00:08:17 INFO - PROCESS | 1096 | _x_x_mozilla_page_load_details 00:08:17 INFO - PROCESS | 1096 | |i|pagename|runs| 00:08:17 INFO - PROCESS | 1096 | |0;dojo.html;3280.5571247881535;3324.418226810309;3337;3358.2443950111215;3379;5419.3225846769155;5489.752961116749;5499.50504454599;5506.834794956148;5523.033469142894;5478.004754381856;5533.833984980447;5550.028744969624;5562.0266453370705;5567.721613919298;4945.678530895499;4956.851294461172;4986.551210391064;4993.226049962243;5052.9747351263195;4857.854264372075;4984.725840078794;5034.119029169899;5035.194368900977;5069.391672999245;4813.518648135194;4834.830780922651;4865.975670121645;4875.829345972892;4952.752362381884;4931.679440836334;4966.230234313687;4966.776495057695;4969.981153863468;4995.225740010304;4781.0676918001;4884.120858245515;4895.9510404895855;4905.288733133676;4923.975380123113;5274.736263186825;5345.732713364317;5412.91880621791;5428.647137936022;5498.697571633558;5847.766089356463;5865;5867.061270196748;5871.647701137946;5873.559483038789;5909.852253693649;5915.112733090028;5917.940820591825;5928.229330187091;5931.555133364972;5379.865503362408;5431.266778984826;5475.863103422446;5527.5577953764405;5529.253550770594;6026.0659597762415;6033.3664965178905;6033.728482218311;6041.667708275999;6069.241344831896;5769.9711501442225;5781.075027995501;5803.970980145073;5818.796342128006;5821.621594596338;5689.800856970069;5701.0308247598;5715.914261286084;5737.99585072619;5938.940610593839;5759.308882933991;5759.992001399738;5762.164486149569;5787.392323806024;5796.014677504834;5769.278840144982;5777.393373695785;5777.62445441045;5778.393268706809;5783.537317014629;4450.777461126931;4460.732356058582;4486.304622783474;4492.168948744493;4519.864404067883;3972.741771784825;4056.411820286042;4072.3484242521054;4074.30736774749;4083.1629515949157;5614.494695477427;5638.548916086703;5665.773369065274;5668.376478587352;5673.886522269531;5586.106223004381;5656.123300888369;5768.3366412862215;5827.213326200994;5872.060470324727;5666.46168613981;5682.119271512839;5684.971575142098;5706.429357064343;5713.171590119493;4975.4527002029745;4981.476944920803;4984.40187177541;4990.800367985313;5007.098722229961;5923.496502797224;5952.732127054206;6015;6140.815775526741;6143.877122457615;5806.709664516673;5856.297244330537;5866.354700982974;5867.178594996789;5896.469317761422;6044.365341639241;6088.786892458743;6094.573379863367;6101.969490152521;6105.938940610537;5604.635698679573;5666.065099258576;5729.627574207663;5791.305043394819;5796.652200867961;5861.062230043172;5921.940780592311;5922.3485416605;5973.313068997121;6011.398860113954;5780.624259423124;5812.505936995402;5817.563682723728;5821.679807610452;5824.097264923944;5538.91691624634;5759.424057594207;5813.970930145322;5819.912701309569;5822.3304320003745;5526.723663816873;5569.749361278671;5613.326400831927;5618.381977982501;5685.118806584986;5661.264035675335;5674.4325567444575;5696.9715151425635;5697.23087383198;5704.229928959538;5527.170924361378;5567.665940043448;5573.108302671553;5573.916391254053;5581.441855814549 00:08:17 INFO - PROCESS | 1096 | |1;ext.html;565.6719102920307;589.9262592175978;624.2103738770456;644.8774732800764;661.1603263854902;36481.81759091204;49115;49697.50302496974;49814.501854981485;49819.750901245534;52162.47837521623;52487.73756131214;52590.47409525904;52690.73654631722;52731;8884.422512536665;9106.271498280155;9432.669856555021;9621.422714637109;9632.855507167398;11192.720181995433;11466.082713382952;11478.885211147865;11915.225510341843;11916.523339066427;11670.883291167065;11763.529458821637;11767.941160294187;11776.941115294454;11779.764404711901;11682.415879206048;11733.120015998835;11736.354500502553;11747.706307342341;11760.235584686969;11099.278546894508;11177.273477223955;11179.664610061629;11181.273217240854;11183.105351571854;49105.50894491045;49585.504144958446;49592.504074959514;49640.75179624079;49649;4561.566651168134;4586.54134586542;4677.602403795681;4728.976355118237;4758.214894542396;35734.463983040274;36004;36067;36360.272794544224;37721.62278377209;4728.716277023355;4743.454502732196;4762.738049407281;4765.118453086156;4820.758962051918;353136;354196;354377;356634;358163;71602;72055.6397218021;72063;72069;72099;73483.63258183675;73802.26197738061;74167;74251;74276.62861685657;18462;19297.614047718966;19409.708854367196;19591.11839967205;19595;13261.071724979352;13477.326133693276;14159.221242831743;14578.562643120724;14586.270686465634;20083.698744518842;20169.899150504447;20175.192992280437;20248.190072397232;20329;6580.21037475506;6630.86738265232;6698.966505167443;6810.148731408574;6925.134358205224;1015.57345914716;1021.9642312519026;1032.2516175772655;1037.2842738510403;1041.6874937518714;35289.64710352864;35875.82062089673;36478.63521364806;36485;36564.634353656125;1182.9290242585473;1185.3125187391292;1197.275648232824;1232.9876701232872;1293.204679122333;18446.538836528925;18567.16447759854;18690.71963920542;18881.15034823436;18965.715514267296;519618;519809;521001;524344;524828;72353.2764672357;72377.27622723706;72389;72437;72593.63703181555;1515.5226103777275;1572.866306363949;1580.9446669366516;1582.9920850395904;1590.8727301815827;33483.832580837916;34798.65201347954;34865;34879.12802179966;35109.82445087758;60871.69564152151;61478;61507.38492615017;61548.69225653843;61617;413178;415490;415957;418274;418871;41374.37938430866;43555.56444435515;46190.53809461863;46660.900259487724;46792.766036170964;54279.72860135674;54928.72535637296;55237.44762552323;55247;55381.44618553763;45622.54377456183;46374.76812615915;46646.76676616595;47000;48109.278360825316;44858.55141448544;45694.543054569025;45776.31335529903;46568;47791;45381;45827.3125903105;46080.53919460763;46099.30851037304;46407;37397.81301093586;39406.2118757629;39421.60578394179;39672;40540.1891962165;29933.251668707584;30009.249768755955;30019.249518762204;30044;30049.549256761602 00:08:17 INFO - PROCESS | 1096 | |2;jquery.html;435.68412900647013;449.58862640683736;455.23293250872285;455.89514411685303;456.52065331402025;21365.786342136573;21542.784572154294;22083.558328833413;22366.32901012968;22371.217007404743;13579.117357371788;13712.725745485084;13857.722845543083;13974.58076257714;14041.157530548175;5712.99999999999;6278.529110316722;6287.434130928215;6292.5595208335435;6294.339094395092;4440.023194897133;4655.348251244828;6211.440970312671;6311.274203466592;6426.164598602175;6519.67401629919;6524.967375163117;6540.215174179081;6540.542162048659;6542.476601871839;6313.747450101991;6428.678566071701;6454.967725161391;6461.063145843873;6463.321351258144;6126.816195514141;6267.71795269214;6311.652859092748;6339.683015849235;6345.175127233476;25774.87112564444;25942;26079.869600652066;26192.60711089335;26227.082052128273;2799.174243598143;2843.9004634837684;2907.331313797817;2922.298648324408;2945.1164650604946;11703.7074073148;11828.408579570987;11863.52545898163;14855.628609284746;16039.037657740573;1982.137770070024;2067.080149333547;2073.8651987620756;2079.3450063230184;2081.9375418737463;94310.52844735801;94755;95789.521052395;96802.51598742032;97176.51411742967;12726.80909786354;12808.80786788199;12884.162529435556;12915.935420323027;13081.607551773272;18367.08164591765;18386.264549417872;18387.264509419474;18402.723959140625;18408.723869141973;9014.729558113268;9199.954000229956;9276.25789936817;9338.225737109928;9632.566534505964;8975.416597921114;9033.548322583845;9037.63849446028;9099.339969810178;9199.126083022102;5536.640118392292;5551.528120109836;5577.386487486373;5585.888282234332;5723;1914.272576420979;2037.8981050947689;2162.4810045588947;2220.433789383697;2236.7986881180773;944.1691311645708;965.2133511188359;973.1873885305849;975.9756006099763;983.7196399026243;20233.89883050605;21288.25491107804;21399.8930005349;21417.89291053535;21465.785342146377;977.4477420257532;979.3536266064364;981.8625392445064;986.2455221755363;1000.2792404659315;11585;12933.417996190006;12984.285864277366;13036.543720969721;13306;686643;750667;752145;764855;779093;109700.45149774359;110676;111928.44035779769;112539.43730281296;114151.42924285325;1106.4965440724452;1156.6992581928591;1170.1750266062236;1175.988240117605;1197.6826141072622;19766.604667906275;20186.899065504578;20222.494437639176;20282.492937676678;20310.695339569622;48880.26679599738;49508.75245623749;49715.50284497109;49799;51059;64144.67927660332;64900.35099649132;65006;66179.33820661732;66652;8099.878501822595;9099.226565741987;9197.724068277963;9515.286353523376;9813.803723925339;13163.407646656115;14759.631009224426;14946.850531494982;15258.160801156044;15554.922225389253;12416.565420210249;12728;12865.292408917225;12963.675908101995;13281.535146269835;12190.573329933764;12557.686057848627;12657.240565566095;12856.100072994801;12882.806757898827;11909.464074116859;12618.873811262138;12876.613701588967;13404.128731632412;13575.660608484866;9850.211983041196;9890.752731181778;9902.257330700082;9935.552900119657;10130.848037279595;11859.288442693465;11942.223755455867;12323.876761232274;12539.93730031344;12727.363631818558 00:08:17 INFO - PROCESS | 1096 | |3;mootools.html;1427.250693385973;1453.1426458389556;1459.824821021477;1462.3638717158033;1469.5885152157396;8784.692535761249;9296.8605470918;9362.297827662918;9378.343515953895;9384.624615015407;7995.7601271961785;8279.130691277405;8297.336213102952;8460.830783384328;9221.40060896043;2420.4916967436816;2513.3590934311815;2518.1690042286095;2541.2503311523146;2543.9618405723927;2478.3060742991993;2506.636537702032;2513.761192686701;2526.886290116949;2532.341591186296;2496.425822060927;2496.900123995038;2512.384465805871;2517.937051573707;2526.393665520271;2380.583397905368;2402.315340128055;2402.483466054789;2411.011485291031;2412.5777988851883;2400.627902675088;2452.969752703869;2506.5613517634342;2508.673872396595;2511.7488251174736;9327;9387.389819661701;9389.201917836992;9396.67111651089;9408.670696525589;5608.915866262009;5770.740316685759;5778.711064446761;5784.652920824764;5787.50806181475;3487.8256087195537;3552.3428165789155;3727.925441491182;3775.2260786538695;3785.905352366213;1456.7814827775812;1485.8217013958285;1537.853903879141;1560.5162399656028;1565.4755656854854;12771.233725976379;12808.80786788199;12822.166559173716;12824.743505129938;12824.80762788559;4929.433115191799;5123.231515272739;5180.274761533313;5194.35070616173;5195.974020129951;10907.18196135293;10908.836367454494;10910.23628346024;10910.618128365628;10921.781564368668;5835.095560188178;5840;5842.707864606753;5844.970775146097;5847.883042339215;4619.630429565626;5154.407243167008;5271.947280527223;5308.336457942757;5328.520433160957;4001.6198461146146;4235.512916014636;4320.6605952154305;4339.414179085783;4366.454193225847;1365.9453621855214;1376.9449222031208;1389.638693939583;1404.2978510744629;1404.3048690898072;999.7850462150673;1006.5319626373771;1015.918726501878;1018.7911478146962;1021.402479549457;5866.70666466675;5873.676947767914;5901.17383566301;5946.67293298864;6040.305364883006;1077.2997551591527;1085.9022687957963;1090.3922231278477;1095.6220104064084;1110.655696734015;3870.9806450967567;3896.298666240047;3977.801109944549;3985.628551012201;4003.779792111462;10994;11316.094712422986;11350.09199264057;11373.658790236306;11419.429028548539;10964.342139471657;11185.888141118485;11200.551977920955;11234.831477527843;11376.886231137749;8646.048934617187;8700.651973920973;8776.60505277264;8790.42862213954;8794.692185773467;5709.942900570941;5825.359210486928;5878.118282257696;5948.940510594839;5971.134185542985;9583.90416095858;10323.122534584645;10347.689569312632;10362.844557331495;10390.064894159563;10268.845967310646;10526.526306316387;10530.842037369599;10588.258821882395;10626.574937002433;8129.430939834155;8266.669333226844;8271.172882711922;8308.293795027248;8340.916590834015;7911.327537159406;8203.753887383387;8400.327973761961;8451.45065570736;8506.447080939719;7738.613069346623;7779.105402878741;7800;7880.56656883854;7982.9600851995365;7352.742654007084;7500.962495187708;7521.5111017783665;7541.208173141741;7541.660625271986;6807.489438292247;6925.411340036153;6929.237783843874;6932.757353492603;6933;5198.116320225647;5203.141481655484;5232.5552328052545;5248.291480650063;5413.756380962787;5311.734413279243;5314.707691077027;5336.706481143575;5386.245925570345;5490.890182196414 00:08:17 INFO - PROCESS | 1096 | |4;prototype.html;407.2181411689556;411.92293567054105;415.50554839740704;427.9251131052065;431.4218946611538;5353.73231338433;5368.355797304324;5385.973070134653;5395.352557693072;5399.838004859851;5099;5181.248718935752;5233.86915327117;5302.522772950434;5352.036633405985;4244.193603215394;4253;4264.272969447371;4274;4279.1441711657635;4268.253055715253;4274.893127671802;4277.957220427787;4278.957210427902;4285.892852678692;3972.6226008529143;4088.366303223005;4209.389638502415;4232.788360581974;4248.808803603845;4176.665866730655;4182.958170418287;4216.51510076342;4219.3249080147025;4245.214635292481;3803.8098095095133;3811.3711237645757;3822.8279727412055;3846.2692088503377;3865.9703655581043;5150.755024334682;5165.535101840815;5191.402988656315;5226.268322434862;5259.263703081571;4880.6095512358925;4899.559039686445;4921.237208232729;4938.950610493885;4993;5568.52667523261;5573.27547418833;5587.357453892813;5604.411536788619;5610.887782244332;4148.79256037197;4303.225419424502;4306.676999225039;4316.978415107905;4321.24378233811;5716.542676585863;5723.198752174699;5743;5762.625429347121;5773.680133923011;4400.471943366816;4406.823727050882;4409.603135717736;4418.491873434532;4423.248047831812;4379.627731642846;4457.258785846746;4458.9331160032625;4465.307877279027;4467.7095988761275;4909.10362885899;4913.803447862046;4916.803327866917;4919.680220785637;4958.413467542581;5625.268715067097;5629.549636029108;5631.971840140855;5634.464725851038;5641.633293835887;1452.716720239543;1482.9406823726931;1485.3241774992352;1497.7528707763097;1516.5071351810705;1020.8311483351521;1049.7375656085978;1064.2762921213648;1079.1636481726568;1079.9946000270106;5266.657667251693;5337.226102215234;5342.248741897925;5342.572594192456;5351.812686555952;5465.480779325958;5573.331200255995;5596.048671725816;5618;5649.180868774005;5025.547700706881;5224.648907991419;5268.946210757864;5282.8943421132135;5343.251944727741;2891.320539673175;2916.314666053476;2932.3255651200343;2936.882524699031;2941.3676059647278;5506.834794956158;5716.714164291769;5727.3699893011735;5744.281964754406;5748.798792042258;5573.637713548606;5577.637453565587;5593.6854839112775;5612.828751755245;5627.887442251132;5745.712714364265;5746.88506229873;5772.884542309131;5777.4400911752755;5779.306483222041;5266.97366513165;5442.782288708408;5479.2602998594675;5485.369182543899;5518.530924871431;5397;5433.505785908907;5447.400785913625;5448.210009548514;5453.427390124139;5487.286652735119;5731.96206010815;5743.195952566561;5760;5790.565707571865;4310.383606147695;4351.912961740811;4355.082094996424;4366.890827729332;4367.759773212504;5524.696141712245;5545.7781688732;5550.722463876871;5584.565492222336;5598.720063996702;5276.34045744282;5419.83740487786;5441.809536666197;5461.1808228766;5465.7813687453645;5313.388960269618;5333.973330133324;5357.1696387060065;5372.650777699437;5387.730613469233;5313.229581710708;5336.706481143575;5359.651622644516;5381.412483317353;5471.671699698031;5429.484199001088;5515.972420137874;5533.197686335378;5535.25274087993;5536.390996990408;5423.810166644149;5432.7555260012605;5483.725813709379;5488.670679759227;5495 00:08:17 INFO - PROCESS | 1096 | |5;yui.html;834.4534330013843;836.7782537627534;895.7044175422111;898.7127759851178;934.897161312256;16431.260593273302;17505.124743762826;17506;17660.47018589441;17691.29234830605;13587.048906576543;15283.465078722247;15533.844661553381;16202.594935126599;16630.50108496744;705.1045172630755;786.8426314737047;820.0159808230147;825.9463134896213;828.1015098618007;817.1052697296481;818.7011740714626;824.3075816314295;824.7113510271417;824.8309096635176;863.8445079885618;864.8054187807755;864.9394542382036;865;865.3380164174412;728.1735230513352;754.3889449545885;858.1547176031604;859.1751918158575;860.2129051917487;555.9805406810783;556.4407770190933;615.264758613457;641.7721708793358;642.2036674523604;17408.912955435142;17478.388256410966;17486.825131748647;17606.38377656776;17646.294148234054;15448.15035173065;15903.363865445368;16149.919250403791;16256.75614865778;16501.752473712902;14449;14459.927700361535;14572;14656.560303190921;14677.853221467756;4662.953370466286;4706.082313948781;4766.6901651392545;4797.4482934462285;4800.639952003614;78367.60816195882;78771.6061419695;79273;79995;80303.5984820078;12388.194767340094;12473.501059957682;12480.563180288827;12495.562655307021;12741.55404560836;24446.877765611414;24451.510969780506;24552.877235614065;24789.380265493146;25040.123595674064;9050.411723237969;9157.496337701357;9209.35534512591;9256.213221876082;9298.488583127993;4847.297141914473;4866.026794641086;4873.147199240118;4923.556879880828;5056.241563765391;3653.232821107544;3751.6998640108727;3758.6053464385845;3760.8683696070507;3768.1333293342686;649.2533586375706;749.2432643030578;771.4831063187678;793.9444238903337;797.4856217739525;866.8699695045793;878.5431575580662;888.4225253585221;904.0507467159588;910.3081657939881;11319.547218111347;11414.885851141382;11458.94270528642;11655.475503602358;11664.766704665859;856.7130011446111;864.321507616518;864.4597126795753;865.8827276301148;868.3747701654798;5506.1190209566275;5517.641353312022;5623.746931388097;5625.46558076982;5637.295338082739;77891.61054194806;77974.610126949;78035.60982195052;79014;84036;70209.29790702027;73054;73596;73894.26105738981;75718.62140689371;65742.67128664421;66951;67587;68436.89378637298;68729;10586.31188972745;11041;11062;11100.833487497855;11220.943895280798;44198.77900610476;44749.328760067976;45237.32144017908;45941.54058459373;46452.30321545247;54341.45658543364;55551.72224138853;56132.43867561272;56498;59125.40874591371;13971.58085257444;14158.78761818553;14178.43404888944;14287.142771433748;14456.20490873012;14222.715545689236;14401.711965760836;14560.199189044704;14652.413903443743;15210.847891520943;14360.138391696531;14418.206998614758;14489.203093829941;14515.419383224553;14843.480478183212;13779;13971;13984.650383740081;13990.510332138327;14029.719405612035;13827.101238419471;13911.304434778018;14056.297185140907;14064.578062658136;14395.856041439452;11938.28370297785;12072;12326.938365308117;12679.302638354979;12938.935305323414;10700;11368.772624547297;11430.82853757211;11675.708107297385;11712.414379281172 00:08:17 INFO - PROCESS | 1096 | __end_tp_report 00:08:17 INFO - PROCESS | 1096 | __start_cc_report 00:08:17 INFO - PROCESS | 1096 | _x_x_mozilla_cycle_collect,992 00:08:17 INFO - PROCESS | 1096 | __end_cc_report 00:08:17 INFO - PROCESS | 1096 | __startTimestamp1486541297860__endTimestamp 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | ------- Summary: start ------- 00:08:17 INFO - PROCESS | 1096 | Number of tests: 6 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | [#0] dojo.html Cycles:165 Average:5439.71 Median:5666.07 stddev:601.81 (10.6%) stddev-sans-first:579.33 00:08:17 INFO - PROCESS | 1096 | Values: 3280.6 3324.4 3337.0 3358.2 3379.0 5419.3 5489.8 5499.5 5506.8 5523.0 5478.0 5533.8 5550.0 5562.0 5567.7 4945.7 4956.9 4986.6 4993.2 5053.0 4857.9 4984.7 5034.1 5035.2 5069.4 4813.5 4834.8 4866.0 4875.8 4952.8 4931.7 4966.2 4966.8 4970.0 4995.2 4781.1 4884.1 4896.0 4905.3 4924.0 5274.7 5345.7 5412.9 5428.6 5498.7 5847.8 5865.0 5867.1 5871.6 5873.6 5909.9 5915.1 5917.9 5928.2 5931.6 5379.9 5431.3 5475.9 5527.6 5529.3 6026.1 6033.4 6033.7 6041.7 6069.2 5770.0 5781.1 5804.0 5818.8 5821.6 5689.8 5701.0 5715.9 5738.0 5938.9 5759.3 5760.0 5762.2 5787.4 5796.0 5769.3 5777.4 5777.6 5778.4 5783.5 4450.8 4460.7 4486.3 4492.2 4519.9 3972.7 4056.4 4072.3 4074.3 4083.2 5614.5 5638.5 5665.8 5668.4 5673.9 5586.1 5656.1 5768.3 5827.2 5872.1 5666.5 5682.1 5685.0 5706.4 5713.2 4975.5 4981.5 4984.4 4990.8 5007.1 5923.5 5952.7 6015.0 6140.8 6143.9 5806.7 5856.3 5866.4 5867.2 5896.5 6044.4 6088.8 6094.6 6102.0 6105.9 5604.6 5666.1 5729.6 5791.3 5796.7 5861.1 5921.9 5922.3 5973.3 6011.4 5780.6 5812.5 5817.6 5821.7 5824.1 5538.9 5759.4 5814.0 5819.9 5822.3 5526.7 5569.7 5613.3 5618.4 5685.1 5661.3 5674.4 5697.0 5697.2 5704.2 5527.2 5567.7 5573.1 5573.9 5581.4 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | [#1] ext.html Cycles:180 Average:63499.94 Median:418572.50 stddev:115036.67 (27.5%) stddev-sans-first:115262.34 00:08:17 INFO - PROCESS | 1096 | Values: 565.7 589.9 624.2 644.9 661.2 36481.8 49115.0 49697.5 49814.5 49819.8 52162.5 52487.7 52590.5 52690.7 52731.0 8884.4 9106.3 9432.7 9621.4 9632.9 11192.7 11466.1 11478.9 11915.2 11916.5 11670.9 11763.5 11767.9 11776.9 11779.8 11682.4 11733.1 11736.4 11747.7 11760.2 11099.3 11177.3 11179.7 11181.3 11183.1 49105.5 49585.5 49592.5 49640.8 49649.0 4561.6 4586.5 4677.6 4729.0 4758.2 35734.5 36004.0 36067.0 36360.3 37721.6 4728.7 4743.5 4762.7 4765.1 4820.8 353136.0 354196.0 354377.0 356634.0 358163.0 71602.0 72055.6 72063.0 72069.0 72099.0 73483.6 73802.3 74167.0 74251.0 74276.6 18462.0 19297.6 19409.7 19591.1 19595.0 13261.1 13477.3 14159.2 14578.6 14586.3 20083.7 20169.9 20175.2 20248.2 20329.0 6580.2 6630.9 6699.0 6810.1 6925.1 1015.6 1022.0 1032.3 1037.3 1041.7 35289.6 35875.8 36478.6 36485.0 36564.6 1182.9 1185.3 1197.3 1233.0 1293.2 18446.5 18567.2 18690.7 18881.2 18965.7 519618.0 519809.0 521001.0 524344.0 524828.0 72353.3 72377.3 72389.0 72437.0 72593.6 1515.5 1572.9 1580.9 1583.0 1590.9 33483.8 34798.7 34865.0 34879.1 35109.8 60871.7 61478.0 61507.4 61548.7 61617.0 413178.0 415490.0 415957.0 418274.0 418871.0 41374.4 43555.6 46190.5 46660.9 46792.8 54279.7 54928.7 55237.4 55247.0 55381.4 45622.5 46374.8 46646.8 47000.0 48109.3 44858.6 45694.5 45776.3 46568.0 47791.0 45381.0 45827.3 46080.5 46099.3 46407.0 37397.8 39406.2 39421.6 39672.0 40540.2 29933.3 30009.2 30019.2 30044.0 30049.5 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | [#2] jquery.html Cycles:180 Average:38330.77 Median:26209.84 stddev:122654.87 (468.0%) stddev-sans-first:122965.94 00:08:17 INFO - PROCESS | 1096 | Values: 435.7 449.6 455.2 455.9 456.5 21365.8 21542.8 22083.6 22366.3 22371.2 13579.1 13712.7 13857.7 13974.6 14041.2 5713.0 6278.5 6287.4 6292.6 6294.3 4440.0 4655.3 6211.4 6311.3 6426.2 6519.7 6525.0 6540.2 6540.5 6542.5 6313.7 6428.7 6455.0 6461.1 6463.3 6126.8 6267.7 6311.7 6339.7 6345.2 25774.9 25942.0 26079.9 26192.6 26227.1 2799.2 2843.9 2907.3 2922.3 2945.1 11703.7 11828.4 11863.5 14855.6 16039.0 1982.1 2067.1 2073.9 2079.3 2081.9 94310.5 94755.0 95789.5 96802.5 97176.5 12726.8 12808.8 12884.2 12915.9 13081.6 18367.1 18386.3 18387.3 18402.7 18408.7 9014.7 9200.0 9276.3 9338.2 9632.6 8975.4 9033.5 9037.6 9099.3 9199.1 5536.6 5551.5 5577.4 5585.9 5723.0 1914.3 2037.9 2162.5 2220.4 2236.8 944.2 965.2 973.2 976.0 983.7 20233.9 21288.3 21399.9 21417.9 21465.8 977.4 979.4 981.9 986.2 1000.3 11585.0 12933.4 12984.3 13036.5 13306.0 686643.0 750667.0 752145.0 764855.0 779093.0 109700.5 110676.0 111928.4 112539.4 114151.4 1106.5 1156.7 1170.2 1176.0 1197.7 19766.6 20186.9 20222.5 20282.5 20310.7 48880.3 49508.8 49715.5 49799.0 51059.0 64144.7 64900.4 65006.0 66179.3 66652.0 8099.9 9099.2 9197.7 9515.3 9813.8 13163.4 14759.6 14946.9 15258.2 15554.9 12416.6 12728.0 12865.3 12963.7 13281.5 12190.6 12557.7 12657.2 12856.1 12882.8 11909.5 12618.9 12876.6 13404.1 13575.7 9850.2 9890.8 9902.3 9935.6 10130.8 11859.3 11942.2 12323.9 12539.9 12727.4 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | [#3] mootools.html Cycles:180 Average:6014.78 Median:4330.04 stddev:3361.19 (77.6%) stddev-sans-first:3352.93 00:08:17 INFO - PROCESS | 1096 | Values: 1427.3 1453.1 1459.8 1462.4 1469.6 8784.7 9296.9 9362.3 9378.3 9384.6 7995.8 8279.1 8297.3 8460.8 9221.4 2420.5 2513.4 2518.2 2541.3 2544.0 2478.3 2506.6 2513.8 2526.9 2532.3 2496.4 2496.9 2512.4 2517.9 2526.4 2380.6 2402.3 2402.5 2411.0 2412.6 2400.6 2453.0 2506.6 2508.7 2511.7 9327.0 9387.4 9389.2 9396.7 9408.7 5608.9 5770.7 5778.7 5784.7 5787.5 3487.8 3552.3 3727.9 3775.2 3785.9 1456.8 1485.8 1537.9 1560.5 1565.5 12771.2 12808.8 12822.2 12824.7 12824.8 4929.4 5123.2 5180.3 5194.4 5196.0 10907.2 10908.8 10910.2 10910.6 10921.8 5835.1 5840.0 5842.7 5845.0 5847.9 4619.6 5154.4 5271.9 5308.3 5328.5 4001.6 4235.5 4320.7 4339.4 4366.5 1365.9 1376.9 1389.6 1404.3 1404.3 999.8 1006.5 1015.9 1018.8 1021.4 5866.7 5873.7 5901.2 5946.7 6040.3 1077.3 1085.9 1090.4 1095.6 1110.7 3871.0 3896.3 3977.8 3985.6 4003.8 10994.0 11316.1 11350.1 11373.7 11419.4 10964.3 11185.9 11200.6 11234.8 11376.9 8646.0 8700.7 8776.6 8790.4 8794.7 5709.9 5825.4 5878.1 5948.9 5971.1 9583.9 10323.1 10347.7 10362.8 10390.1 10268.8 10526.5 10530.8 10588.3 10626.6 8129.4 8266.7 8271.2 8308.3 8340.9 7911.3 8203.8 8400.3 8451.5 8506.4 7738.6 7779.1 7800.0 7880.6 7983.0 7352.7 7501.0 7521.5 7541.2 7541.7 6807.5 6925.4 6929.2 6932.8 6933.0 5198.1 5203.1 5232.6 5248.3 5413.8 5311.7 5314.7 5336.7 5386.2 5490.9 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | [#4] prototype.html Cycles:180 Average:4699.23 Median:5307.88 stddev:1304.78 (24.6%) stddev-sans-first:1268.04 00:08:17 INFO - PROCESS | 1096 | Values: 407.2 411.9 415.5 427.9 431.4 5353.7 5368.4 5386.0 5395.4 5399.8 5099.0 5181.2 5233.9 5302.5 5352.0 4244.2 4253.0 4264.3 4274.0 4279.1 4268.3 4274.9 4278.0 4279.0 4285.9 3972.6 4088.4 4209.4 4232.8 4248.8 4176.7 4183.0 4216.5 4219.3 4245.2 3803.8 3811.4 3822.8 3846.3 3866.0 5150.8 5165.5 5191.4 5226.3 5259.3 4880.6 4899.6 4921.2 4939.0 4993.0 5568.5 5573.3 5587.4 5604.4 5610.9 4148.8 4303.2 4306.7 4317.0 4321.2 5716.5 5723.2 5743.0 5762.6 5773.7 4400.5 4406.8 4409.6 4418.5 4423.2 4379.6 4457.3 4458.9 4465.3 4467.7 4909.1 4913.8 4916.8 4919.7 4958.4 5625.3 5629.5 5632.0 5634.5 5641.6 1452.7 1482.9 1485.3 1497.8 1516.5 1020.8 1049.7 1064.3 1079.2 1080.0 5266.7 5337.2 5342.2 5342.6 5351.8 5465.5 5573.3 5596.0 5618.0 5649.2 5025.5 5224.6 5268.9 5282.9 5343.3 2891.3 2916.3 2932.3 2936.9 2941.4 5506.8 5716.7 5727.4 5744.3 5748.8 5573.6 5577.6 5593.7 5612.8 5627.9 5745.7 5746.9 5772.9 5777.4 5779.3 5267.0 5442.8 5479.3 5485.4 5518.5 5397.0 5433.5 5447.4 5448.2 5453.4 5487.3 5732.0 5743.2 5760.0 5790.6 4310.4 4351.9 4355.1 4366.9 4367.8 5524.7 5545.8 5550.7 5584.6 5598.7 5276.3 5419.8 5441.8 5461.2 5465.8 5313.4 5334.0 5357.2 5372.7 5387.7 5313.2 5336.7 5359.7 5381.4 5471.7 5429.5 5516.0 5533.2 5535.3 5536.4 5423.8 5432.8 5483.7 5488.7 5495.0 00:08:17 INFO - PROCESS | 1096 | 00:08:17 INFO - PROCESS | 1096 | [#5] yui.html Cycles:180 Average:18676.58 Median:4684.52 stddev:23054.64 (492.1%) stddev-sans-first:23080.39 00:08:17 INFO - PROCESS | 1096 | Values: 834.5 836.8 895.7 898.7 934.9 16431.3 17505.1 17506.0 17660.5 17691.3 13587.0 15283.5 15533.8 16202.6 16630.5 705.1 786.8 820.0 825.9 828.1 817.1 818.7 824.3 824.7 824.8 863.8 864.8 864.9 865.0 865.3 728.2 754.4 858.2 859.2 860.2 556.0 556.4 615.3 641.8 642.2 17408.9 17478.4 17486.8 17606.4 17646.3 15448.2 15903.4 16149.9 16256.8 16501.8 14449.0 14459.9 14572.0 14656.6 14677.9 4663.0 4706.1 4766.7 4797.4 4800.6 78367.6 78771.6 79273.0 79995.0 80303.6 12388.2 12473.5 12480.6 12495.6 12741.6 24446.9 24451.5 24552.9 24789.4 25040.1 9050.4 9157.5 9209.4 9256.2 9298.5 4847.3 4866.0 4873.1 4923.6 5056.2 3653.2 3751.7 3758.6 3760.9 3768.1 649.3 749.2 771.5 793.9 797.5 866.9 878.5 888.4 904.1 910.3 11319.5 11414.9 11458.9 11655.5 11664.8 856.7 864.3 864.5 865.9 868.4 5506.1 5517.6 5623.7 5625.5 5637.3 77891.6 77974.6 78035.6 79014.0 84036.0 70209.3 73054.0 73596.0 73894.3 75718.6 65742.7 66951.0 67587.0 68436.9 68729.0 10586.3 11041.0 11062.0 11100.8 11220.9 44198.8 44749.3 45237.3 45941.5 46452.3 54341.5 55551.7 56132.4 56498.0 59125.4 13971.6 14158.8 14178.4 14287.1 14456.2 14222.7 14401.7 14560.2 14652.4 15210.8 14360.1 14418.2 14489.2 14515.4 14843.5 13779.0 13971.0 13984.7 13990.5 14029.7 13827.1 13911.3 14056.3 14064.6 14395.9 11938.3 12072.0 12326.9 12679.3 12938.9 10700.0 11368.8 11430.8 11675.7 11712.4 00:08:17 INFO - PROCESS | 1096 | -------- Summary: end -------- 00:08:17 INFO - PROCESS | 1096 | 00:08:18 INFO - TEST-INFO | 1096: exit 0 00:08:18 INFO - TEST-OK | dromaeo_css | took 1113548ms 00:08:18 INFO - TEST-START | kraken 00:08:18 INFO - operating with platform_type : linux_ 00:08:18 INFO - Initialising browser for kraken test... 00:08:18 INFO - TEST-INFO | started process 1481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprzPTl_/profile http://localhost:41536/getInfo.html) 00:08:22 INFO - PROCESS | 1481 | __metrics Screen width/height:1600/1200 00:08:22 INFO - PROCESS | 1481 | colorDepth:24 00:08:22 INFO - PROCESS | 1481 | Browser inner width/height: 1024/697 00:08:22 INFO - PROCESS | 1481 | __metrics 00:08:23 INFO - PROCESS | 1481 | 1486541303070 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 00:08:23 INFO - PROCESS | 1481 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:08:23 INFO - PROCESS | 1481 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:08:23 INFO - PROCESS | 1481 | console.error: PushService: 00:08:23 INFO - PROCESS | 1481 | stateChangeProcessEnqueue: Error transitioning state 00:08:23 INFO - PROCESS | 1481 | UnknownError 00:08:24 INFO - TEST-INFO | 1481: exit 0 00:08:24 INFO - Browser initialized. 00:08:24 INFO - Running cycle 1/1 for kraken test... 00:08:24 INFO - TEST-INFO | started process 1575 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprzPTl_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:08:36 INFO - PROCESS | 1575 | RSS: Main: 138645504 00:08:36 INFO - PROCESS | 1575 | 00:08:38 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41536/tests/kraken/driver.html?testName=audio-beat-detection) 00:08:39 INFO - PROCESS | 1575 | RSS: Main: 182743040 00:08:39 INFO - PROCESS | 1575 | 00:08:42 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41536/tests/kraken/driver.html?testName=audio-dft) 00:08:42 INFO - PROCESS | 1575 | RSS: Main: 172691456 00:08:42 INFO - PROCESS | 1575 | 00:08:45 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41536/tests/kraken/driver.html?testName=audio-fft) 00:08:46 INFO - PROCESS | 1575 | RSS: Main: 264130560 00:08:46 INFO - PROCESS | 1575 | 00:08:48 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41536/tests/kraken/driver.html?testName=audio-oscillator) 00:08:48 INFO - PROCESS | 1575 | RSS: Main: 170274816 00:08:48 INFO - PROCESS | 1575 | 00:08:50 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41536/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:08:51 INFO - PROCESS | 1575 | RSS: Main: 172380160 00:08:51 INFO - PROCESS | 1575 | 00:08:54 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41536/tests/kraken/driver.html?testName=imaging-darkroom) 00:08:54 INFO - PROCESS | 1575 | RSS: Main: 211439616 00:08:54 INFO - PROCESS | 1575 | 00:08:58 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41536/tests/kraken/driver.html?testName=imaging-desaturate) 00:08:58 INFO - PROCESS | 1575 | RSS: Main: 210321408 00:08:58 INFO - PROCESS | 1575 | 00:09:01 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41536/tests/kraken/driver.html?testName=json-parse-financial) 00:09:02 INFO - PROCESS | 1575 | RSS: Main: 207994880 00:09:02 INFO - PROCESS | 1575 | 00:09:03 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41536/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:09:04 INFO - PROCESS | 1575 | RSS: Main: 171368448 00:09:04 INFO - PROCESS | 1575 | 00:09:06 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:09:06 INFO - PROCESS | 1575 | RSS: Main: 178774016 00:09:06 INFO - PROCESS | 1575 | 00:09:08 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:09:09 INFO - PROCESS | 1575 | RSS: Main: 187764736 00:09:09 INFO - PROCESS | 1575 | 00:09:11 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:09:12 INFO - PROCESS | 1575 | RSS: Main: 197439488 00:09:12 INFO - PROCESS | 1575 | 00:09:15 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:09:15 INFO - PROCESS | 1575 | RSS: Main: 193486848 00:09:15 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | Cycle 1(1): loaded http://localhost:41536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41536/tests/kraken/driver.html?testName=ai-astar) 00:09:17 INFO - PROCESS | 1575 | RSS: Main: 244854784 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | __start_tp_report 00:09:17 INFO - PROCESS | 1575 | _x_x_mozilla_page_load 00:09:17 INFO - PROCESS | 1575 | _x_x_mozilla_page_load_details 00:09:17 INFO - PROCESS | 1575 | |i|pagename|runs| 00:09:17 INFO - PROCESS | 1575 | |0;ai-astar;112;114;114;113;113;112;112;112;114;113 00:09:17 INFO - PROCESS | 1575 | |1;audio-beat-detection;142;117;117;151;119;118;119;122;138;122 00:09:17 INFO - PROCESS | 1575 | |2;audio-dft;172;161;161;161;161;160;160;160;161;160 00:09:17 INFO - PROCESS | 1575 | |3;audio-fft;76;75;75;110;76;76;76;75;88;74 00:09:17 INFO - PROCESS | 1575 | |4;audio-oscillator;82;83;113;82;84;83;98;83;82;96 00:09:17 INFO - PROCESS | 1575 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 00:09:17 INFO - PROCESS | 1575 | |6;imaging-darkroom;148;147;146;147;147;147;146;148;146;147 00:09:17 INFO - PROCESS | 1575 | |7;imaging-desaturate;95;97;96;96;96;95;97;96;96;95 00:09:17 INFO - PROCESS | 1575 | |8;json-parse-financial;50;50;49;50;50;49;49;49;50;50 00:09:17 INFO - PROCESS | 1575 | |9;json-stringify-tinderbox;64;65;65;65;64;64;64;64;64;65 00:09:17 INFO - PROCESS | 1575 | |10;stanford-crypto-aes;76;83;76;77;76;76;81;75;75;76 00:09:17 INFO - PROCESS | 1575 | |11;stanford-crypto-ccm;122;121;128;133;129;121;117;118;160;125 00:09:17 INFO - PROCESS | 1575 | |12;stanford-crypto-pbkdf2;161;159;159;163;162;159;156;158;162;160 00:09:17 INFO - PROCESS | 1575 | |13;stanford-crypto-sha256-iterative;60;61;62;61;64;60;61;62;59;59 00:09:17 INFO - PROCESS | 1575 | __end_tp_report 00:09:17 INFO - PROCESS | 1575 | __start_cc_report 00:09:17 INFO - PROCESS | 1575 | _x_x_mozilla_cycle_collect,1560 00:09:17 INFO - PROCESS | 1575 | __end_cc_report 00:09:17 INFO - PROCESS | 1575 | __startTimestamp1486541357719__endTimestamp 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | ------- Summary: start ------- 00:09:17 INFO - PROCESS | 1575 | Number of tests: 14 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 00:09:17 INFO - PROCESS | 1575 | Values: 112.0 114.0 114.0 113.0 113.0 112.0 112.0 112.0 114.0 113.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:122.00 stddev:12.38 (10.1%) stddev-sans-first:11.79 00:09:17 INFO - PROCESS | 1575 | Values: 142.0 117.0 117.0 151.0 119.0 118.0 119.0 122.0 138.0 122.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#2] audio-dft Cycles:10 Average:161.70 Median:161.00 stddev:3.65 (2.3%) stddev-sans-first:0.53 00:09:17 INFO - PROCESS | 1575 | Values: 172.0 161.0 161.0 161.0 161.0 160.0 160.0 160.0 161.0 160.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#3] audio-fft Cycles:10 Average:80.10 Median:76.00 stddev:11.25 (14.8%) stddev-sans-first:11.83 00:09:17 INFO - PROCESS | 1575 | Values: 76.0 75.0 75.0 110.0 76.0 76.0 76.0 75.0 88.0 74.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:83.00 stddev:10.46 (12.6%) stddev-sans-first:10.82 00:09:17 INFO - PROCESS | 1575 | Values: 82.0 83.0 113.0 82.0 84.0 83.0 98.0 83.0 82.0 96.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 00:09:17 INFO - PROCESS | 1575 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 00:09:17 INFO - PROCESS | 1575 | Values: 148.0 147.0 146.0 147.0 147.0 147.0 146.0 148.0 146.0 147.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 00:09:17 INFO - PROCESS | 1575 | Values: 95.0 97.0 96.0 96.0 96.0 95.0 97.0 96.0 96.0 95.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:0.52 (1.0%) stddev-sans-first:0.53 00:09:17 INFO - PROCESS | 1575 | Values: 50.0 50.0 49.0 50.0 50.0 49.0 49.0 49.0 50.0 50.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#9] json-stringify-tinderbox Cycles:10 Average:64.40 Median:64.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 00:09:17 INFO - PROCESS | 1575 | Values: 64.0 65.0 65.0 65.0 64.0 64.0 64.0 64.0 64.0 65.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#10] stanford-crypto-aes Cycles:10 Average:77.10 Median:76.00 stddev:2.69 (3.5%) stddev-sans-first:2.82 00:09:17 INFO - PROCESS | 1575 | Values: 76.0 83.0 76.0 77.0 76.0 76.0 81.0 75.0 75.0 76.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:126.50 stddev:12.52 (9.9%) stddev-sans-first:13.12 00:09:17 INFO - PROCESS | 1575 | Values: 122.0 121.0 128.0 133.0 129.0 121.0 117.0 118.0 160.0 125.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.90 Median:160.50 stddev:2.13 (1.3%) stddev-sans-first:2.22 00:09:17 INFO - PROCESS | 1575 | Values: 161.0 159.0 159.0 163.0 162.0 159.0 156.0 158.0 162.0 160.0 00:09:17 INFO - PROCESS | 1575 | 00:09:17 INFO - PROCESS | 1575 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.52 (2.5%) stddev-sans-first:1.58 00:09:17 INFO - PROCESS | 1575 | Values: 60.0 61.0 62.0 61.0 64.0 60.0 61.0 62.0 59.0 59.0 00:09:17 INFO - PROCESS | 1575 | -------- Summary: end -------- 00:09:17 INFO - PROCESS | 1575 | 00:09:18 INFO - TEST-INFO | 1575: exit 0 00:09:18 INFO - TEST-OK | kraken | took 60095ms 00:09:18 INFO - SUITE-END | took 1173s 00:09:18 INFO - Completed test suite (00:19:33) 00:09:18 INFO - javascript benchmark 00:09:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.5571247881535, 3324.418226810309, 3337.0, 3358.2443950111215, 3379.0, 5419.3225846769155, 5489.752961116749, 5499.50504454599, 5506.834794956148, 5523.033469142894, 5478.004754381856, 5533.833984980447, 5550.028744969624, 5562.0266453370705, 5567.721613919298, 4945.678530895499, 4956.851294461172, 4986.551210391064, 4993.226049962243, 5052.9747351263195, 4857.854264372075, 4984.725840078794, 5034.119029169899, 5035.194368900977, 5069.391672999245, 4813.518648135194, 4834.830780922651, 4865.975670121645, 4875.829345972892, 4952.752362381884, 4931.679440836334, 4966.230234313687, 4966.776495057695, 4969.981153863468, 4995.225740010304, 4781.0676918001, 4884.120858245515, 4895.9510404895855, 4905.288733133676, 4923.975380123113, 5274.736263186825, 5345.732713364317, 5412.91880621791, 5428.647137936022, 5498.697571633558, 5847.766089356463, 5865.0, 5867.061270196748, 5871.647701137946, 5873.559483038789, 5909.852253693649, 5915.112733090028, 5917.940820591825, 5928.229330187091, 5931.555133364972, 5379.865503362408, 5431.266778984826, 5475.863103422446, 5527.5577953764405, 5529.253550770594, 6026.0659597762415, 6033.3664965178905, 6033.728482218311, 6041.667708275999, 6069.241344831896, 5769.9711501442225, 5781.075027995501, 5803.970980145073, 5818.796342128006, 5821.621594596338, 5689.800856970069, 5701.0308247598, 5715.914261286084, 5737.99585072619, 5938.940610593839, 5759.308882933991, 5759.992001399738, 5762.164486149569, 5787.392323806024, 5796.014677504834, 5769.278840144982, 5777.393373695785, 5777.62445441045, 5778.393268706809, 5783.537317014629, 4450.777461126931, 4460.732356058582, 4486.304622783474, 4492.168948744493, 4519.864404067883, 3972.741771784825, 4056.411820286042, 4072.3484242521054, 4074.30736774749, 4083.1629515949157, 5614.494695477427, 5638.548916086703, 5665.773369065274, 5668.376478587352, 5673.886522269531, 5586.106223004381, 5656.123300888369, 5768.3366412862215, 5827.213326200994, 5872.060470324727, 5666.46168613981, 5682.119271512839, 5684.971575142098, 5706.429357064343, 5713.171590119493, 4975.4527002029745, 4981.476944920803, 4984.40187177541, 4990.800367985313, 5007.098722229961, 5923.496502797224, 5952.732127054206, 6015.0, 6140.815775526741, 6143.877122457615, 5806.709664516673, 5856.297244330537, 5866.354700982974, 5867.178594996789, 5896.469317761422, 6044.365341639241, 6088.786892458743, 6094.573379863367, 6101.969490152521, 6105.938940610537, 5604.635698679573, 5666.065099258576, 5729.627574207663, 5791.305043394819, 5796.652200867961, 5861.062230043172, 5921.940780592311, 5922.3485416605, 5973.313068997121, 6011.398860113954, 5780.624259423124, 5812.505936995402, 5817.563682723728, 5821.679807610452, 5824.097264923944, 5538.91691624634, 5759.424057594207, 5813.970930145322, 5819.912701309569, 5822.3304320003745, 5526.723663816873, 5569.749361278671, 5613.326400831927, 5618.381977982501, 5685.118806584986, 5661.264035675335, 5674.4325567444575, 5696.9715151425635, 5697.23087383198, 5704.229928959538, 5527.170924361378, 5567.665940043448, 5573.108302671553, 5573.916391254053, 5581.441855814549], "value": 5401.174745022876, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.6719102920307, 589.9262592175978, 624.2103738770456, 644.8774732800764, 661.1603263854902, 36481.81759091204, 49115.0, 49697.50302496974, 49814.501854981485, 49819.750901245534, 52162.47837521623, 52487.73756131214, 52590.47409525904, 52690.73654631722, 52731.0, 8884.422512536665, 9106.271498280155, 9432.669856555021, 9621.422714637109, 9632.855507167398, 11192.720181995433, 11466.082713382952, 11478.885211147865, 11915.225510341843, 11916.523339066427, 11670.883291167065, 11763.529458821637, 11767.941160294187, 11776.941115294454, 11779.764404711901, 11682.415879206048, 11733.120015998835, 11736.354500502553, 11747.706307342341, 11760.235584686969, 11099.278546894508, 11177.273477223955, 11179.664610061629, 11181.273217240854, 11183.105351571854, 49105.50894491045, 49585.504144958446, 49592.504074959514, 49640.75179624079, 49649.0, 4561.566651168134, 4586.54134586542, 4677.602403795681, 4728.976355118237, 4758.214894542396, 35734.463983040274, 36004.0, 36067.0, 36360.272794544224, 37721.62278377209, 4728.716277023355, 4743.454502732196, 4762.738049407281, 4765.118453086156, 4820.758962051918, 353136.0, 354196.0, 354377.0, 356634.0, 358163.0, 71602.0, 72055.6397218021, 72063.0, 72069.0, 72099.0, 73483.63258183675, 73802.26197738061, 74167.0, 74251.0, 74276.62861685657, 18462.0, 19297.614047718966, 19409.708854367196, 19591.11839967205, 19595.0, 13261.071724979352, 13477.326133693276, 14159.221242831743, 14578.562643120724, 14586.270686465634, 20083.698744518842, 20169.899150504447, 20175.192992280437, 20248.190072397232, 20329.0, 6580.21037475506, 6630.86738265232, 6698.966505167443, 6810.148731408574, 6925.134358205224, 1015.57345914716, 1021.9642312519026, 1032.2516175772655, 1037.2842738510403, 1041.6874937518714, 35289.64710352864, 35875.82062089673, 36478.63521364806, 36485.0, 36564.634353656125, 1182.9290242585473, 1185.3125187391292, 1197.275648232824, 1232.9876701232872, 1293.204679122333, 18446.538836528925, 18567.16447759854, 18690.71963920542, 18881.15034823436, 18965.715514267296, 519618.0, 519809.0, 521001.0, 524344.0, 524828.0, 72353.2764672357, 72377.27622723706, 72389.0, 72437.0, 72593.63703181555, 1515.5226103777275, 1572.866306363949, 1580.9446669366516, 1582.9920850395904, 1590.8727301815827, 33483.832580837916, 34798.65201347954, 34865.0, 34879.12802179966, 35109.82445087758, 60871.69564152151, 61478.0, 61507.38492615017, 61548.69225653843, 61617.0, 413178.0, 415490.0, 415957.0, 418274.0, 418871.0, 41374.37938430866, 43555.56444435515, 46190.53809461863, 46660.900259487724, 46792.766036170964, 54279.72860135674, 54928.72535637296, 55237.44762552323, 55247.0, 55381.44618553763, 45622.54377456183, 46374.76812615915, 46646.76676616595, 47000.0, 48109.278360825316, 44858.55141448544, 45694.543054569025, 45776.31335529903, 46568.0, 47791.0, 45381.0, 45827.3125903105, 46080.53919460763, 46099.30851037304, 46407.0, 37397.81301093586, 39406.2118757629, 39421.60578394179, 39672.0, 40540.1891962165, 29933.251668707584, 30009.249768755955, 30019.249518762204, 30044.0, 30049.549256761602], "value": 22907.923952323647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.68412900647013, 449.58862640683736, 455.23293250872285, 455.89514411685303, 456.52065331402025, 21365.786342136573, 21542.784572154294, 22083.558328833413, 22366.32901012968, 22371.217007404743, 13579.117357371788, 13712.725745485084, 13857.722845543083, 13974.58076257714, 14041.157530548175, 5712.99999999999, 6278.529110316722, 6287.434130928215, 6292.5595208335435, 6294.339094395092, 4440.023194897133, 4655.348251244828, 6211.440970312671, 6311.274203466592, 6426.164598602175, 6519.67401629919, 6524.967375163117, 6540.215174179081, 6540.542162048659, 6542.476601871839, 6313.747450101991, 6428.678566071701, 6454.967725161391, 6461.063145843873, 6463.321351258144, 6126.816195514141, 6267.71795269214, 6311.652859092748, 6339.683015849235, 6345.175127233476, 25774.87112564444, 25942.0, 26079.869600652066, 26192.60711089335, 26227.082052128273, 2799.174243598143, 2843.9004634837684, 2907.331313797817, 2922.298648324408, 2945.1164650604946, 11703.7074073148, 11828.408579570987, 11863.52545898163, 14855.628609284746, 16039.037657740573, 1982.137770070024, 2067.080149333547, 2073.8651987620756, 2079.3450063230184, 2081.9375418737463, 94310.52844735801, 94755.0, 95789.521052395, 96802.51598742032, 97176.51411742967, 12726.80909786354, 12808.80786788199, 12884.162529435556, 12915.935420323027, 13081.607551773272, 18367.08164591765, 18386.264549417872, 18387.264509419474, 18402.723959140625, 18408.723869141973, 9014.729558113268, 9199.954000229956, 9276.25789936817, 9338.225737109928, 9632.566534505964, 8975.416597921114, 9033.548322583845, 9037.63849446028, 9099.339969810178, 9199.126083022102, 5536.640118392292, 5551.528120109836, 5577.386487486373, 5585.888282234332, 5723.0, 1914.272576420979, 2037.8981050947689, 2162.4810045588947, 2220.433789383697, 2236.7986881180773, 944.1691311645708, 965.2133511188359, 973.1873885305849, 975.9756006099763, 983.7196399026243, 20233.89883050605, 21288.25491107804, 21399.8930005349, 21417.89291053535, 21465.785342146377, 977.4477420257532, 979.3536266064364, 981.8625392445064, 986.2455221755363, 1000.2792404659315, 11585.0, 12933.417996190006, 12984.285864277366, 13036.543720969721, 13306.0, 686643.0, 750667.0, 752145.0, 764855.0, 779093.0, 109700.45149774359, 110676.0, 111928.44035779769, 112539.43730281296, 114151.42924285325, 1106.4965440724452, 1156.6992581928591, 1170.1750266062236, 1175.988240117605, 1197.6826141072622, 19766.604667906275, 20186.899065504578, 20222.494437639176, 20282.492937676678, 20310.695339569622, 48880.26679599738, 49508.75245623749, 49715.50284497109, 49799.0, 51059.0, 64144.67927660332, 64900.35099649132, 65006.0, 66179.33820661732, 66652.0, 8099.878501822595, 9099.226565741987, 9197.724068277963, 9515.286353523376, 9813.803723925339, 13163.407646656115, 14759.631009224426, 14946.850531494982, 15258.160801156044, 15554.922225389253, 12416.565420210249, 12728.0, 12865.292408917225, 12963.675908101995, 13281.535146269835, 12190.573329933764, 12557.686057848627, 12657.240565566095, 12856.100072994801, 12882.806757898827, 11909.464074116859, 12618.873811262138, 12876.613701588967, 13404.128731632412, 13575.660608484866, 9850.211983041196, 9890.752731181778, 9902.257330700082, 9935.552900119657, 10130.848037279595, 11859.288442693465, 11942.223755455867, 12323.876761232274, 12539.93730031344, 12727.363631818558], "value": 10335.167194240803, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.250693385973, 1453.1426458389556, 1459.824821021477, 1462.3638717158033, 1469.5885152157396, 8784.692535761249, 9296.8605470918, 9362.297827662918, 9378.343515953895, 9384.624615015407, 7995.7601271961785, 8279.130691277405, 8297.336213102952, 8460.830783384328, 9221.40060896043, 2420.4916967436816, 2513.3590934311815, 2518.1690042286095, 2541.2503311523146, 2543.9618405723927, 2478.3060742991993, 2506.636537702032, 2513.761192686701, 2526.886290116949, 2532.341591186296, 2496.425822060927, 2496.900123995038, 2512.384465805871, 2517.937051573707, 2526.393665520271, 2380.583397905368, 2402.315340128055, 2402.483466054789, 2411.011485291031, 2412.5777988851883, 2400.627902675088, 2452.969752703869, 2506.5613517634342, 2508.673872396595, 2511.7488251174736, 9327.0, 9387.389819661701, 9389.201917836992, 9396.67111651089, 9408.670696525589, 5608.915866262009, 5770.740316685759, 5778.711064446761, 5784.652920824764, 5787.50806181475, 3487.8256087195537, 3552.3428165789155, 3727.925441491182, 3775.2260786538695, 3785.905352366213, 1456.7814827775812, 1485.8217013958285, 1537.853903879141, 1560.5162399656028, 1565.4755656854854, 12771.233725976379, 12808.80786788199, 12822.166559173716, 12824.743505129938, 12824.80762788559, 4929.433115191799, 5123.231515272739, 5180.274761533313, 5194.35070616173, 5195.974020129951, 10907.18196135293, 10908.836367454494, 10910.23628346024, 10910.618128365628, 10921.781564368668, 5835.095560188178, 5840.0, 5842.707864606753, 5844.970775146097, 5847.883042339215, 4619.630429565626, 5154.407243167008, 5271.947280527223, 5308.336457942757, 5328.520433160957, 4001.6198461146146, 4235.512916014636, 4320.6605952154305, 4339.414179085783, 4366.454193225847, 1365.9453621855214, 1376.9449222031208, 1389.638693939583, 1404.2978510744629, 1404.3048690898072, 999.7850462150673, 1006.5319626373771, 1015.918726501878, 1018.7911478146962, 1021.402479549457, 5866.70666466675, 5873.676947767914, 5901.17383566301, 5946.67293298864, 6040.305364883006, 1077.2997551591527, 1085.9022687957963, 1090.3922231278477, 1095.6220104064084, 1110.655696734015, 3870.9806450967567, 3896.298666240047, 3977.801109944549, 3985.628551012201, 4003.779792111462, 10994.0, 11316.094712422986, 11350.09199264057, 11373.658790236306, 11419.429028548539, 10964.342139471657, 11185.888141118485, 11200.551977920955, 11234.831477527843, 11376.886231137749, 8646.048934617187, 8700.651973920973, 8776.60505277264, 8790.42862213954, 8794.692185773467, 5709.942900570941, 5825.359210486928, 5878.118282257696, 5948.940510594839, 5971.134185542985, 9583.90416095858, 10323.122534584645, 10347.689569312632, 10362.844557331495, 10390.064894159563, 10268.845967310646, 10526.526306316387, 10530.842037369599, 10588.258821882395, 10626.574937002433, 8129.430939834155, 8266.669333226844, 8271.172882711922, 8308.293795027248, 8340.916590834015, 7911.327537159406, 8203.753887383387, 8400.327973761961, 8451.45065570736, 8506.447080939719, 7738.613069346623, 7779.105402878741, 7800.0, 7880.56656883854, 7982.9600851995365, 7352.742654007084, 7500.962495187708, 7521.5111017783665, 7541.208173141741, 7541.660625271986, 6807.489438292247, 6925.411340036153, 6929.237783843874, 6932.757353492603, 6933.0, 5198.116320225647, 5203.141481655484, 5232.5552328052545, 5248.291480650063, 5413.756380962787, 5311.734413279243, 5314.707691077027, 5336.706481143575, 5386.245925570345, 5490.890182196414], "value": 4872.818740540486, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.2181411689556, 411.92293567054105, 415.50554839740704, 427.9251131052065, 431.4218946611538, 5353.73231338433, 5368.355797304324, 5385.973070134653, 5395.352557693072, 5399.838004859851, 5099.0, 5181.248718935752, 5233.86915327117, 5302.522772950434, 5352.036633405985, 4244.193603215394, 4253.0, 4264.272969447371, 4274.0, 4279.1441711657635, 4268.253055715253, 4274.893127671802, 4277.957220427787, 4278.957210427902, 4285.892852678692, 3972.6226008529143, 4088.366303223005, 4209.389638502415, 4232.788360581974, 4248.808803603845, 4176.665866730655, 4182.958170418287, 4216.51510076342, 4219.3249080147025, 4245.214635292481, 3803.8098095095133, 3811.3711237645757, 3822.8279727412055, 3846.2692088503377, 3865.9703655581043, 5150.755024334682, 5165.535101840815, 5191.402988656315, 5226.268322434862, 5259.263703081571, 4880.6095512358925, 4899.559039686445, 4921.237208232729, 4938.950610493885, 4993.0, 5568.52667523261, 5573.27547418833, 5587.357453892813, 5604.411536788619, 5610.887782244332, 4148.79256037197, 4303.225419424502, 4306.676999225039, 4316.978415107905, 4321.24378233811, 5716.542676585863, 5723.198752174699, 5743.0, 5762.625429347121, 5773.680133923011, 4400.471943366816, 4406.823727050882, 4409.603135717736, 4418.491873434532, 4423.248047831812, 4379.627731642846, 4457.258785846746, 4458.9331160032625, 4465.307877279027, 4467.7095988761275, 4909.10362885899, 4913.803447862046, 4916.803327866917, 4919.680220785637, 4958.413467542581, 5625.268715067097, 5629.549636029108, 5631.971840140855, 5634.464725851038, 5641.633293835887, 1452.716720239543, 1482.9406823726931, 1485.3241774992352, 1497.7528707763097, 1516.5071351810705, 1020.8311483351521, 1049.7375656085978, 1064.2762921213648, 1079.1636481726568, 1079.9946000270106, 5266.657667251693, 5337.226102215234, 5342.248741897925, 5342.572594192456, 5351.812686555952, 5465.480779325958, 5573.331200255995, 5596.048671725816, 5618.0, 5649.180868774005, 5025.547700706881, 5224.648907991419, 5268.946210757864, 5282.8943421132135, 5343.251944727741, 2891.320539673175, 2916.314666053476, 2932.3255651200343, 2936.882524699031, 2941.3676059647278, 5506.834794956158, 5716.714164291769, 5727.3699893011735, 5744.281964754406, 5748.798792042258, 5573.637713548606, 5577.637453565587, 5593.6854839112775, 5612.828751755245, 5627.887442251132, 5745.712714364265, 5746.88506229873, 5772.884542309131, 5777.4400911752755, 5779.306483222041, 5266.97366513165, 5442.782288708408, 5479.2602998594675, 5485.369182543899, 5518.530924871431, 5397.0, 5433.505785908907, 5447.400785913625, 5448.210009548514, 5453.427390124139, 5487.286652735119, 5731.96206010815, 5743.195952566561, 5760.0, 5790.565707571865, 4310.383606147695, 4351.912961740811, 4355.082094996424, 4366.890827729332, 4367.759773212504, 5524.696141712245, 5545.7781688732, 5550.722463876871, 5584.565492222336, 5598.720063996702, 5276.34045744282, 5419.83740487786, 5441.809536666197, 5461.1808228766, 5465.7813687453645, 5313.388960269618, 5333.973330133324, 5357.1696387060065, 5372.650777699437, 5387.730613469233, 5313.229581710708, 5336.706481143575, 5359.651622644516, 5381.412483317353, 5471.671699698031, 5429.484199001088, 5515.972420137874, 5533.197686335378, 5535.25274087993, 5536.390996990408, 5423.810166644149, 5432.7555260012605, 5483.725813709379, 5488.670679759227, 5495.0], "value": 4309.876174069581, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.4534330013843, 836.7782537627534, 895.7044175422111, 898.7127759851178, 934.897161312256, 16431.260593273302, 17505.124743762826, 17506.0, 17660.47018589441, 17691.29234830605, 13587.048906576543, 15283.465078722247, 15533.844661553381, 16202.594935126599, 16630.50108496744, 705.1045172630755, 786.8426314737047, 820.0159808230147, 825.9463134896213, 828.1015098618007, 817.1052697296481, 818.7011740714626, 824.3075816314295, 824.7113510271417, 824.8309096635176, 863.8445079885618, 864.8054187807755, 864.9394542382036, 865.0, 865.3380164174412, 728.1735230513352, 754.3889449545885, 858.1547176031604, 859.1751918158575, 860.2129051917487, 555.9805406810783, 556.4407770190933, 615.264758613457, 641.7721708793358, 642.2036674523604, 17408.912955435142, 17478.388256410966, 17486.825131748647, 17606.38377656776, 17646.294148234054, 15448.15035173065, 15903.363865445368, 16149.919250403791, 16256.75614865778, 16501.752473712902, 14449.0, 14459.927700361535, 14572.0, 14656.560303190921, 14677.853221467756, 4662.953370466286, 4706.082313948781, 4766.6901651392545, 4797.4482934462285, 4800.639952003614, 78367.60816195882, 78771.6061419695, 79273.0, 79995.0, 80303.5984820078, 12388.194767340094, 12473.501059957682, 12480.563180288827, 12495.562655307021, 12741.55404560836, 24446.877765611414, 24451.510969780506, 24552.877235614065, 24789.380265493146, 25040.123595674064, 9050.411723237969, 9157.496337701357, 9209.35534512591, 9256.213221876082, 9298.488583127993, 4847.297141914473, 4866.026794641086, 4873.147199240118, 4923.556879880828, 5056.241563765391, 3653.232821107544, 3751.6998640108727, 3758.6053464385845, 3760.8683696070507, 3768.1333293342686, 649.2533586375706, 749.2432643030578, 771.4831063187678, 793.9444238903337, 797.4856217739525, 866.8699695045793, 878.5431575580662, 888.4225253585221, 904.0507467159588, 910.3081657939881, 11319.547218111347, 11414.885851141382, 11458.94270528642, 11655.475503602358, 11664.766704665859, 856.7130011446111, 864.321507616518, 864.4597126795753, 865.8827276301148, 868.3747701654798, 5506.1190209566275, 5517.641353312022, 5623.746931388097, 5625.46558076982, 5637.295338082739, 77891.61054194806, 77974.610126949, 78035.60982195052, 79014.0, 84036.0, 70209.29790702027, 73054.0, 73596.0, 73894.26105738981, 75718.62140689371, 65742.67128664421, 66951.0, 67587.0, 68436.89378637298, 68729.0, 10586.31188972745, 11041.0, 11062.0, 11100.833487497855, 11220.943895280798, 44198.77900610476, 44749.328760067976, 45237.32144017908, 45941.54058459373, 46452.30321545247, 54341.45658543364, 55551.72224138853, 56132.43867561272, 56498.0, 59125.40874591371, 13971.58085257444, 14158.78761818553, 14178.43404888944, 14287.142771433748, 14456.20490873012, 14222.715545689236, 14401.711965760836, 14560.199189044704, 14652.413903443743, 15210.847891520943, 14360.138391696531, 14418.206998614758, 14489.203093829941, 14515.419383224553, 14843.480478183212, 13779.0, 13971.0, 13984.650383740081, 13990.510332138327, 14029.719405612035, 13827.101238419471, 13911.304434778018, 14056.297185140907, 14064.578062658136, 14395.856041439452, 11938.28370297785, 12072.0, 12326.938365308117, 12679.302638354979, 12938.935305323414, 10700.0, 11368.772624547297, 11430.82853757211, 11675.708107297385, 11712.414379281172], "value": 7870.30283152992, "unit": "score"}], "name": "dromaeo_css", "value": 7718.142611491065, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 113.0, 113.0, 112.0, 112.0, 112.0, 114.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 117.0, 151.0, 119.0, 118.0, 119.0, 122.0, 138.0, 122.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 160.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 110.0, 76.0, 76.0, 76.0, 75.0, 88.0, 74.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 113.0, 82.0, 84.0, 83.0, 98.0, 83.0, 82.0, 96.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 148.0, 146.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 96.0, 96.0, 95.0, 97.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 50.0, 49.0, 49.0, 49.0, 50.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0], "value": 64.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 83.0, 76.0, 77.0, 76.0, 76.0, 81.0, 75.0, 75.0, 76.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 128.0, 133.0, 129.0, 121.0, 117.0, 118.0, 160.0, 125.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 159.0, 163.0, 162.0, 159.0, 156.0, 158.0, 162.0, 160.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 61.0, 64.0, 60.0, 61.0, 62.0, 59.0, 59.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1468.9000000000003, "alertThreshold": 2.0}]} 00:09:18 INFO - Return code: 0 00:09:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:09:19 ERROR - # TBPL SUCCESS # 00:09:19 INFO - Running post-action listener: _resource_record_post_action 00:09:19 INFO - [mozharness: 2017-02-08 08:09:19.050687Z] Finished run-tests step (success) 00:09:19 INFO - Running post-run listener: _resource_record_post_run 00:09:19 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 363098112; Read time: 16; Write time: 305548 00:09:19 INFO - TinderboxPrint: CPU usage
13.0% 00:09:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 00:09:19 INFO - TinderboxPrint: I/O write bytes / time
363,098,112 / 305,548 00:09:19 INFO - TinderboxPrint: CPU idle
8,256.3 (86.9%) 00:09:19 INFO - TinderboxPrint: CPU user
1,195.1 (12.6%) 00:09:19 INFO - TinderboxPrint: Swap in / out
0 / 0 00:09:19 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110256128; Read time: 0; Write time: 140396 00:09:19 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 252841984; Read time: 16; Write time: 165152 00:09:19 INFO - Running post-run listener: _upload_blobber_files 00:09:19 INFO - Blob upload gear active. 00:09:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:09:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 00:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:09:20 INFO - (blobuploader) - INFO - Open directory for files ... 00:09:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:09:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:20 INFO - (blobuploader) - INFO - Done attempting. 00:09:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:09:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:09:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:21 INFO - (blobuploader) - INFO - Done attempting. 00:09:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:09:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:09:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:22 INFO - (blobuploader) - INFO - Done attempting. 00:09:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:09:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:09:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:25 INFO - (blobuploader) - INFO - Done attempting. 00:09:25 INFO - (blobuploader) - INFO - Iteration through files over. 00:09:25 INFO - Return code: 0 00:09:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:09:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:09:25 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbfc900f58ea82792a94dd21f2ff212763614d8796405bedd532a5108c485aa5aa927756102efc3e47eb8dfb56c360e7e2b39feea6bb114bf48313ad46deece3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/93dad16da2204c0cf5a776bc934a648fdc563c144087e356a86e973ea33ce44a392a8f60aa1e69cf72782aa0aa5d2763de942e17e3ea52d7ffe5e2a4e01b1156", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbd090117117671ac79c228b91f2393ebede7cb58619329073ab2929b3e85304f9e497457ca78cc8e0832797767e288bc00dc26b3fe2acf77d7ded88be7438c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8b1ac631c01a70e8250c2831c75457ec61422590d0cb7beb86fdd45a4368bcb2831f26e9c7313afe5fa9c90b8685f7f8f9c8f4d2af7510e09acce22e707cc726"} 00:09:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:09:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:09:25 INFO - Contents: 00:09:25 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbfc900f58ea82792a94dd21f2ff212763614d8796405bedd532a5108c485aa5aa927756102efc3e47eb8dfb56c360e7e2b39feea6bb114bf48313ad46deece3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/93dad16da2204c0cf5a776bc934a648fdc563c144087e356a86e973ea33ce44a392a8f60aa1e69cf72782aa0aa5d2763de942e17e3ea52d7ffe5e2a4e01b1156", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbd090117117671ac79c228b91f2393ebede7cb58619329073ab2929b3e85304f9e497457ca78cc8e0832797767e288bc00dc26b3fe2acf77d7ded88be7438c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8b1ac631c01a70e8250c2831c75457ec61422590d0cb7beb86fdd45a4368bcb2831f26e9c7313afe5fa9c90b8685f7f8f9c8f4d2af7510e09acce22e707cc726"} 00:09:25 INFO - Running post-run listener: copy_logs_to_upload_dir 00:09:25 INFO - Copying logs to upload dir... 00:09:25 INFO - mkdir: /builds/slave/test/build/upload/logs 00:09:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.181835 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-02-08 00:09:25.725543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 00:09:25.731045) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbfc900f58ea82792a94dd21f2ff212763614d8796405bedd532a5108c485aa5aa927756102efc3e47eb8dfb56c360e7e2b39feea6bb114bf48313ad46deece3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/93dad16da2204c0cf5a776bc934a648fdc563c144087e356a86e973ea33ce44a392a8f60aa1e69cf72782aa0aa5d2763de942e17e3ea52d7ffe5e2a4e01b1156", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbd090117117671ac79c228b91f2393ebede7cb58619329073ab2929b3e85304f9e497457ca78cc8e0832797767e288bc00dc26b3fe2acf77d7ded88be7438c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8b1ac631c01a70e8250c2831c75457ec61422590d0cb7beb86fdd45a4368bcb2831f26e9c7313afe5fa9c90b8685f7f8f9c8f4d2af7510e09acce22e707cc726"} build_url:https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/FXxDmAlYRxWRkiMnCupXTw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbfc900f58ea82792a94dd21f2ff212763614d8796405bedd532a5108c485aa5aa927756102efc3e47eb8dfb56c360e7e2b39feea6bb114bf48313ad46deece3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/93dad16da2204c0cf5a776bc934a648fdc563c144087e356a86e973ea33ce44a392a8f60aa1e69cf72782aa0aa5d2763de942e17e3ea52d7ffe5e2a4e01b1156", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fbd090117117671ac79c228b91f2393ebede7cb58619329073ab2929b3e85304f9e497457ca78cc8e0832797767e288bc00dc26b3fe2acf77d7ded88be7438c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/8b1ac631c01a70e8250c2831c75457ec61422590d0cb7beb86fdd45a4368bcb2831f26e9c7313afe5fa9c90b8685f7f8f9c8f4d2af7510e09acce22e707cc726"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 00:09:25.756216) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 00:09:25.756592) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486540127.549824-1991619230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004377 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 00:09:25.841554) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 00:09:25.841913) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 00:09:25.842245) ========= ========= Total master_lag: 0.18 =========