builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1472779044.51 results: success (0) buildid: 20160901153146 builduid: dd1900a428064e5eaca95b5f0db51d9a revision: bef3532d2dc5666785738ecd089363a84989ef40 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.506594) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.507004) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.507286) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.537836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.538119) ========= 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=af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 18:17:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.3M=0s 2016-09-01 18:17:25 (36.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.630701) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.630994) ========= 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=af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020048 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.680662) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:24.680976) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev bef3532d2dc5666785738ecd089363a84989ef40 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev bef3532d2dc5666785738ecd089363a84989ef40 --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=af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 18:17:25,240 truncating revision to first 12 chars 2016-09-01 18:17:25,240 Setting DEBUG logging. 2016-09-01 18:17:25,240 attempt 1/10 2016-09-01 18:17:25,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/bef3532d2dc5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 18:17:25,560 unpacking tar archive at: mozilla-inbound-bef3532d2dc5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.667517 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:25.362269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:25.362582) ========= script_repo_revision: bef3532d2dc5666785738ecd089363a84989ef40 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:25.362985) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:25.363279) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 18:17:25.372373) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-01 18:17:25.372662) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:17:26 INFO - MultiFileLogger online at 20160901 18:17:26 in /builds/slave/test 18:17:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 18:17:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:17:26 INFO - {'append_to_log': False, 18:17:26 INFO - 'base_work_dir': '/builds/slave/test', 18:17:26 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 18:17:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:17:26 INFO - 'branch': 'Mozilla-Inbound', 18:17:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:17:26 INFO - 'config_files': ('talos/linux_config.py',), 18:17:26 INFO - 'default_actions': ('clobber', 18:17:26 INFO - 'read-buildbot-config', 18:17:26 INFO - 'download-and-extract', 18:17:26 INFO - 'populate-webroot', 18:17:26 INFO - 'create-virtualenv', 18:17:26 INFO - 'install', 18:17:26 INFO - 'run-tests'), 18:17:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:17:26 INFO - 'download_minidump_stackwalk': True, 18:17:26 INFO - 'download_symbols': 'ondemand', 18:17:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:17:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:17:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:17:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:17:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:17:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:17:26 INFO - 'installer_path': 'installer.exe', 18:17:26 INFO - 'log_level': 'info', 18:17:26 INFO - 'log_name': 'talos', 18:17:26 INFO - 'log_to_console': True, 18:17:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:17:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:17:26 INFO - 'opt_config_files': (), 18:17:26 INFO - 'pip_index': False, 18:17:26 INFO - 'sps_profile': False, 18:17:26 INFO - 'sps_profile_interval': 0, 18:17:26 INFO - 'suite': 'dromaeojs', 18:17:26 INFO - 'system_bits': '32', 18:17:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:17:26 INFO - 'title': 'talos-linux64-ix-020', 18:17:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:17:26 INFO - 'use_talos_json': True, 18:17:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:17:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:17:26 INFO - 'work_dir': 'build'} 18:17:26 INFO - [mozharness: 2016-09-02 01:17:26.023505Z] Running clobber step. 18:17:26 INFO - Running pre-action listener: _resource_record_pre_action 18:17:26 INFO - Running main action method: clobber 18:17:26 INFO - rmtree: /builds/slave/test/build 18:17:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:17:26 INFO - Running post-action listener: _resource_record_post_action 18:17:26 INFO - [mozharness: 2016-09-02 01:17:26.482319Z] Finished clobber step (success) 18:17:26 INFO - [mozharness: 2016-09-02 01:17:26.482423Z] Running read-buildbot-config step. 18:17:26 INFO - Running pre-action listener: _resource_record_pre_action 18:17:26 INFO - Running main action method: read_buildbot_config 18:17:26 INFO - Using buildbot properties: 18:17:26 INFO - { 18:17:26 INFO - "project": "", 18:17:26 INFO - "product": "firefox", 18:17:26 INFO - "script_repo_revision": "production", 18:17:26 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 18:17:26 INFO - "repository": "", 18:17:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 18:17:26 INFO - "buildid": "20160901153146", 18:17:26 INFO - "pgo_build": "True", 18:17:26 INFO - "basedir": "/builds/slave/test", 18:17:26 INFO - "buildnumber": 1482, 18:17:26 INFO - "slavename": "talos-linux64-ix-020", 18:17:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:17:26 INFO - "platform": "ubuntu64_hw", 18:17:26 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 18:17:26 INFO - "branch": "mozilla-inbound", 18:17:26 INFO - "repo_path": "integration/mozilla-inbound", 18:17:26 INFO - "revision": "bef3532d2dc5666785738ecd089363a84989ef40", 18:17:26 INFO - "stage_platform": "linux64-pgo", 18:17:26 INFO - "builduid": "dd1900a428064e5eaca95b5f0db51d9a", 18:17:26 INFO - "slavebuilddir": "test" 18:17:26 INFO - } 18:17:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 18:17:26 INFO - Running post-action listener: _resource_record_post_action 18:17:26 INFO - [mozharness: 2016-09-02 01:17:26.484842Z] Finished read-buildbot-config step (success) 18:17:26 INFO - [mozharness: 2016-09-02 01:17:26.484930Z] Running download-and-extract step. 18:17:26 INFO - Running pre-action listener: _resource_record_pre_action 18:17:26 INFO - Running main action method: download_and_extract 18:17:26 INFO - mkdir: /builds/slave/test/build/tests 18:17:26 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')]} 18:17:26 INFO - trying https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:17:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:17:27 INFO - Downloaded 1361 bytes. 18:17:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 18:17:27 INFO - Using the following test package requirements: 18:17:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:17:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:17:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:17:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'jsshell-linux-x86_64.zip'], 18:17:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:17:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 18:17:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:17:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:17:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:17:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 18:17:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:17:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:17: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')]} 18:17:27 INFO - trying https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:17:27 INFO - Downloading https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:17:28 INFO - Downloaded 21906037 bytes. 18:17:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:17:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:17:32 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')]} 18:17:32 INFO - trying https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:17:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 18:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:17:33 INFO - Downloaded 21906037 bytes. 18:17:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 18:17:37 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')]} 18:17:37 INFO - trying https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:17:37 INFO - Downloading https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 18:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:17:38 INFO - Downloaded 14413998 bytes. 18:17:38 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 18:17:38 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')]} 18:17:38 INFO - trying https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:17:38 INFO - Downloading https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:17:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:17:40 INFO - Downloaded 59744427 bytes. 18:17:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:17:40 INFO - mkdir: /builds/slave/test/properties 18:17:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:17:40 INFO - Writing to file /builds/slave/test/properties/build_url 18:17:40 INFO - Contents: 18:17:40 INFO - build_url:https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 18:17:40 INFO - Running post-action listener: _resource_record_post_action 18:17:40 INFO - Running post-action listener: set_extra_try_arguments 18:17:40 INFO - [mozharness: 2016-09-02 01:17:40.811092Z] Finished download-and-extract step (success) 18:17:40 INFO - [mozharness: 2016-09-02 01:17:40.811320Z] Running populate-webroot step. 18:17:40 INFO - Running pre-action listener: _resource_record_pre_action 18:17:40 INFO - Running main action method: populate_webroot 18:17:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:17:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:17:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:17:40 INFO - '--tppagecycles', 18:17:40 INFO - '7'], 18:17:40 INFO - 'tests': ['tsvgm']}}, 18:17:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:17:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:17:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:17:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:17:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:17:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:17:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['damp', 'tps']}, 18:17:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'tests': ['damp', 'tps']}, 18:17:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['dromaeo_dom']}, 18:17:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:17:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['basic_compositor_video']}, 18:17:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:17:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['a11yr', 18:17:40 INFO - 'ts_paint', 18:17:40 INFO - 'tpaint', 18:17:40 INFO - 'sessionrestore', 18:17:40 INFO - 'sessionrestore_no_auto_restore', 18:17:40 INFO - 'tabpaint']}, 18:17:40 INFO - 'other-e10s': {'tests': ['a11yr', 18:17:40 INFO - 'ts_paint', 18:17:40 INFO - 'tpaint', 18:17:40 INFO - 'sessionrestore', 18:17:40 INFO - 'sessionrestore_no_auto_restore', 18:17:40 INFO - 'tabpaint']}, 18:17:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:17:40 INFO - 'ts_paint', 18:17:40 INFO - 'tpaint', 18:17:40 INFO - 'sessionrestore', 18:17:40 INFO - 'sessionrestore_no_auto_restore', 18:17:40 INFO - 'tabpaint']}, 18:17:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:17:40 INFO - 'ts_paint', 18:17:40 INFO - 'tpaint', 18:17:40 INFO - 'sessionrestore', 18:17:40 INFO - 'sessionrestore_no_auto_restore', 18:17:40 INFO - 'tabpaint']}, 18:17:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['a11yr', 18:17:40 INFO - 'ts_paint', 18:17:40 INFO - 'tpaint', 18:17:40 INFO - 'sessionrestore', 18:17:40 INFO - 'sessionrestore_no_auto_restore', 18:17:40 INFO - 'tabpaint']}, 18:17:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['a11yr', 18:17:40 INFO - 'ts_paint', 18:17:40 INFO - 'tpaint', 18:17:40 INFO - 'sessionrestore', 18:17:40 INFO - 'sessionrestore_no_auto_restore', 18:17:40 INFO - 'tabpaint']}, 18:17:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['tsvgx', 18:17:40 INFO - 'tsvgr_opacity', 18:17:40 INFO - 'tart', 18:17:40 INFO - 'tscrollx', 18:17:40 INFO - 'cart']}, 18:17:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:17:40 INFO - 'tsvgr_opacity', 18:17:40 INFO - 'tart', 18:17:40 INFO - 'tscrollx', 18:17:40 INFO - 'cart']}, 18:17:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'talos_options': ['--disable-e10s'], 18:17:40 INFO - 'tests': ['tp5o']}, 18:17:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'tests': ['tp5o']}, 18:17:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'talos_options': ['--disable-e10s', 18:17:40 INFO - '--xperf_path', 18:17:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:17:40 INFO - 'tests': ['tp5n']}, 18:17:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:17:40 INFO - 'talos_options': ['--xperf_path', 18:17:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:17:40 INFO - 'tests': ['tp5n']}}, 18:17:40 INFO - 'talos.zip': {'path': '', 18:17:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:17:40 INFO - Running post-action listener: _resource_record_post_action 18:17:40 INFO - [mozharness: 2016-09-02 01:17:40.828458Z] Finished populate-webroot step (success) 18:17:40 INFO - [mozharness: 2016-09-02 01:17:40.828548Z] Running create-virtualenv step. 18:17:40 INFO - Running pre-action listener: _resource_record_pre_action 18:17:40 INFO - Running main action method: create_virtualenv 18:17:40 INFO - Creating virtualenv /builds/slave/test/build/venv 18:17:40 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 18:17:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:17:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:17:40 INFO - Using real prefix '/usr' 18:17:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:17:41 INFO - Installing distribute.............................................................................................................................................................................................done. 18:17:43 INFO - Installing pip.................done. 18:17:43 INFO - Return code: 0 18:17:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:17:43 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')]} 18:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:43 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')]} 18:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:43 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:43 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 18:17:43 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 18:17:43 INFO - Using env: {'DISPLAY': ':0', 18:17:43 INFO - 'HOME': '/home/cltbld', 18:17:43 INFO - 'LANG': 'en_US.UTF-8', 18:17:43 INFO - 'LANGUAGE': 'en_US:en', 18:17:43 INFO - 'LOGNAME': 'cltbld', 18:17:43 INFO - 'MAIL': '/var/mail/cltbld', 18:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:43 INFO - 'MOZ_NO_REMOTE': '1', 18:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:43 INFO - 'NO_EM_RESTART': '1', 18:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:43 INFO - 'PWD': '/builds/slave/test', 18:17:43 INFO - 'SHELL': '/bin/bash', 18:17:43 INFO - 'SHLVL': '1', 18:17:43 INFO - 'TERM': 'linux', 18:17:43 INFO - 'TMOUT': '86400', 18:17:43 INFO - 'USER': 'cltbld', 18:17:43 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:43 INFO - '_': '/tools/buildbot/bin/python'} 18:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:43 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 18:17:43 INFO - Cleaning up... 18:17:43 INFO - Return code: 0 18:17:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:17:43 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')]} 18:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:43 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')]} 18:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:43 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:43 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 18:17:43 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 18:17:43 INFO - Using env: {'DISPLAY': ':0', 18:17:43 INFO - 'HOME': '/home/cltbld', 18:17:43 INFO - 'LANG': 'en_US.UTF-8', 18:17:43 INFO - 'LANGUAGE': 'en_US:en', 18:17:43 INFO - 'LOGNAME': 'cltbld', 18:17:43 INFO - 'MAIL': '/var/mail/cltbld', 18:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:43 INFO - 'MOZ_NO_REMOTE': '1', 18:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:43 INFO - 'NO_EM_RESTART': '1', 18:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:43 INFO - 'PWD': '/builds/slave/test', 18:17:43 INFO - 'SHELL': '/bin/bash', 18:17:43 INFO - 'SHLVL': '1', 18:17:43 INFO - 'TERM': 'linux', 18:17:43 INFO - 'TMOUT': '86400', 18:17:43 INFO - 'USER': 'cltbld', 18:17:43 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:43 INFO - '_': '/tools/buildbot/bin/python'} 18:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:43 INFO - Downloading/unpacking psutil>=3.1.1 18:17:43 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 18:17:43 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 18:17:43 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 18:17:43 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 18:17:43 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 18:17:43 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 18:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:17:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:45 INFO - Installing collected packages: psutil 18:17:45 INFO - Running setup.py install for psutil 18:17:45 INFO - building 'psutil._psutil_linux' extension 18:17:45 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 18:17:46 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 18:17:46 INFO - building 'psutil._psutil_posix' extension 18:17:46 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 18:17:46 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 18:17:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:17:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:17:46 INFO - Successfully installed psutil 18:17:46 INFO - Cleaning up... 18:17:46 INFO - Return code: 0 18:17:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:17:46 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')]} 18:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:46 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')]} 18:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:46 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:46 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 18:17:46 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 18:17:46 INFO - Using env: {'DISPLAY': ':0', 18:17:46 INFO - 'HOME': '/home/cltbld', 18:17:46 INFO - 'LANG': 'en_US.UTF-8', 18:17:46 INFO - 'LANGUAGE': 'en_US:en', 18:17:46 INFO - 'LOGNAME': 'cltbld', 18:17:46 INFO - 'MAIL': '/var/mail/cltbld', 18:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:46 INFO - 'MOZ_NO_REMOTE': '1', 18:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:46 INFO - 'NO_EM_RESTART': '1', 18:17:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:46 INFO - 'PWD': '/builds/slave/test', 18:17:46 INFO - 'SHELL': '/bin/bash', 18:17:46 INFO - 'SHLVL': '1', 18:17:46 INFO - 'TERM': 'linux', 18:17:46 INFO - 'TMOUT': '86400', 18:17:46 INFO - 'USER': 'cltbld', 18:17:46 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:46 INFO - '_': '/tools/buildbot/bin/python'} 18:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:17:46 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 18:17:46 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 18:17:46 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 18:17:46 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 18:17:46 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 18:17:46 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 18:17:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:17:48 INFO - Installing collected packages: mozsystemmonitor 18:17:48 INFO - Running setup.py install for mozsystemmonitor 18:17:48 INFO - Successfully installed mozsystemmonitor 18:17:48 INFO - Cleaning up... 18:17:48 INFO - Return code: 0 18:17:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:17:48 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')]} 18:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:48 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')]} 18:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:48 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:48 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 18:17:48 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 18:17:48 INFO - Using env: {'DISPLAY': ':0', 18:17:48 INFO - 'HOME': '/home/cltbld', 18:17:48 INFO - 'LANG': 'en_US.UTF-8', 18:17:48 INFO - 'LANGUAGE': 'en_US:en', 18:17:48 INFO - 'LOGNAME': 'cltbld', 18:17:48 INFO - 'MAIL': '/var/mail/cltbld', 18:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:48 INFO - 'MOZ_NO_REMOTE': '1', 18:17:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:48 INFO - 'NO_EM_RESTART': '1', 18:17:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:48 INFO - 'PWD': '/builds/slave/test', 18:17:48 INFO - 'SHELL': '/bin/bash', 18:17:48 INFO - 'SHLVL': '1', 18:17:48 INFO - 'TERM': 'linux', 18:17:48 INFO - 'TMOUT': '86400', 18:17:48 INFO - 'USER': 'cltbld', 18:17:48 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:48 INFO - '_': '/tools/buildbot/bin/python'} 18:17:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:48 INFO - Downloading/unpacking jsonschema==2.5.1 18:17:48 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 18:17:48 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 18:17:48 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 18:17:48 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 18:17:48 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 18:17:48 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 18:17:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:17:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:17:50 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 18:17:50 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 18:17:50 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 18:17:50 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 18:17:50 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 18:17:50 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 18:17:50 INFO - Downloading functools32-3.2.3-2.tar.gz 18:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:17:51 INFO - warning: no files found matching '*.txt' 18:17:51 INFO - no previously-included directories found matching 'build' 18:17:51 INFO - no previously-included directories found matching 'dist' 18:17:51 INFO - no previously-included directories found matching '.git*' 18:17:51 INFO - Installing collected packages: jsonschema, functools32 18:17:51 INFO - Running setup.py install for functools32 18:17:51 INFO - warning: no files found matching '*.txt' 18:17:51 INFO - no previously-included directories found matching 'build' 18:17:51 INFO - no previously-included directories found matching 'dist' 18:17:51 INFO - no previously-included directories found matching '.git*' 18:17:51 INFO - Successfully installed jsonschema functools32 18:17:51 INFO - Cleaning up... 18:17:51 INFO - Return code: 0 18:17:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:17:51 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')]} 18:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:51 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')]} 18:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:51 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:51 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 18:17:51 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 18:17:51 INFO - Using env: {'DISPLAY': ':0', 18:17:51 INFO - 'HOME': '/home/cltbld', 18:17:51 INFO - 'LANG': 'en_US.UTF-8', 18:17:51 INFO - 'LANGUAGE': 'en_US:en', 18:17:51 INFO - 'LOGNAME': 'cltbld', 18:17:51 INFO - 'MAIL': '/var/mail/cltbld', 18:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:51 INFO - 'MOZ_NO_REMOTE': '1', 18:17:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:51 INFO - 'NO_EM_RESTART': '1', 18:17:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:51 INFO - 'PWD': '/builds/slave/test', 18:17:51 INFO - 'SHELL': '/bin/bash', 18:17:51 INFO - 'SHLVL': '1', 18:17:51 INFO - 'TERM': 'linux', 18:17:51 INFO - 'TMOUT': '86400', 18:17:51 INFO - 'USER': 'cltbld', 18:17:51 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:51 INFO - '_': '/tools/buildbot/bin/python'} 18:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:17:51 INFO - Cleaning up... 18:17:51 INFO - Return code: 0 18:17:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:17:51 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')]} 18:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:51 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')]} 18:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:51 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:51 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 18:17:51 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 18:17:51 INFO - Using env: {'DISPLAY': ':0', 18:17:51 INFO - 'HOME': '/home/cltbld', 18:17:51 INFO - 'LANG': 'en_US.UTF-8', 18:17:51 INFO - 'LANGUAGE': 'en_US:en', 18:17:51 INFO - 'LOGNAME': 'cltbld', 18:17:51 INFO - 'MAIL': '/var/mail/cltbld', 18:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:51 INFO - 'MOZ_NO_REMOTE': '1', 18:17:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:51 INFO - 'NO_EM_RESTART': '1', 18:17:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:51 INFO - 'PWD': '/builds/slave/test', 18:17:51 INFO - 'SHELL': '/bin/bash', 18:17:51 INFO - 'SHLVL': '1', 18:17:51 INFO - 'TERM': 'linux', 18:17:51 INFO - 'TMOUT': '86400', 18:17:51 INFO - 'USER': 'cltbld', 18:17:51 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:51 INFO - '_': '/tools/buildbot/bin/python'} 18:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:51 INFO - Downloading/unpacking blobuploader==1.2.4 18:17:51 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 18:17:51 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 18:17:51 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 18:17:51 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 18:17:51 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 18:17:51 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 18:17:53 INFO - Downloading blobuploader-1.2.4.tar.gz 18:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:17:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:17:53 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 18:17:53 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 18:17:53 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 18:17:53 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 18:17:53 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 18:17:53 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 18:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:17:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:17:54 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 18:17:54 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 18:17:54 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 18:17:54 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 18:17:54 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 18:17:54 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 18:17:54 INFO - Downloading docopt-0.6.1.tar.gz 18:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:17:54 INFO - Installing collected packages: blobuploader, requests, docopt 18:17:54 INFO - Running setup.py install for blobuploader 18:17:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:17:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:17:54 INFO - Running setup.py install for requests 18:17:54 INFO - Running setup.py install for docopt 18:17:54 INFO - Successfully installed blobuploader requests docopt 18:17:54 INFO - Cleaning up... 18:17:54 INFO - Return code: 0 18:17:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:54 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')]} 18:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:54 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')]} 18:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:54 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:54 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 18:17:54 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 18:17:54 INFO - Using env: {'DISPLAY': ':0', 18:17:54 INFO - 'HOME': '/home/cltbld', 18:17:54 INFO - 'LANG': 'en_US.UTF-8', 18:17:54 INFO - 'LANGUAGE': 'en_US:en', 18:17:54 INFO - 'LOGNAME': 'cltbld', 18:17:54 INFO - 'MAIL': '/var/mail/cltbld', 18:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:54 INFO - 'MOZ_NO_REMOTE': '1', 18:17:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:54 INFO - 'NO_EM_RESTART': '1', 18:17:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:54 INFO - 'PWD': '/builds/slave/test', 18:17:54 INFO - 'SHELL': '/bin/bash', 18:17:54 INFO - 'SHLVL': '1', 18:17:54 INFO - 'TERM': 'linux', 18:17:54 INFO - 'TMOUT': '86400', 18:17:54 INFO - 'USER': 'cltbld', 18:17:54 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:54 INFO - '_': '/tools/buildbot/bin/python'} 18:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:55 INFO - Running setup.py (path:/tmp/pip-Uqf6lK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:55 INFO - Running setup.py (path:/tmp/pip-46nHt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:55 INFO - Running setup.py (path:/tmp/pip-s2wEIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:55 INFO - Running setup.py (path:/tmp/pip-j5qGbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:55 INFO - Running setup.py (path:/tmp/pip-9Y_d6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:55 INFO - Running setup.py (path:/tmp/pip-EMejP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:55 INFO - Running setup.py (path:/tmp/pip-Pwv3BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:55 INFO - Running setup.py (path:/tmp/pip-ps92_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:55 INFO - Running setup.py (path:/tmp/pip-3lpV5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:55 INFO - Running setup.py (path:/tmp/pip-6KMfyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:55 INFO - Running setup.py (path:/tmp/pip-Kgjlju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:55 INFO - Running setup.py (path:/tmp/pip-Mo4V0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:55 INFO - Running setup.py (path:/tmp/pip-0ZAmUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:56 INFO - Running setup.py (path:/tmp/pip-JBdyIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:56 INFO - Running setup.py (path:/tmp/pip-s2UaSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:56 INFO - Running setup.py (path:/tmp/pip-VkPcBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:56 INFO - Running setup.py (path:/tmp/pip-DIKtAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:17:56 INFO - Running setup.py install for manifestparser 18:17:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:17:56 INFO - Running setup.py install for mozcrash 18:17:56 INFO - Running setup.py install for mozdebug 18:17:56 INFO - Running setup.py install for mozdevice 18:17:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:17:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:17:56 INFO - Running setup.py install for mozfile 18:17:56 INFO - Running setup.py install for mozhttpd 18:17:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:17:56 INFO - Running setup.py install for mozinfo 18:17:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Running setup.py install for mozInstall 18:17:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Running setup.py install for mozleak 18:17:57 INFO - Running setup.py install for mozlog 18:17:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Running setup.py install for moznetwork 18:17:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Running setup.py install for mozprocess 18:17:57 INFO - Running setup.py install for mozprofile 18:17:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Running setup.py install for mozrunner 18:17:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:17:57 INFO - Running setup.py install for mozscreenshot 18:17:57 INFO - Running setup.py install for moztest 18:17:58 INFO - Running setup.py install for mozversion 18:17:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:17:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:17:58 INFO - Cleaning up... 18:17:58 INFO - Return code: 0 18:17:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:17:58 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')]} 18:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:58 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')]} 18:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:17:58 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:17:58 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 18:17:58 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 18:17:58 INFO - Using env: {'DISPLAY': ':0', 18:17:58 INFO - 'HOME': '/home/cltbld', 18:17:58 INFO - 'LANG': 'en_US.UTF-8', 18:17:58 INFO - 'LANGUAGE': 'en_US:en', 18:17:58 INFO - 'LOGNAME': 'cltbld', 18:17:58 INFO - 'MAIL': '/var/mail/cltbld', 18:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:17:58 INFO - 'MOZ_NO_REMOTE': '1', 18:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:17:58 INFO - 'NO_EM_RESTART': '1', 18:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:17:58 INFO - 'PWD': '/builds/slave/test', 18:17:58 INFO - 'SHELL': '/bin/bash', 18:17:58 INFO - 'SHLVL': '1', 18:17:58 INFO - 'TERM': 'linux', 18:17:58 INFO - 'TMOUT': '86400', 18:17:58 INFO - 'USER': 'cltbld', 18:17:58 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:17:58 INFO - '_': '/tools/buildbot/bin/python'} 18:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:17:58 INFO - Running setup.py (path:/tmp/pip-7kcS3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:17:58 INFO - Running setup.py (path:/tmp/pip-Tu9Fii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:17:58 INFO - Running setup.py (path:/tmp/pip-Sib70X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:17:58 INFO - Running setup.py (path:/tmp/pip-KKqLep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:17:58 INFO - Running setup.py (path:/tmp/pip-hfSRyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:17:58 INFO - Running setup.py (path:/tmp/pip-LQrZ3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:17:58 INFO - Running setup.py (path:/tmp/pip-p9xzKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:17:58 INFO - Running setup.py (path:/tmp/pip-ueOaby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:17:58 INFO - Running setup.py (path:/tmp/pip-QO4pFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:17:58 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)) 18:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:17:59 INFO - Running setup.py (path:/tmp/pip-1QwOdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:17:59 INFO - Running setup.py (path:/tmp/pip-ZnfZm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:17:59 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:17:59 INFO - Running setup.py (path:/tmp/pip-Ojbtgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:17:59 INFO - Running setup.py (path:/tmp/pip-LAUynd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:17:59 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:17:59 INFO - Running setup.py (path:/tmp/pip-lKQkcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:59 INFO - Running setup.py (path:/tmp/pip-NQoLFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:17:59 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:17:59 INFO - Running setup.py (path:/tmp/pip-2Pzkwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 18:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:17:59 INFO - Running setup.py (path:/tmp/pip-gWuHS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:17:59 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)) 18:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:17:59 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)) 18:17:59 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:59 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:17:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 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 18:17:59 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 18:18:01 INFO - Downloading blessings-1.6.tar.gz 18:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:01 INFO - Installing collected packages: blessings 18:18:01 INFO - Running setup.py install for blessings 18:18:01 INFO - Successfully installed blessings 18:18:01 INFO - Cleaning up... 18:18:01 INFO - Return code: 0 18:18:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:01 INFO - Reading from file tmpfile_stdout 18:18:01 INFO - Current package versions: 18:18:01 INFO - argparse == 1.2.1 18:18:01 INFO - blessings == 1.6 18:18:01 INFO - blobuploader == 1.2.4 18:18:01 INFO - docopt == 0.6.1 18:18:01 INFO - functools32 == 3.2.3-2 18:18:01 INFO - jsonschema == 2.5.1 18:18:01 INFO - manifestparser == 1.1 18:18:01 INFO - mozInstall == 1.12 18:18:01 INFO - mozcrash == 0.17 18:18:01 INFO - mozdebug == 0.1 18:18:01 INFO - mozdevice == 0.48 18:18:01 INFO - mozfile == 1.2 18:18:01 INFO - mozhttpd == 0.7 18:18:01 INFO - mozinfo == 0.9 18:18:01 INFO - mozleak == 0.1 18:18:01 INFO - mozlog == 3.3 18:18:01 INFO - moznetwork == 0.27 18:18:01 INFO - mozprocess == 0.23 18:18:01 INFO - mozprofile == 0.28 18:18:01 INFO - mozrunner == 6.12 18:18:01 INFO - mozscreenshot == 0.1 18:18:01 INFO - mozsystemmonitor == 0.3 18:18:01 INFO - moztest == 0.7 18:18:01 INFO - mozversion == 1.4 18:18:01 INFO - psutil == 3.1.1 18:18:01 INFO - requests == 1.2.3 18:18:01 INFO - wsgiref == 0.1.2 18:18:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:01 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')]} 18:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:01 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')]} 18:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:01 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:18:01 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 18:18:01 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 18:18:01 INFO - Using env: {'DISPLAY': ':0', 18:18:01 INFO - 'HOME': '/home/cltbld', 18:18:01 INFO - 'LANG': 'en_US.UTF-8', 18:18:01 INFO - 'LANGUAGE': 'en_US:en', 18:18:01 INFO - 'LOGNAME': 'cltbld', 18:18:01 INFO - 'MAIL': '/var/mail/cltbld', 18:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:01 INFO - 'MOZ_NO_REMOTE': '1', 18:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:01 INFO - 'NO_EM_RESTART': '1', 18:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:01 INFO - 'PWD': '/builds/slave/test', 18:18:01 INFO - 'SHELL': '/bin/bash', 18:18:01 INFO - 'SHLVL': '1', 18:18:01 INFO - 'TERM': 'linux', 18:18:01 INFO - 'TMOUT': '86400', 18:18:01 INFO - 'USER': 'cltbld', 18:18:01 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:01 INFO - '_': '/tools/buildbot/bin/python'} 18:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 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)) 18:18:02 INFO - Cleaning up... 18:18:02 INFO - Return code: 0 18:18:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18:02 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')]} 18:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:02 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')]} 18:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:02 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 0x7ffa029e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cd88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da7230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, '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': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', '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 18:18:02 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 18:18:02 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 18:18:02 INFO - Using env: {'DISPLAY': ':0', 18:18:02 INFO - 'HOME': '/home/cltbld', 18:18:02 INFO - 'LANG': 'en_US.UTF-8', 18:18:02 INFO - 'LANGUAGE': 'en_US:en', 18:18:02 INFO - 'LOGNAME': 'cltbld', 18:18:02 INFO - 'MAIL': '/var/mail/cltbld', 18:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:02 INFO - 'MOZ_NO_REMOTE': '1', 18:18:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:02 INFO - 'NO_EM_RESTART': '1', 18:18:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:02 INFO - 'PWD': '/builds/slave/test', 18:18:02 INFO - 'SHELL': '/bin/bash', 18:18:02 INFO - 'SHLVL': '1', 18:18:02 INFO - 'TERM': 'linux', 18:18:02 INFO - 'TMOUT': '86400', 18:18:02 INFO - 'USER': 'cltbld', 18:18:02 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:02 INFO - '_': '/tools/buildbot/bin/python'} 18:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:18:02 INFO - Cleaning up... 18:18:02 INFO - Return code: 0 18:18:02 INFO - Running post-action listener: _resource_record_post_action 18:18:02 INFO - Running post-action listener: _start_resource_monitoring 18:18:02 INFO - Starting resource monitoring. 18:18:02 INFO - [mozharness: 2016-09-02 01:18:02.420393Z] Finished create-virtualenv step (success) 18:18:02 INFO - [mozharness: 2016-09-02 01:18:02.420909Z] Running install step. 18:18:02 INFO - Running pre-action listener: _resource_record_pre_action 18:18:02 INFO - Running main action method: install 18:18:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:02 INFO - Reading from file tmpfile_stdout 18:18:02 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:02 INFO - Reading from file tmpfile_stdout 18:18:02 INFO - Output received: 18:18:02 INFO - Usage: mozinstall [options] installer 18:18:02 INFO - Options: 18:18:02 INFO - -h, --help show this help message and exit 18:18:02 INFO - -d DEST, --destination=DEST 18:18:02 INFO - Directory to install application into. [default: 18:18:02 INFO - "/builds/slave/test"] 18:18:02 INFO - --app=APP Application being installed. [default: firefox] 18:18:02 INFO - mkdir: /builds/slave/test/build/application 18:18:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:18:19 INFO - Reading from file tmpfile_stdout 18:18:19 INFO - Output received: 18:18:19 INFO - /builds/slave/test/build/application/firefox/firefox 18:18:19 INFO - Running post-action listener: _resource_record_post_action 18:18:19 INFO - [mozharness: 2016-09-02 01:18:19.717219Z] Finished install step (success) 18:18:19 INFO - [mozharness: 2016-09-02 01:18:19.717675Z] Running run-tests step. 18:18:19 INFO - Running pre-action listener: _resource_record_pre_action 18:18:19 INFO - Running main action method: run_tests 18:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:18:19 INFO - Python 2.7.3 18:18:19 INFO - Return code: 0 18:18:19 INFO - grabbing minidump binary from tooltool 18:18:19 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')]} 18:18:19 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 0x1da1090>, '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 0x1da49b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da4e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:18:19 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 18:18:19 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 18:18:19 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 18:18:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:18:19 INFO - Return code: 0 18:18:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:18:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:18:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:18:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:18:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 18:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 18:18:19 INFO - Using env: {'DISPLAY': ':0', 18:18:19 INFO - 'HOME': '/home/cltbld', 18:18:19 INFO - 'LANG': 'en_US.UTF-8', 18:18:19 INFO - 'LANGUAGE': 'en_US:en', 18:18:19 INFO - 'LOGNAME': 'cltbld', 18:18:19 INFO - 'MAIL': '/var/mail/cltbld', 18:18:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:18:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:19 INFO - 'MOZ_NO_REMOTE': '1', 18:18:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:18:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:18:19 INFO - 'NO_EM_RESTART': '1', 18:18:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:19 INFO - 'PWD': '/builds/slave/test', 18:18:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:18:19 INFO - 'SHELL': '/bin/bash', 18:18:19 INFO - 'SHLVL': '1', 18:18:19 INFO - 'TERM': 'linux', 18:18:19 INFO - 'TMOUT': '86400', 18:18:19 INFO - 'USER': 'cltbld', 18:18:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046', 18:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:19 INFO - '_': '/tools/buildbot/bin/python'} 18:18:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 18:18:19 INFO - mozversion application_buildid: 20160901153146 18:18:19 INFO - mozversion application_changeset: bef3532d2dc5666785738ecd089363a84989ef40 18:18:19 INFO - mozversion application_display_name: Nightly 18:18:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:18:19 INFO - mozversion application_name: Firefox 18:18:19 INFO - mozversion application_remotingname: firefox 18:18:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:18:19 INFO - mozversion application_vendor: Mozilla 18:18:19 INFO - mozversion application_version: 51.0a1 18:18:19 INFO - mozversion platform_buildid: 20160901153146 18:18:19 INFO - mozversion platform_changeset: bef3532d2dc5666785738ecd089363a84989ef40 18:18:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:18:19 INFO - mozversion platform_version: 51.0a1 18:18:19 INFO - using testdate: 1472779099 18:18:19 INFO - actual date: 1472779099 18:18:19 INFO - starting webserver on 'localhost:33794' 18:18:19 INFO - SUITE-START | Running 2 tests 18:18:19 INFO - TEST-START | dromaeo_css 18:18:19 INFO - operating with platform_type : linux_ 18:18:19 INFO - Initialising browser for dromaeo_css test... 18:18:20 INFO - TEST-INFO | started process 2040 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv9eQXU/profile http://localhost:33794/getInfo.html) 18:18:24 INFO - PROCESS | 2040 | __metrics Screen width/height:1600/1200 18:18:24 INFO - PROCESS | 2040 | colorDepth:24 18:18:24 INFO - PROCESS | 2040 | Browser inner width/height: 1024/697 18:18:24 INFO - PROCESS | 2040 | __metrics 18:18:25 INFO - PROCESS | 2040 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:18:25 INFO - PROCESS | 2040 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:18:26 INFO - TEST-INFO | 2040: exit 0 18:18:26 INFO - Browser initialized. 18:18:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:18:26 INFO - TEST-INFO | started process 2118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv9eQXU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:18:38 INFO - PROCESS | 2118 | RSS: Main: 179298304 18:18:38 INFO - PROCESS | 2118 | 18:19:29 INFO - PROCESS | 2118 | 1472779169779 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:21:39 INFO - PROCESS | 2118 | Cycle 1(1): loaded http://localhost:33794/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33794/tests/dromaeo/cssquery-ext.html) 18:21:40 INFO - PROCESS | 2118 | RSS: Main: 275005440 18:21:40 INFO - PROCESS | 2118 | 18:24:42 INFO - PROCESS | 2118 | Cycle 1(1): loaded http://localhost:33794/tests/dromaeo/cssquery-ext.html (next: http://localhost:33794/tests/dromaeo/cssquery-jquery.html) 18:24:43 INFO - PROCESS | 2118 | RSS: Main: 277745664 18:24:43 INFO - PROCESS | 2118 | 18:27:45 INFO - PROCESS | 2118 | Cycle 1(1): loaded http://localhost:33794/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33794/tests/dromaeo/cssquery-mootools.html) 18:27:46 INFO - PROCESS | 2118 | RSS: Main: 219025408 18:27:46 INFO - PROCESS | 2118 | 18:30:48 INFO - PROCESS | 2118 | Cycle 1(1): loaded http://localhost:33794/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33794/tests/dromaeo/cssquery-prototype.html) 18:30:48 INFO - PROCESS | 2118 | RSS: Main: 219545600 18:30:48 INFO - PROCESS | 2118 | 18:33:51 INFO - PROCESS | 2118 | Cycle 1(1): loaded http://localhost:33794/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33794/tests/dromaeo/cssquery-yui.html) 18:33:52 INFO - PROCESS | 2118 | RSS: Main: 218886144 18:33:52 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | Cycle 1(1): loaded http://localhost:33794/tests/dromaeo/cssquery-yui.html (next: http://localhost:33794/tests/dromaeo/cssquery-dojo.html) 18:36:55 INFO - PROCESS | 2118 | RSS: Main: 309858304 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | __start_tp_report 18:36:55 INFO - PROCESS | 2118 | _x_x_mozilla_page_load 18:36:55 INFO - PROCESS | 2118 | _x_x_mozilla_page_load_details 18:36:55 INFO - PROCESS | 2118 | |i|pagename|runs| 18:36:55 INFO - PROCESS | 2118 | |0;dojo.html;3003.7146471085243;3008.6840881707426;3030.303030303032;3055.0376631361105;3074.323648797264;5050.368703912011;5156.767945442455;5187.1959846223845;5244.344456942887;5269.710165940872;5205.427402985678;5217.295665085212;5307.1243244864645;5308;5416.593755468336;4697.53024697531;4743.312219728155;4765.571098601134;4766.118268120409;4817.373741413611;4752.714837109785;4791.784369703354;4807.567318941295;4867.780949857247;4903.166461701519;4828.951710482903;4839.975800121012;4843.24929635907;4877.8780530486665;4889.779959901813;4650.372199753024;4675.719456832601;4717.410323709536;4718.787654555554;4801.783919723621;4668.486466488684;4668.836590719308;4679.040796636681;4726.983698504803;4765.928511072302;5220.060389129955;5226.503482169188;5236.973815130938;5263.526282634582;5284.180951952453;5414.160805075219;5595.38450770415;5607.551395888319;5625.6905870177125;5679.204911312419;5651.745671444794;5677.432256774289;5693.03218452864;5696.11710184922;5749.137629355589;5305.7612407441375;5310.522053015247;5316.069687804618;5318.680879147302;5321.467853214647;5505.779768809202;5649.971750141223;5702.144678298288;5702.45826646468;5784.1902133701315;5451.209574611738;5452.04589196889;5452.563794896399;5458.2904222450825;5517.172424136332;5528.226048353154;5530.889382212414;5533.833984980457;5536.086545719992;5539.833804985857;5331.173668081454;5549.916751248735;5566.944330556724;5573.108302671553;5621.747021384048;5634.830955071355;5637.379888212294;5639.605227634026;5642.435756424325;5699.943000569941;4352.847650332224;4389.912201755947;4390.714603550759;4393.956060439418;4442.067165895133;3807.0862992881507;3881.84472621092;3941.8620348287673;3955.090329224237;3959.287328280937;5402.459754024645;5402.972985135049;5403.7838486460105;5408.45915408456;5432.809851655173;5624.746886390122;5658.292713410824;5672.688002159838;5673.886522269531;5674.489295963301;5153.4073581538605;5313.07021271276;5368.785248590091;5369.865753356197;5459.863503412446;4863.294822250754;4863.683860549053;4877.975610121926;4896.020795840846;4926.039422312615;5782.161586569924;5811.767529298865;5815.8255252340805;5819.0689489681445;5824.446677565625;5471.316085489314;5563.916541251803;5616.887662246814;5618.971905140448;5643.830685079454;5833.125031245348;5839.941600583939;5890.587658863838;5892.823215303376;5902.557308201816;5541.695206763667;5548.4174161712435;5549.223108764857;5558.471945165203;5559.444055594408;5411.9717253721665;5541;5597.244372009726;5603.963266795656;5606.607537472338;5478.287822583039;5506.311711036121;5508.669479831223;5534.723263836872;5571.777128914798;5440.972795135999;5477.698726569917;5490.258815059916;5493.587980901529;5495.807646732345;5247.737613119252;5347.411784703757;5425.891482170256;5437.157240627709;5509.5041446270025;5495.505404513613;5512.503874651301;5523.696196709219;5573.888522229614;5578.944210557843;5372.838814835562;5384.461553844741;5387.488188622075;5388.299521062237;5391.7034563099405 18:36:55 INFO - PROCESS | 2118 | |1;ext.html;595.6872641863022;596.9970150149248;606.7754930675651;620.8975519039359;621.5276389943641;41808.37287440687;44416.55583444165;44417.55582444175;44595.77702111489;47604;45620.771896140555;45851;46046.079078418414;46109.76945115278;46453.53546464534;8284.088750237499;9055.501947392891;9231.95384023079;9233.907660923405;9249.537523123818;10663.946680266627;10974.067204287645;10993.065589424908;11369.317840929529;11386.487608057658;11135.276207046556;11221;11229.38238396892;11234.3259404436;11236.550537978472;10847.566097356097;10864.293820901696;10889.564417423373;10901.073408760225;10904.509297081571;10707.732306692316;10771.407572583505;10772.138228941665;10777.191710621657;10781.892181078245;43205.78397108026;43306.78346608279;43415.13169736619;43556.12887742259;43639;3531.964680353189;3635.9272814543824;3693.593704692482;3704.9255715842373;3935.822887970048;32326.838365808257;32784.50823237653;33458.33083338344;33852.322953541036;34156.316873662385;4007.9599204007877;4035.556088830226;4156;4268;4283.571642835723;348866;351352;352092;353271;353493;61923.69038154781;62330;62380;62392;62445.68777156085;36438.27123457516;37131.62868371336;37432.81283593619;39614.801925990185;64485.35514644887;17430.47708568745;17602.91198543999;17604.119794010247;17615.64768704619;17618.823811761973;12050.337231452178;12937.805932911015;13173.40719667598;13216.810487056211;13294.734105317839;19189.520261993282;19233.422997310103;19294.32469863548;19386.321478748177;19416.708749368772;6943.89584156238;7186.568805871664;7337.853242935112;7349.779506614703;7445.181030086686;910.5219759626117;1064.3613831700918;1091.497910960951;1095.2260270228662;1140.4297851074464;32190.83904580462;32454;32522.674773252438;32613.836930815192;32920.341593168;1053.1311667874033;1091.110744743032;1105.082781291526;1155.4165146600913;1193.075366590899;16774.412895548598;17074.57313567146;17239.553148544157;17257.827421725622;17265.56836079108;550489;560194;561145;561624;562119;58155.709221453624;59032.70483647554;59051.409485904594;59057.40942590605;59093.04534773244;1220.3227208899145;1275.0309764578988;1278.9104762666525;1348.1978222957325;1353.2759973414372;26381.34046648849;31210;32810.67189328076;35027.649723502436;35064.12339691528;56331;56948.43051569597;56991.430085698616;57035.87426011818;57088;480151;486582;487505;488062;488481;24068.638970415108;24093.9520140783;28573.428531429672;29048.966572339734;30389.544156838107;47006.76496617495;47884.5211547894;48072;48224;48319.75840120777;39391;39934;40126.79936600298;40893.59106409017;42303.36544951767;40059.39910901397;40073.59926400815;40268.798656007704;41227.38158927559;42220.788896055325;40246.1950760989;40697.1860562793;41072.97317567085;41457.58542414537;41710.79144604379;33983.320333592696;35933.64066359303;36438.08904777402;36770;36900.446493302086;27563.724362756115;27930.44139117247;27963;28021.579676304464;28178.154655360373 18:36:55 INFO - PROCESS | 2118 | |2;jquery.html;373.2143837222648;380.47301514956337;394.982225799839;396.63311436920844;417.42187070906783;14180.22008789516;17833.64332713345;17876.748627596073;19626.018699065044;19633.607327853453;17570.29718811249;18941.90529047356;19348.51628709283;19493;19499.317523886668;5563.805266815642;5648.152777083439;5799.536037117021;5808.070708686611;5950.077737950624;5596.972015139919;5869;5892.734826932799;5900.350961394243;5900.734466948998;5883.088121341178;5906.2912450506;5907.232059832216;5912.852178695524;5915.408459154093;5885.646861188321;5889.293284805808;5891.440313170242;7065.929340706579;7375.078115235596;7163.67763450646;7192.64036798158;7294.81762955925;7342.743003994888;7366.337029667356;23263.883680581657;23463.7653623465;23706.525869482688;23983.40041498959;24884;2501.562226610336;2938.2360586247514;3238.352329534079;3290.083640311951;3295.1762059485127;15511.922440387838;15737.527874163794;15844.44544440939;16154.596135096717;16670.416535421202;2030.2691031228746;2104.821090207319;2120.363890832744;2191.649336106231;2216.1910902520463;85167.57416212941;93693.31638122264;96819;97541.51229243808;100581.4970925148;17568.736468952975;17736;17928.9103554484;18645.627087458175;18707;25321.87339063293;29487.705122948497;31637.683623163935;31878.68121318757;31937.201569960936;9868.901310986941;9907.702768916944;10052.899471005196;10123.848142277871;10400.47997600117;8108.716194933267;8187.754367368989;8189.672413103409;8428.662853485792;8434.283085937765;8719.8083209731;8763;8793.38446308765;8798.12018798115;9090.454572725524;3314.751393645486;3418.265073009315;3479.634638362986;3495.80134177225;3501.352249833738;897.5422534507449;929.0198840223629;983.1348413396308;1014.9391036537831;1026.2816028779885;17081.66211295536;20137.295194668117;20831.791682083287;20962.895185523976;21163.576728465345;954.6324665004023;963.7638778499172;964.3587014635189;972.3436680240951;1017.1862509991977;10318.896811031944;10823.621173258898;10971.780564388668;10992.615258465916;11036.172287078502;491230;492401;505166;505974;506252;99184.00815991747;99726.50136749414;100156;102606;104777;1017.1811691588342;1033.5452400943711;1071.3357718214602;1077.36435503052;1081.859358283434;16916.407925723033;16964.830351696324;17482.125893705008;18025.819741802938;18313.633727325112;39477.605223947394;42144.78927605342;43117.13765724605;43964.34053489264;44135;51287;60184.39815601788;60231.69884150551;62303;63539;6557.540972131905;7456.589887556236;8374.330053595819;8547.487150770974;8624.611892464729;12326.938365308117;13127.934360328138;13161.53934612284;13223.140495867738;13445.327733613085;11775.823362649739;11857.407129643656;11921.761564768829;12015.158938874194;12453.813192801934;11744.119191060876;11853.5851245206;11919.284842909454;11933;12347.13570050088;11773.52905883755;11794.587189448328;11837.993770529602;12017.939910300393;12022.699432514257;7429.5542267464125;7490.325870671666;7687.11598166218;7936.920630793618;9035.864462032943;10525.36847789135;11365.48855301497;11563.30620162793;11684.18210725241;12212.816807747713 18:36:55 INFO - PROCESS | 2118 | |3;mootools.html;1319.9406026728795;1477.6305923519121;1482.207019244704;1645.9917700411497;1757.0511923561278;8001;8413.663453461862;8436.072032076467;8646.092160323155;8672.47965122093;8536.402451828375;8633.30933525318;8647.092055334193;8650.307975361971;8652;2024.2105578824196;2276.157821606008;2287.1194590082855;2314.2710046335433;2318.3740390094663;2274.1812947338944;2293.174457195408;2302.412884714404;2305.2392710405525;2305.8385912986096;2307.9307620771406;2311.1102225643162;2320.071971211512;2350.6356514740155;2354.9764502355015;2130.371540395587;2196.934091977243;2201.3175915466095;2202.0861342542826;2206.5255969966374;2338.871362075085;2340.8244381671443;2350.294911526535;2358.2217868103658;2358.9174378896655;8635.52504612246;8638.827223455495;8692.304615630797;8762.956185219033;8767;4981.427135879384;5110.744462776846;5117.257997590365;5118.2578526113875;5118.616103792193;2993.850307484639;3155.25851424915;3200.1679563313705;3201.119692084672;3218.951715724266;945.9479728614923;991.6430085169334;994.1351024608637;1006.6376104602338;1008.2639673038723;11443.942780286128;11476.770464590745;11536.769264614744;11592.652220433316;11706.53173873044;4140.68944829133;4168.562300958416;4294.312909934395;4306.138772245564;4389.692721509464;9421.858672119924;9426.528673566294;9432.151106400319;9441.858372124423;9447.905520944703;4982.10322142018;5008.84973450797;5012.298278241049;5013.874653133701;5039.244113383022;4122.484689413824;4623.16782979067;4681.438227412732;4719.126961512131;4729.858104256878;3642.6357364263363;3695.279420512974;3825.7704537727827;3840.1551658635058;3922.058705910561;1099.7745462180394;1104.5029736618553;1128.5203788389902;1129.1418521923233;1136.971575710614;835.5863847395457;894.4186278918755;935.6584846531103;948.8813898262717;965.8261512927741;5921.3190483094995;6026.885026270154;6040.063790112452;6054.39456054391;6446.194225721785;928.4429342394644;969.6024629901707;1000.1198944928417;1010.6816352848829;1074.8280275155937;3951.9012024699036;3960.2673505401594;3992.760434373889;4080.204360149742;4147.428594271571;9740.659076932274;10286.691399258034;10368.896311036793;10378.68863934068;10391.740206494747;9977.750556236153;10240.48797560119;10242.385456872462;10245.077942985166;10255.435951022617;7941.6029198539845;8044.839103217902;8079.636416361278;8082.676692932217;8101.027876654839;5644.266245388073;5855.94144058571;5872.882542349214;5913.970430147821;6095.969520152371;9743.610255589698;9761.463119528495;9815.754606134618;9870.161036311994;9870.753231169278;9464.432134071978;10110.494475276353;10128.544215510467;10129.139023182817;10148.340357876714;6555.147830781968;6701.798946031627;6701.865962680621;7014.0531028310525;7125.714971401293;7877.054753429625;8075.515469071875;8182.7545173644885;8294.294984926108;8571.485710857369;7534.321911028033;7654.693812247448;7662.310392064741;7726.1501234863;7929.444938854225;7361.852762944818;7466.365358944333;7507.512011719221;7642.312191902755;7771.145174030739;6922.653867306513;7033.261507541634;7035.788926332219;7043.577385356895;7045.682944267418;4976.975115124401;5163.690178589297;5192.844214673566;5195.53240208368;5363.356397232357;5114;5138.3577052868395;5174.430812610534;5247.1866860636665;5311.3891902431715 18:36:55 INFO - PROCESS | 2118 | |4;prototype.html;311.9594452721146;320.1292484442316;323.91092449576365;324.3318763347506;324.7888872233048;5226.8693282667955;5300.549453296469;5305.124654432024;5310.15037593985;5329.147336426173;5201.687898726079;5221.843344699656;5246.422893481714;5253.50091741284;5261.605674496262;3891.241207964441;3895.8636447724334;4027.2750904837117;4030.7581545107387;4060.309747342959;4034.475518182632;4040.313146765057;4046.514418269812;4054.3715724062818;4065.125997910449;3987.48162738845;3999.860004899829;4002.6197511236387;4005.83976640934;4007.098402859348;3865.1303456722294;3918.3338832398417;3972.8609498667697;3978.781167035812;3983.4821473208585;3514.9297014059575;3516.6307537708685;3522.2779330237495;3522.4011917974008;3540.6368548109162;5038.193888977783;5044.823431179928;5078.060558796597;5078.162103252959;5134.332536770234;4450.844220452301;4608.331791890158;4617.561331673485;4640.257558790578;4654.953450465486;5377.0321342158395;5378.811741589026;5379.892402151935;5442.564594832404;5461.371942226615;3903.8243279052513;3913.5108111486065;3925.391564307537;3930.233604447134;3931.9803400983096;5337.412884582693;5648.8870222595315;5692.231548740909;5692.943070569283;5706.4864162225185;3934.8229329680235;3964.5837187095517;3966.2265858157957;3967.1867267210155;3978.5822488638278;4024.69814763888;4032.153247817991;4039.555648878621;4050.9392359114636;4057.655099316532;4512.458504979423;4644.489106198315;4669.579737823612;4680.789364478606;4684.297355396718;5478.835634930959;5485.862853428696;5501.422350653123;5520.309961254843;5570.192322113352;1367.863213678624;1371.4856928651755;1380.1443105274793;1393.6794537256487;1394.9442022319197;837.7696133563319;881.1584936385736;882.3294296334703;898.820235952812;899.8605216191501;5124.846254612367;5260.368755749258;5342.412334643186;5393.83818485446;5398.541124004425;5620.100783874561;5628.437156284421;5661.801836935688;5679.466544033171;5683.147527870852;5112.897742045213;5323.920141197885;5355.464453554691;5360.91958620621;5364.9731751341;2949.705029497033;2968.85155742212;2975.5834183214365;2983.523156037775;2988.133441301998;5815.86590614826;5829.358770535237;5844.620099693507;5854.385289544622;5863.941360586339;5440.510354068153;5758.971205143947;5762.4525670061275;5786.450287222707;5805.419458054245;5874.295084589877;5900.232969713996;5901.76392944286;5913.5564832636865;5926.111083337534;5464.426582944977;5666.575006874584;5669.886602268014;5685.118806584986;5709.914351284651;5566.165075238585;5575.693336866511;5591.412901645269;5593.384727679871;5606.271184745957;5848.356680765198;5863;5869.148973398918;5899.292084949833;5900.232969713909;3873.322168620424;3894.9221015578964;3943.4282029106193;3955.268275369066;3955.92088158241;5640.210370548045;5667.574931880043;5668.234788303526;5677.1768093627015;5677.290338707661;5512.889742205053;5515.7865269640615;5520;5542.667439953616;5558.527525160407;5347.251384806208;5389.110796718497;5452.863678408072;5464.18037294409;5478.835634930959;5431.511163995101;5450.863728406822;5473.945260547343;5474.835754927359;5498.697571633598;5340.919886201632;5372.200312895067;5492.846502234415;5528.336599608073;5529.834104976696;5400.4059553448305;5409.6213265071065;5412.350517937873;5415.810446634349;5427.294451721251 18:36:55 INFO - PROCESS | 2118 | |5;yui.html;740.2264633458036;758.154657556824;777.7511196417149;778.3500776851329;803.8874557561941;15886.364545418184;17345;17356.91321543392;17372.826271737278;17453.127343632816;15581.688366232696;15733.213339333044;15740.449084282052;15751.291191896364;15754.921225393886;642.4828013449171;739.4934469888144;742.4542960923717;749.7713197474768;785.3795501553766;796.4663675337538;796.5539297993114;800.4276941986478;801.234820746186;802.0535767794;848.6944699908029;851.9233269005788;852.3692467574012;852.799592095857;853.6286715278874;672.5964421347202;779.3375630713905;791.0941971442666;798.2456578533288;842.3093063687778;609.1380696314709;610.914471973924;611.843979785152;612.0116012639555;612.1063247658379;14596.708065838624;14614.707705845824;14617.342219600143;14637.194954277511;14640.707185856223;13504.527341543097;13661.590152295446;13673.24797136157;13756.862431375659;13804.481238726095;12225.144239903211;12317.34843384941;12520;12528.874711252862;12912.677183070404;4223.894402639928;4239.512456067561;4243.108947121108;4453.421055262795;4458.576435238647;66930.3306966929;68182.9772553417;69329.65335173292;80222.5988870052;82059.5897020517;11857.466414011391;11911.583094591648;11989.160758746804;12113.818292725617;12577.811332830015;21186.788132118483;21299.254526091514;21317.29461643065;21330;21333.573328533654;8177.1414001530175;8178.46839955401;8442.746717598358;8490.23587877094;8644.092370301154;4318.395424640553;4331.935020974688;4579.862604121881;4707.8587642370785;4864.6594738368685;3421.0934102462866;3546.911327216789;3553.236054248349;3553.591336996227;3561.6260292669413;587.0460501684762;626.8558231606756;627.6046090962665;629.7795771480037;631.6178711879339;604.1934018085817;636.665750480992;646.9906945165557;676.4453148418348;723.9638018099074;8553.615087321086;8907;9784.657536986171;10194.43930583825;10302.742431439125;674.865026994603;718.4360277182386;734.229059487536;739.737393225408;761.474582538047;4487.708298960557;4629.490756016836;4672.976635116803;4688.109259240733;4700.647451441156;122048;123851;126126.36936815258;128021;132700;108146;109181;109185;111634;112044.4397778006;96045.51977240069;96936;98240.50879745555;100363.99636003692;100684;8986.82026359456;9006.954965225132;9140.862887056566;9204.95397523008;9274.397164184307;39789.40315895206;43400.131997360506;49942.500574993785;53013.469865302395;54118.458815411344;48827;50534;60333;62619;75829.24170758222;12378.195417297848;12380.938095309466;12729.29988850622;12803.807942880678;13214.86785132175;13747.243901585109;13825.861741382458;14122.858771412155;14957.401703932157;15062.397504099714;13792.862071379157;14294.356753945889;14668.486602968846;14984.775228371365;15009.549713508612;13044.152130111512;13242.867571324548;13719.314034298444;13840.515581954583;13888.444462221398;12139.939300303442;12273.938630306791;13122.60632181036;13139.408726607517;13448.46206151743;10528.157747380037;10606.000554872766;11035.613753518588;11065;11316.60391886313;9765.193439213768;9808.65669701557;9858.30991830565;10062.295639305179;10646.8402973957 18:36:55 INFO - PROCESS | 2118 | __end_tp_report 18:36:55 INFO - PROCESS | 2118 | __start_cc_report 18:36:55 INFO - PROCESS | 2118 | _x_x_mozilla_cycle_collect,1007 18:36:55 INFO - PROCESS | 2118 | __end_cc_report 18:36:55 INFO - PROCESS | 2118 | __startTimestamp1472780215340__endTimestamp 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | ------- Summary: start ------- 18:36:55 INFO - PROCESS | 2118 | Number of tests: 6 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | [#0] dojo.html Cycles:165 Average:5221.93 Median:5416.59 stddev:581.84 (10.7%) stddev-sans-first:557.00 18:36:55 INFO - PROCESS | 2118 | Values: 3003.7 3008.7 3030.3 3055.0 3074.3 5050.4 5156.8 5187.2 5244.3 5269.7 5205.4 5217.3 5307.1 5308.0 5416.6 4697.5 4743.3 4765.6 4766.1 4817.4 4752.7 4791.8 4807.6 4867.8 4903.2 4829.0 4840.0 4843.2 4877.9 4889.8 4650.4 4675.7 4717.4 4718.8 4801.8 4668.5 4668.8 4679.0 4727.0 4765.9 5220.1 5226.5 5237.0 5263.5 5284.2 5414.2 5595.4 5607.6 5625.7 5679.2 5651.7 5677.4 5693.0 5696.1 5749.1 5305.8 5310.5 5316.1 5318.7 5321.5 5505.8 5650.0 5702.1 5702.5 5784.2 5451.2 5452.0 5452.6 5458.3 5517.2 5528.2 5530.9 5533.8 5536.1 5539.8 5331.2 5549.9 5566.9 5573.1 5621.7 5634.8 5637.4 5639.6 5642.4 5699.9 4352.8 4389.9 4390.7 4394.0 4442.1 3807.1 3881.8 3941.9 3955.1 3959.3 5402.5 5403.0 5403.8 5408.5 5432.8 5624.7 5658.3 5672.7 5673.9 5674.5 5153.4 5313.1 5368.8 5369.9 5459.9 4863.3 4863.7 4878.0 4896.0 4926.0 5782.2 5811.8 5815.8 5819.1 5824.4 5471.3 5563.9 5616.9 5619.0 5643.8 5833.1 5839.9 5890.6 5892.8 5902.6 5541.7 5548.4 5549.2 5558.5 5559.4 5412.0 5541.0 5597.2 5604.0 5606.6 5478.3 5506.3 5508.7 5534.7 5571.8 5441.0 5477.7 5490.3 5493.6 5495.8 5247.7 5347.4 5425.9 5437.2 5509.5 5495.5 5512.5 5523.7 5573.9 5578.9 5372.8 5384.5 5387.5 5388.3 5391.7 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | [#1] ext.html Cycles:180 Average:62516.55 Median:36835.22 stddev:125707.05 (341.3%) stddev-sans-first:125973.72 18:36:55 INFO - PROCESS | 2118 | Values: 595.7 597.0 606.8 620.9 621.5 41808.4 44416.6 44417.6 44595.8 47604.0 45620.8 45851.0 46046.1 46109.8 46453.5 8284.1 9055.5 9232.0 9233.9 9249.5 10663.9 10974.1 10993.1 11369.3 11386.5 11135.3 11221.0 11229.4 11234.3 11236.6 10847.6 10864.3 10889.6 10901.1 10904.5 10707.7 10771.4 10772.1 10777.2 10781.9 43205.8 43306.8 43415.1 43556.1 43639.0 3532.0 3635.9 3693.6 3704.9 3935.8 32326.8 32784.5 33458.3 33852.3 34156.3 4008.0 4035.6 4156.0 4268.0 4283.6 348866.0 351352.0 352092.0 353271.0 353493.0 61923.7 62330.0 62380.0 62392.0 62445.7 36438.3 37131.6 37432.8 39614.8 64485.4 17430.5 17602.9 17604.1 17615.6 17618.8 12050.3 12937.8 13173.4 13216.8 13294.7 19189.5 19233.4 19294.3 19386.3 19416.7 6943.9 7186.6 7337.9 7349.8 7445.2 910.5 1064.4 1091.5 1095.2 1140.4 32190.8 32454.0 32522.7 32613.8 32920.3 1053.1 1091.1 1105.1 1155.4 1193.1 16774.4 17074.6 17239.6 17257.8 17265.6 550489.0 560194.0 561145.0 561624.0 562119.0 58155.7 59032.7 59051.4 59057.4 59093.0 1220.3 1275.0 1278.9 1348.2 1353.3 26381.3 31210.0 32810.7 35027.6 35064.1 56331.0 56948.4 56991.4 57035.9 57088.0 480151.0 486582.0 487505.0 488062.0 488481.0 24068.6 24094.0 28573.4 29049.0 30389.5 47006.8 47884.5 48072.0 48224.0 48319.8 39391.0 39934.0 40126.8 40893.6 42303.4 40059.4 40073.6 40268.8 41227.4 42220.8 40246.2 40697.2 41073.0 41457.6 41710.8 33983.3 35933.6 36438.1 36770.0 36900.4 27563.7 27930.4 27963.0 28021.6 28178.2 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | [#2] jquery.html Cycles:180 Average:31059.41 Median:31907.94 stddev:82738.52 (259.3%) stddev-sans-first:82938.54 18:36:55 INFO - PROCESS | 2118 | Values: 373.2 380.5 395.0 396.6 417.4 14180.2 17833.6 17876.7 19626.0 19633.6 17570.3 18941.9 19348.5 19493.0 19499.3 5563.8 5648.2 5799.5 5808.1 5950.1 5597.0 5869.0 5892.7 5900.4 5900.7 5883.1 5906.3 5907.2 5912.9 5915.4 5885.6 5889.3 5891.4 7065.9 7375.1 7163.7 7192.6 7294.8 7342.7 7366.3 23263.9 23463.8 23706.5 23983.4 24884.0 2501.6 2938.2 3238.4 3290.1 3295.2 15511.9 15737.5 15844.4 16154.6 16670.4 2030.3 2104.8 2120.4 2191.6 2216.2 85167.6 93693.3 96819.0 97541.5 100581.5 17568.7 17736.0 17928.9 18645.6 18707.0 25321.9 29487.7 31637.7 31878.7 31937.2 9868.9 9907.7 10052.9 10123.8 10400.5 8108.7 8187.8 8189.7 8428.7 8434.3 8719.8 8763.0 8793.4 8798.1 9090.5 3314.8 3418.3 3479.6 3495.8 3501.4 897.5 929.0 983.1 1014.9 1026.3 17081.7 20137.3 20831.8 20962.9 21163.6 954.6 963.8 964.4 972.3 1017.2 10318.9 10823.6 10971.8 10992.6 11036.2 491230.0 492401.0 505166.0 505974.0 506252.0 99184.0 99726.5 100156.0 102606.0 104777.0 1017.2 1033.5 1071.3 1077.4 1081.9 16916.4 16964.8 17482.1 18025.8 18313.6 39477.6 42144.8 43117.1 43964.3 44135.0 51287.0 60184.4 60231.7 62303.0 63539.0 6557.5 7456.6 8374.3 8547.5 8624.6 12326.9 13127.9 13161.5 13223.1 13445.3 11775.8 11857.4 11921.8 12015.2 12453.8 11744.1 11853.6 11919.3 11933.0 12347.1 11773.5 11794.6 11838.0 12017.9 12022.7 7429.6 7490.3 7687.1 7936.9 9035.9 10525.4 11365.5 11563.3 11684.2 12212.8 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | [#3] mootools.html Cycles:180 Average:5598.04 Median:5115.63 stddev:3142.32 (61.4%) stddev-sans-first:3134.68 18:36:55 INFO - PROCESS | 2118 | Values: 1319.9 1477.6 1482.2 1646.0 1757.1 8001.0 8413.7 8436.1 8646.1 8672.5 8536.4 8633.3 8647.1 8650.3 8652.0 2024.2 2276.2 2287.1 2314.3 2318.4 2274.2 2293.2 2302.4 2305.2 2305.8 2307.9 2311.1 2320.1 2350.6 2355.0 2130.4 2196.9 2201.3 2202.1 2206.5 2338.9 2340.8 2350.3 2358.2 2358.9 8635.5 8638.8 8692.3 8763.0 8767.0 4981.4 5110.7 5117.3 5118.3 5118.6 2993.9 3155.3 3200.2 3201.1 3219.0 945.9 991.6 994.1 1006.6 1008.3 11443.9 11476.8 11536.8 11592.7 11706.5 4140.7 4168.6 4294.3 4306.1 4389.7 9421.9 9426.5 9432.2 9441.9 9447.9 4982.1 5008.8 5012.3 5013.9 5039.2 4122.5 4623.2 4681.4 4719.1 4729.9 3642.6 3695.3 3825.8 3840.2 3922.1 1099.8 1104.5 1128.5 1129.1 1137.0 835.6 894.4 935.7 948.9 965.8 5921.3 6026.9 6040.1 6054.4 6446.2 928.4 969.6 1000.1 1010.7 1074.8 3951.9 3960.3 3992.8 4080.2 4147.4 9740.7 10286.7 10368.9 10378.7 10391.7 9977.8 10240.5 10242.4 10245.1 10255.4 7941.6 8044.8 8079.6 8082.7 8101.0 5644.3 5855.9 5872.9 5914.0 6096.0 9743.6 9761.5 9815.8 9870.2 9870.8 9464.4 10110.5 10128.5 10129.1 10148.3 6555.1 6701.8 6701.9 7014.1 7125.7 7877.1 8075.5 8182.8 8294.3 8571.5 7534.3 7654.7 7662.3 7726.2 7929.4 7361.9 7466.4 7507.5 7642.3 7771.1 6922.7 7033.3 7035.8 7043.6 7045.7 4977.0 5163.7 5192.8 5195.5 5363.4 5114.0 5138.4 5174.4 5247.2 5311.4 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | [#4] prototype.html Cycles:180 Average:4612.48 Median:5341.67 stddev:1380.56 (25.8%) stddev-sans-first:1346.17 18:36:55 INFO - PROCESS | 2118 | Values: 312.0 320.1 323.9 324.3 324.8 5226.9 5300.5 5305.1 5310.2 5329.1 5201.7 5221.8 5246.4 5253.5 5261.6 3891.2 3895.9 4027.3 4030.8 4060.3 4034.5 4040.3 4046.5 4054.4 4065.1 3987.5 3999.9 4002.6 4005.8 4007.1 3865.1 3918.3 3972.9 3978.8 3983.5 3514.9 3516.6 3522.3 3522.4 3540.6 5038.2 5044.8 5078.1 5078.2 5134.3 4450.8 4608.3 4617.6 4640.3 4655.0 5377.0 5378.8 5379.9 5442.6 5461.4 3903.8 3913.5 3925.4 3930.2 3932.0 5337.4 5648.9 5692.2 5692.9 5706.5 3934.8 3964.6 3966.2 3967.2 3978.6 4024.7 4032.2 4039.6 4050.9 4057.7 4512.5 4644.5 4669.6 4680.8 4684.3 5478.8 5485.9 5501.4 5520.3 5570.2 1367.9 1371.5 1380.1 1393.7 1394.9 837.8 881.2 882.3 898.8 899.9 5124.8 5260.4 5342.4 5393.8 5398.5 5620.1 5628.4 5661.8 5679.5 5683.1 5112.9 5323.9 5355.5 5360.9 5365.0 2949.7 2968.9 2975.6 2983.5 2988.1 5815.9 5829.4 5844.6 5854.4 5863.9 5440.5 5759.0 5762.5 5786.5 5805.4 5874.3 5900.2 5901.8 5913.6 5926.1 5464.4 5666.6 5669.9 5685.1 5709.9 5566.2 5575.7 5591.4 5593.4 5606.3 5848.4 5863.0 5869.1 5899.3 5900.2 3873.3 3894.9 3943.4 3955.3 3955.9 5640.2 5667.6 5668.2 5677.2 5677.3 5512.9 5515.8 5520.0 5542.7 5558.5 5347.3 5389.1 5452.9 5464.2 5478.8 5431.5 5450.9 5473.9 5474.8 5498.7 5340.9 5372.2 5492.8 5528.3 5529.8 5400.4 5409.6 5412.4 5415.8 5427.3 18:36:55 INFO - PROCESS | 2118 | 18:36:55 INFO - PROCESS | 2118 | [#5] yui.html Cycles:180 Average:21183.48 Median:4325.17 stddev:31824.75 (735.8%) stddev-sans-first:31877.01 18:36:55 INFO - PROCESS | 2118 | Values: 740.2 758.2 777.8 778.4 803.9 15886.4 17345.0 17356.9 17372.8 17453.1 15581.7 15733.2 15740.4 15751.3 15754.9 642.5 739.5 742.5 749.8 785.4 796.5 796.6 800.4 801.2 802.1 848.7 851.9 852.4 852.8 853.6 672.6 779.3 791.1 798.2 842.3 609.1 610.9 611.8 612.0 612.1 14596.7 14614.7 14617.3 14637.2 14640.7 13504.5 13661.6 13673.2 13756.9 13804.5 12225.1 12317.3 12520.0 12528.9 12912.7 4223.9 4239.5 4243.1 4453.4 4458.6 66930.3 68183.0 69329.7 80222.6 82059.6 11857.5 11911.6 11989.2 12113.8 12577.8 21186.8 21299.3 21317.3 21330.0 21333.6 8177.1 8178.5 8442.7 8490.2 8644.1 4318.4 4331.9 4579.9 4707.9 4864.7 3421.1 3546.9 3553.2 3553.6 3561.6 587.0 626.9 627.6 629.8 631.6 604.2 636.7 647.0 676.4 724.0 8553.6 8907.0 9784.7 10194.4 10302.7 674.9 718.4 734.2 739.7 761.5 4487.7 4629.5 4673.0 4688.1 4700.6 122048.0 123851.0 126126.4 128021.0 132700.0 108146.0 109181.0 109185.0 111634.0 112044.4 96045.5 96936.0 98240.5 100364.0 100684.0 8986.8 9007.0 9140.9 9205.0 9274.4 39789.4 43400.1 49942.5 53013.5 54118.5 48827.0 50534.0 60333.0 62619.0 75829.2 12378.2 12380.9 12729.3 12803.8 13214.9 13747.2 13825.9 14122.9 14957.4 15062.4 13792.9 14294.4 14668.5 14984.8 15009.5 13044.2 13242.9 13719.3 13840.5 13888.4 12139.9 12273.9 13122.6 13139.4 13448.5 10528.2 10606.0 11035.6 11065.0 11316.6 9765.2 9808.7 9858.3 10062.3 10646.8 18:36:55 INFO - PROCESS | 2118 | -------- Summary: end -------- 18:36:55 INFO - PROCESS | 2118 | 18:36:56 INFO - TEST-INFO | 2118: exit 0 18:36:56 INFO - TEST-OK | dromaeo_css | took 1116251ms 18:36:56 INFO - TEST-START | kraken 18:36:56 INFO - operating with platform_type : linux_ 18:36:56 INFO - Initialising browser for kraken test... 18:36:56 INFO - TEST-INFO | started process 2511 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpltyjIw/profile http://localhost:33794/getInfo.html) 18:37:00 INFO - PROCESS | 2511 | __metrics Screen width/height:1600/1200 18:37:00 INFO - PROCESS | 2511 | colorDepth:24 18:37:00 INFO - PROCESS | 2511 | Browser inner width/height: 1024/697 18:37:00 INFO - PROCESS | 2511 | __metrics 18:37:01 INFO - PROCESS | 2511 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:37:01 INFO - PROCESS | 2511 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:37:01 INFO - PROCESS | 2511 | console.error: PushService: 18:37:01 INFO - PROCESS | 2511 | stateChangeProcessEnqueue: Error transitioning state 18:37:01 INFO - PROCESS | 2511 | UnknownError 18:37:02 INFO - TEST-INFO | 2511: exit 0 18:37:02 INFO - Browser initialized. 18:37:02 INFO - Running cycle 1/1 for kraken test... 18:37:02 INFO - TEST-INFO | started process 2603 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpltyjIw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:37:14 INFO - PROCESS | 2603 | RSS: Main: 179789824 18:37:14 INFO - PROCESS | 2603 | 18:37:17 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33794/tests/kraken/driver.html?testName=audio-beat-detection) 18:37:17 INFO - PROCESS | 2603 | RSS: Main: 234606592 18:37:17 INFO - PROCESS | 2603 | 18:37:20 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33794/tests/kraken/driver.html?testName=audio-dft) 18:37:21 INFO - PROCESS | 2603 | RSS: Main: 224423936 18:37:21 INFO - PROCESS | 2603 | 18:37:24 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33794/tests/kraken/driver.html?testName=audio-fft) 18:37:24 INFO - PROCESS | 2603 | RSS: Main: 301596672 18:37:24 INFO - PROCESS | 2603 | 18:37:26 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33794/tests/kraken/driver.html?testName=audio-oscillator) 18:37:27 INFO - PROCESS | 2603 | RSS: Main: 211791872 18:37:27 INFO - PROCESS | 2603 | 18:37:29 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33794/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:37:30 INFO - PROCESS | 2603 | RSS: Main: 214818816 18:37:30 INFO - PROCESS | 2603 | 18:37:33 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33794/tests/kraken/driver.html?testName=imaging-darkroom) 18:37:33 INFO - PROCESS | 2603 | RSS: Main: 253288448 18:37:33 INFO - PROCESS | 2603 | 18:37:37 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33794/tests/kraken/driver.html?testName=imaging-desaturate) 18:37:37 INFO - PROCESS | 2603 | RSS: Main: 252096512 18:37:37 INFO - PROCESS | 2603 | 18:37:40 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33794/tests/kraken/driver.html?testName=json-parse-financial) 18:37:41 INFO - PROCESS | 2603 | RSS: Main: 251904000 18:37:41 INFO - PROCESS | 2603 | 18:37:42 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33794/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:37:43 INFO - PROCESS | 2603 | RSS: Main: 213991424 18:37:43 INFO - PROCESS | 2603 | 18:37:45 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:37:45 INFO - PROCESS | 2603 | RSS: Main: 222162944 18:37:45 INFO - PROCESS | 2603 | 18:37:47 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:37:48 INFO - PROCESS | 2603 | RSS: Main: 223621120 18:37:48 INFO - PROCESS | 2603 | 18:37:50 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:37:51 INFO - PROCESS | 2603 | RSS: Main: 222748672 18:37:51 INFO - PROCESS | 2603 | 18:37:54 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:37:54 INFO - PROCESS | 2603 | RSS: Main: 221933568 18:37:54 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | Cycle 1(1): loaded http://localhost:33794/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33794/tests/kraken/driver.html?testName=ai-astar) 18:37:56 INFO - PROCESS | 2603 | RSS: Main: 273321984 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | __start_tp_report 18:37:56 INFO - PROCESS | 2603 | _x_x_mozilla_page_load 18:37:56 INFO - PROCESS | 2603 | _x_x_mozilla_page_load_details 18:37:56 INFO - PROCESS | 2603 | |i|pagename|runs| 18:37:56 INFO - PROCESS | 2603 | |0;ai-astar;113;113;120;114;111;112;113;115;113;127 18:37:56 INFO - PROCESS | 2603 | |1;audio-beat-detection;153;122;145;179;129;152;145;124;185;122 18:37:56 INFO - PROCESS | 2603 | |2;audio-dft;161;161;160;160;160;161;161;163;161;162 18:37:56 INFO - PROCESS | 2603 | |3;audio-fft;76;75;74;170;76;77;75;76;165;78 18:37:56 INFO - PROCESS | 2603 | |4;audio-oscillator;82;82;149;81;82;81;141;82;83;143 18:37:56 INFO - PROCESS | 2603 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;117;117 18:37:56 INFO - PROCESS | 2603 | |6;imaging-darkroom;146;145;146;145;146;145;146;146;145;146 18:37:56 INFO - PROCESS | 2603 | |7;imaging-desaturate;96;96;96;96;96;96;96;95;96;95 18:37:56 INFO - PROCESS | 2603 | |8;json-parse-financial;59;51;52;53;51;52;54;52;53;52 18:37:56 INFO - PROCESS | 2603 | |9;json-stringify-tinderbox;63;63;63;63;64;62;63;63;63;63 18:37:56 INFO - PROCESS | 2603 | |10;stanford-crypto-aes;82;84;78;78;79;79;79;77;78;78 18:37:56 INFO - PROCESS | 2603 | |11;stanford-crypto-ccm;125;138;136;127;128;141;129;128;175;123 18:37:56 INFO - PROCESS | 2603 | |12;stanford-crypto-pbkdf2;164;162;164;166;167;167;164;167;165;167 18:37:56 INFO - PROCESS | 2603 | |13;stanford-crypto-sha256-iterative;65;65;67;67;65;67;65;65;65;64 18:37:56 INFO - PROCESS | 2603 | __end_tp_report 18:37:56 INFO - PROCESS | 2603 | __start_cc_report 18:37:56 INFO - PROCESS | 2603 | _x_x_mozilla_cycle_collect,1666 18:37:56 INFO - PROCESS | 2603 | __end_cc_report 18:37:56 INFO - PROCESS | 2603 | __startTimestamp1472780276877__endTimestamp 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | ------- Summary: start ------- 18:37:56 INFO - PROCESS | 2603 | Number of tests: 14 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#0] ai-astar Cycles:10 Average:115.10 Median:113.50 stddev:4.84 (4.3%) stddev-sans-first:5.07 18:37:56 INFO - PROCESS | 2603 | Values: 113.0 113.0 120.0 114.0 111.0 112.0 113.0 115.0 113.0 127.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:148.50 stddev:22.66 (15.3%) stddev-sans-first:23.87 18:37:56 INFO - PROCESS | 2603 | Values: 153.0 122.0 145.0 179.0 129.0 152.0 145.0 124.0 185.0 122.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 18:37:56 INFO - PROCESS | 2603 | Values: 161.0 161.0 160.0 160.0 160.0 161.0 161.0 163.0 161.0 162.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.00 stddev:38.67 (50.9%) stddev-sans-first:40.45 18:37:56 INFO - PROCESS | 2603 | Values: 76.0 75.0 74.0 170.0 76.0 77.0 75.0 76.0 165.0 78.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#4] audio-oscillator Cycles:10 Average:100.60 Median:82.00 stddev:30.25 (36.9%) stddev-sans-first:31.32 18:37:56 INFO - PROCESS | 2603 | Values: 82.0 82.0 149.0 81.0 82.0 81.0 141.0 82.0 83.0 143.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:37:56 INFO - PROCESS | 2603 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:37:56 INFO - PROCESS | 2603 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 145.0 146.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:37:56 INFO - PROCESS | 2603 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:52.50 stddev:2.33 (4.4%) stddev-sans-first:0.97 18:37:56 INFO - PROCESS | 2603 | Values: 59.0 51.0 52.0 53.0 51.0 52.0 54.0 52.0 53.0 52.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#9] json-stringify-tinderbox Cycles:10 Average:63.00 Median:63.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 18:37:56 INFO - PROCESS | 2603 | Values: 63.0 63.0 63.0 63.0 64.0 62.0 63.0 63.0 63.0 63.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:2.15 (2.7%) stddev-sans-first:2.03 18:37:56 INFO - PROCESS | 2603 | Values: 82.0 84.0 78.0 78.0 79.0 79.0 79.0 77.0 78.0 78.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:132.50 stddev:15.23 (11.5%) stddev-sans-first:15.72 18:37:56 INFO - PROCESS | 2603 | Values: 125.0 138.0 136.0 127.0 128.0 141.0 129.0 128.0 175.0 123.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:166.50 stddev:1.77 (1.1%) stddev-sans-first:1.81 18:37:56 INFO - PROCESS | 2603 | Values: 164.0 162.0 164.0 166.0 167.0 167.0 164.0 167.0 165.0 167.0 18:37:56 INFO - PROCESS | 2603 | 18:37:56 INFO - PROCESS | 2603 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.00 stddev:1.08 (1.7%) stddev-sans-first:1.13 18:37:56 INFO - PROCESS | 2603 | Values: 65.0 65.0 67.0 67.0 65.0 67.0 65.0 65.0 65.0 64.0 18:37:56 INFO - PROCESS | 2603 | -------- Summary: end -------- 18:37:56 INFO - PROCESS | 2603 | 18:37:58 INFO - TEST-INFO | 2603: exit 0 18:37:58 INFO - TEST-OK | kraken | took 61867ms 18:37:58 INFO - SUITE-END | took 1178s 18:37:58 INFO - Completed test suite (00:19:38) 18:37:58 INFO - javascript benchmark 18:37:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.7146471085243, 3008.6840881707426, 3030.303030303032, 3055.0376631361105, 3074.323648797264, 5050.368703912011, 5156.767945442455, 5187.1959846223845, 5244.344456942887, 5269.710165940872, 5205.427402985678, 5217.295665085212, 5307.1243244864645, 5308.0, 5416.593755468336, 4697.53024697531, 4743.312219728155, 4765.571098601134, 4766.118268120409, 4817.373741413611, 4752.714837109785, 4791.784369703354, 4807.567318941295, 4867.780949857247, 4903.166461701519, 4828.951710482903, 4839.975800121012, 4843.24929635907, 4877.8780530486665, 4889.779959901813, 4650.372199753024, 4675.719456832601, 4717.410323709536, 4718.787654555554, 4801.783919723621, 4668.486466488684, 4668.836590719308, 4679.040796636681, 4726.983698504803, 4765.928511072302, 5220.060389129955, 5226.503482169188, 5236.973815130938, 5263.526282634582, 5284.180951952453, 5414.160805075219, 5595.38450770415, 5607.551395888319, 5625.6905870177125, 5679.204911312419, 5651.745671444794, 5677.432256774289, 5693.03218452864, 5696.11710184922, 5749.137629355589, 5305.7612407441375, 5310.522053015247, 5316.069687804618, 5318.680879147302, 5321.467853214647, 5505.779768809202, 5649.971750141223, 5702.144678298288, 5702.45826646468, 5784.1902133701315, 5451.209574611738, 5452.04589196889, 5452.563794896399, 5458.2904222450825, 5517.172424136332, 5528.226048353154, 5530.889382212414, 5533.833984980457, 5536.086545719992, 5539.833804985857, 5331.173668081454, 5549.916751248735, 5566.944330556724, 5573.108302671553, 5621.747021384048, 5634.830955071355, 5637.379888212294, 5639.605227634026, 5642.435756424325, 5699.943000569941, 4352.847650332224, 4389.912201755947, 4390.714603550759, 4393.956060439418, 4442.067165895133, 3807.0862992881507, 3881.84472621092, 3941.8620348287673, 3955.090329224237, 3959.287328280937, 5402.459754024645, 5402.972985135049, 5403.7838486460105, 5408.45915408456, 5432.809851655173, 5624.746886390122, 5658.292713410824, 5672.688002159838, 5673.886522269531, 5674.489295963301, 5153.4073581538605, 5313.07021271276, 5368.785248590091, 5369.865753356197, 5459.863503412446, 4863.294822250754, 4863.683860549053, 4877.975610121926, 4896.020795840846, 4926.039422312615, 5782.161586569924, 5811.767529298865, 5815.8255252340805, 5819.0689489681445, 5824.446677565625, 5471.316085489314, 5563.916541251803, 5616.887662246814, 5618.971905140448, 5643.830685079454, 5833.125031245348, 5839.941600583939, 5890.587658863838, 5892.823215303376, 5902.557308201816, 5541.695206763667, 5548.4174161712435, 5549.223108764857, 5558.471945165203, 5559.444055594408, 5411.9717253721665, 5541.0, 5597.244372009726, 5603.963266795656, 5606.607537472338, 5478.287822583039, 5506.311711036121, 5508.669479831223, 5534.723263836872, 5571.777128914798, 5440.972795135999, 5477.698726569917, 5490.258815059916, 5493.587980901529, 5495.807646732345, 5247.737613119252, 5347.411784703757, 5425.891482170256, 5437.157240627709, 5509.5041446270025, 5495.505404513613, 5512.503874651301, 5523.696196709219, 5573.888522229614, 5578.944210557843, 5372.838814835562, 5384.461553844741, 5387.488188622075, 5388.299521062237, 5391.7034563099405], "value": 5183.308499176415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.6872641863022, 596.9970150149248, 606.7754930675651, 620.8975519039359, 621.5276389943641, 41808.37287440687, 44416.55583444165, 44417.55582444175, 44595.77702111489, 47604.0, 45620.771896140555, 45851.0, 46046.079078418414, 46109.76945115278, 46453.53546464534, 8284.088750237499, 9055.501947392891, 9231.95384023079, 9233.907660923405, 9249.537523123818, 10663.946680266627, 10974.067204287645, 10993.065589424908, 11369.317840929529, 11386.487608057658, 11135.276207046556, 11221.0, 11229.38238396892, 11234.3259404436, 11236.550537978472, 10847.566097356097, 10864.293820901696, 10889.564417423373, 10901.073408760225, 10904.509297081571, 10707.732306692316, 10771.407572583505, 10772.138228941665, 10777.191710621657, 10781.892181078245, 43205.78397108026, 43306.78346608279, 43415.13169736619, 43556.12887742259, 43639.0, 3531.964680353189, 3635.9272814543824, 3693.593704692482, 3704.9255715842373, 3935.822887970048, 32326.838365808257, 32784.50823237653, 33458.33083338344, 33852.322953541036, 34156.316873662385, 4007.9599204007877, 4035.556088830226, 4156.0, 4268.0, 4283.571642835723, 348866.0, 351352.0, 352092.0, 353271.0, 353493.0, 61923.69038154781, 62330.0, 62380.0, 62392.0, 62445.68777156085, 36438.27123457516, 37131.62868371336, 37432.81283593619, 39614.801925990185, 64485.35514644887, 17430.47708568745, 17602.91198543999, 17604.119794010247, 17615.64768704619, 17618.823811761973, 12050.337231452178, 12937.805932911015, 13173.40719667598, 13216.810487056211, 13294.734105317839, 19189.520261993282, 19233.422997310103, 19294.32469863548, 19386.321478748177, 19416.708749368772, 6943.89584156238, 7186.568805871664, 7337.853242935112, 7349.779506614703, 7445.181030086686, 910.5219759626117, 1064.3613831700918, 1091.497910960951, 1095.2260270228662, 1140.4297851074464, 32190.83904580462, 32454.0, 32522.674773252438, 32613.836930815192, 32920.341593168, 1053.1311667874033, 1091.110744743032, 1105.082781291526, 1155.4165146600913, 1193.075366590899, 16774.412895548598, 17074.57313567146, 17239.553148544157, 17257.827421725622, 17265.56836079108, 550489.0, 560194.0, 561145.0, 561624.0, 562119.0, 58155.709221453624, 59032.70483647554, 59051.409485904594, 59057.40942590605, 59093.04534773244, 1220.3227208899145, 1275.0309764578988, 1278.9104762666525, 1348.1978222957325, 1353.2759973414372, 26381.34046648849, 31210.0, 32810.67189328076, 35027.649723502436, 35064.12339691528, 56331.0, 56948.43051569597, 56991.430085698616, 57035.87426011818, 57088.0, 480151.0, 486582.0, 487505.0, 488062.0, 488481.0, 24068.638970415108, 24093.9520140783, 28573.428531429672, 29048.966572339734, 30389.544156838107, 47006.76496617495, 47884.5211547894, 48072.0, 48224.0, 48319.75840120777, 39391.0, 39934.0, 40126.79936600298, 40893.59106409017, 42303.36544951767, 40059.39910901397, 40073.59926400815, 40268.798656007704, 41227.38158927559, 42220.788896055325, 40246.1950760989, 40697.1860562793, 41072.97317567085, 41457.58542414537, 41710.79144604379, 33983.320333592696, 35933.64066359303, 36438.08904777402, 36770.0, 36900.446493302086, 27563.724362756115, 27930.44139117247, 27963.0, 28021.579676304464, 28178.154655360373], "value": 20716.084320603426, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.2143837222648, 380.47301514956337, 394.982225799839, 396.63311436920844, 417.42187070906783, 14180.22008789516, 17833.64332713345, 17876.748627596073, 19626.018699065044, 19633.607327853453, 17570.29718811249, 18941.90529047356, 19348.51628709283, 19493.0, 19499.317523886668, 5563.805266815642, 5648.152777083439, 5799.536037117021, 5808.070708686611, 5950.077737950624, 5596.972015139919, 5869.0, 5892.734826932799, 5900.350961394243, 5900.734466948998, 5883.088121341178, 5906.2912450506, 5907.232059832216, 5912.852178695524, 5915.408459154093, 5885.646861188321, 5889.293284805808, 5891.440313170242, 7065.929340706579, 7375.078115235596, 7163.67763450646, 7192.64036798158, 7294.81762955925, 7342.743003994888, 7366.337029667356, 23263.883680581657, 23463.7653623465, 23706.525869482688, 23983.40041498959, 24884.0, 2501.562226610336, 2938.2360586247514, 3238.352329534079, 3290.083640311951, 3295.1762059485127, 15511.922440387838, 15737.527874163794, 15844.44544440939, 16154.596135096717, 16670.416535421202, 2030.2691031228746, 2104.821090207319, 2120.363890832744, 2191.649336106231, 2216.1910902520463, 85167.57416212941, 93693.31638122264, 96819.0, 97541.51229243808, 100581.4970925148, 17568.736468952975, 17736.0, 17928.9103554484, 18645.627087458175, 18707.0, 25321.87339063293, 29487.705122948497, 31637.683623163935, 31878.68121318757, 31937.201569960936, 9868.901310986941, 9907.702768916944, 10052.899471005196, 10123.848142277871, 10400.47997600117, 8108.716194933267, 8187.754367368989, 8189.672413103409, 8428.662853485792, 8434.283085937765, 8719.8083209731, 8763.0, 8793.38446308765, 8798.12018798115, 9090.454572725524, 3314.751393645486, 3418.265073009315, 3479.634638362986, 3495.80134177225, 3501.352249833738, 897.5422534507449, 929.0198840223629, 983.1348413396308, 1014.9391036537831, 1026.2816028779885, 17081.66211295536, 20137.295194668117, 20831.791682083287, 20962.895185523976, 21163.576728465345, 954.6324665004023, 963.7638778499172, 964.3587014635189, 972.3436680240951, 1017.1862509991977, 10318.896811031944, 10823.621173258898, 10971.780564388668, 10992.615258465916, 11036.172287078502, 491230.0, 492401.0, 505166.0, 505974.0, 506252.0, 99184.00815991747, 99726.50136749414, 100156.0, 102606.0, 104777.0, 1017.1811691588342, 1033.5452400943711, 1071.3357718214602, 1077.36435503052, 1081.859358283434, 16916.407925723033, 16964.830351696324, 17482.125893705008, 18025.819741802938, 18313.633727325112, 39477.605223947394, 42144.78927605342, 43117.13765724605, 43964.34053489264, 44135.0, 51287.0, 60184.39815601788, 60231.69884150551, 62303.0, 63539.0, 6557.540972131905, 7456.589887556236, 8374.330053595819, 8547.487150770974, 8624.611892464729, 12326.938365308117, 13127.934360328138, 13161.53934612284, 13223.140495867738, 13445.327733613085, 11775.823362649739, 11857.407129643656, 11921.761564768829, 12015.158938874194, 12453.813192801934, 11744.119191060876, 11853.5851245206, 11919.284842909454, 11933.0, 12347.13570050088, 11773.52905883755, 11794.587189448328, 11837.993770529602, 12017.939910300393, 12022.699432514257, 7429.5542267464125, 7490.325870671666, 7687.11598166218, 7936.920630793618, 9035.864462032943, 10525.36847789135, 11365.48855301497, 11563.30620162793, 11684.18210725241, 12212.816807747713], "value": 10321.969486513972, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.9406026728795, 1477.6305923519121, 1482.207019244704, 1645.9917700411497, 1757.0511923561278, 8001.0, 8413.663453461862, 8436.072032076467, 8646.092160323155, 8672.47965122093, 8536.402451828375, 8633.30933525318, 8647.092055334193, 8650.307975361971, 8652.0, 2024.2105578824196, 2276.157821606008, 2287.1194590082855, 2314.2710046335433, 2318.3740390094663, 2274.1812947338944, 2293.174457195408, 2302.412884714404, 2305.2392710405525, 2305.8385912986096, 2307.9307620771406, 2311.1102225643162, 2320.071971211512, 2350.6356514740155, 2354.9764502355015, 2130.371540395587, 2196.934091977243, 2201.3175915466095, 2202.0861342542826, 2206.5255969966374, 2338.871362075085, 2340.8244381671443, 2350.294911526535, 2358.2217868103658, 2358.9174378896655, 8635.52504612246, 8638.827223455495, 8692.304615630797, 8762.956185219033, 8767.0, 4981.427135879384, 5110.744462776846, 5117.257997590365, 5118.2578526113875, 5118.616103792193, 2993.850307484639, 3155.25851424915, 3200.1679563313705, 3201.119692084672, 3218.951715724266, 945.9479728614923, 991.6430085169334, 994.1351024608637, 1006.6376104602338, 1008.2639673038723, 11443.942780286128, 11476.770464590745, 11536.769264614744, 11592.652220433316, 11706.53173873044, 4140.68944829133, 4168.562300958416, 4294.312909934395, 4306.138772245564, 4389.692721509464, 9421.858672119924, 9426.528673566294, 9432.151106400319, 9441.858372124423, 9447.905520944703, 4982.10322142018, 5008.84973450797, 5012.298278241049, 5013.874653133701, 5039.244113383022, 4122.484689413824, 4623.16782979067, 4681.438227412732, 4719.126961512131, 4729.858104256878, 3642.6357364263363, 3695.279420512974, 3825.7704537727827, 3840.1551658635058, 3922.058705910561, 1099.7745462180394, 1104.5029736618553, 1128.5203788389902, 1129.1418521923233, 1136.971575710614, 835.5863847395457, 894.4186278918755, 935.6584846531103, 948.8813898262717, 965.8261512927741, 5921.3190483094995, 6026.885026270154, 6040.063790112452, 6054.39456054391, 6446.194225721785, 928.4429342394644, 969.6024629901707, 1000.1198944928417, 1010.6816352848829, 1074.8280275155937, 3951.9012024699036, 3960.2673505401594, 3992.760434373889, 4080.204360149742, 4147.428594271571, 9740.659076932274, 10286.691399258034, 10368.896311036793, 10378.68863934068, 10391.740206494747, 9977.750556236153, 10240.48797560119, 10242.385456872462, 10245.077942985166, 10255.435951022617, 7941.6029198539845, 8044.839103217902, 8079.636416361278, 8082.676692932217, 8101.027876654839, 5644.266245388073, 5855.94144058571, 5872.882542349214, 5913.970430147821, 6095.969520152371, 9743.610255589698, 9761.463119528495, 9815.754606134618, 9870.161036311994, 9870.753231169278, 9464.432134071978, 10110.494475276353, 10128.544215510467, 10129.139023182817, 10148.340357876714, 6555.147830781968, 6701.798946031627, 6701.865962680621, 7014.0531028310525, 7125.714971401293, 7877.054753429625, 8075.515469071875, 8182.7545173644885, 8294.294984926108, 8571.485710857369, 7534.321911028033, 7654.693812247448, 7662.310392064741, 7726.1501234863, 7929.444938854225, 7361.852762944818, 7466.365358944333, 7507.512011719221, 7642.312191902755, 7771.145174030739, 6922.653867306513, 7033.261507541634, 7035.788926332219, 7043.577385356895, 7045.682944267418, 4976.975115124401, 5163.690178589297, 5192.844214673566, 5195.53240208368, 5363.356397232357, 5114.0, 5138.3577052868395, 5174.430812610534, 5247.1866860636665, 5311.3891902431715], "value": 4483.398285436056, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.9594452721146, 320.1292484442316, 323.91092449576365, 324.3318763347506, 324.7888872233048, 5226.8693282667955, 5300.549453296469, 5305.124654432024, 5310.15037593985, 5329.147336426173, 5201.687898726079, 5221.843344699656, 5246.422893481714, 5253.50091741284, 5261.605674496262, 3891.241207964441, 3895.8636447724334, 4027.2750904837117, 4030.7581545107387, 4060.309747342959, 4034.475518182632, 4040.313146765057, 4046.514418269812, 4054.3715724062818, 4065.125997910449, 3987.48162738845, 3999.860004899829, 4002.6197511236387, 4005.83976640934, 4007.098402859348, 3865.1303456722294, 3918.3338832398417, 3972.8609498667697, 3978.781167035812, 3983.4821473208585, 3514.9297014059575, 3516.6307537708685, 3522.2779330237495, 3522.4011917974008, 3540.6368548109162, 5038.193888977783, 5044.823431179928, 5078.060558796597, 5078.162103252959, 5134.332536770234, 4450.844220452301, 4608.331791890158, 4617.561331673485, 4640.257558790578, 4654.953450465486, 5377.0321342158395, 5378.811741589026, 5379.892402151935, 5442.564594832404, 5461.371942226615, 3903.8243279052513, 3913.5108111486065, 3925.391564307537, 3930.233604447134, 3931.9803400983096, 5337.412884582693, 5648.8870222595315, 5692.231548740909, 5692.943070569283, 5706.4864162225185, 3934.8229329680235, 3964.5837187095517, 3966.2265858157957, 3967.1867267210155, 3978.5822488638278, 4024.69814763888, 4032.153247817991, 4039.555648878621, 4050.9392359114636, 4057.655099316532, 4512.458504979423, 4644.489106198315, 4669.579737823612, 4680.789364478606, 4684.297355396718, 5478.835634930959, 5485.862853428696, 5501.422350653123, 5520.309961254843, 5570.192322113352, 1367.863213678624, 1371.4856928651755, 1380.1443105274793, 1393.6794537256487, 1394.9442022319197, 837.7696133563319, 881.1584936385736, 882.3294296334703, 898.820235952812, 899.8605216191501, 5124.846254612367, 5260.368755749258, 5342.412334643186, 5393.83818485446, 5398.541124004425, 5620.100783874561, 5628.437156284421, 5661.801836935688, 5679.466544033171, 5683.147527870852, 5112.897742045213, 5323.920141197885, 5355.464453554691, 5360.91958620621, 5364.9731751341, 2949.705029497033, 2968.85155742212, 2975.5834183214365, 2983.523156037775, 2988.133441301998, 5815.86590614826, 5829.358770535237, 5844.620099693507, 5854.385289544622, 5863.941360586339, 5440.510354068153, 5758.971205143947, 5762.4525670061275, 5786.450287222707, 5805.419458054245, 5874.295084589877, 5900.232969713996, 5901.76392944286, 5913.5564832636865, 5926.111083337534, 5464.426582944977, 5666.575006874584, 5669.886602268014, 5685.118806584986, 5709.914351284651, 5566.165075238585, 5575.693336866511, 5591.412901645269, 5593.384727679871, 5606.271184745957, 5848.356680765198, 5863.0, 5869.148973398918, 5899.292084949833, 5900.232969713909, 3873.322168620424, 3894.9221015578964, 3943.4282029106193, 3955.268275369066, 3955.92088158241, 5640.210370548045, 5667.574931880043, 5668.234788303526, 5677.1768093627015, 5677.290338707661, 5512.889742205053, 5515.7865269640615, 5520.0, 5542.667439953616, 5558.527525160407, 5347.251384806208, 5389.110796718497, 5452.863678408072, 5464.18037294409, 5478.835634930959, 5431.511163995101, 5450.863728406822, 5473.945260547343, 5474.835754927359, 5498.697571633598, 5340.919886201632, 5372.200312895067, 5492.846502234415, 5528.336599608073, 5529.834104976696, 5400.4059553448305, 5409.6213265071065, 5412.350517937873, 5415.810446634349, 5427.294451721251], "value": 4166.577424033474, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.2264633458036, 758.154657556824, 777.7511196417149, 778.3500776851329, 803.8874557561941, 15886.364545418184, 17345.0, 17356.91321543392, 17372.826271737278, 17453.127343632816, 15581.688366232696, 15733.213339333044, 15740.449084282052, 15751.291191896364, 15754.921225393886, 642.4828013449171, 739.4934469888144, 742.4542960923717, 749.7713197474768, 785.3795501553766, 796.4663675337538, 796.5539297993114, 800.4276941986478, 801.234820746186, 802.0535767794, 848.6944699908029, 851.9233269005788, 852.3692467574012, 852.799592095857, 853.6286715278874, 672.5964421347202, 779.3375630713905, 791.0941971442666, 798.2456578533288, 842.3093063687778, 609.1380696314709, 610.914471973924, 611.843979785152, 612.0116012639555, 612.1063247658379, 14596.708065838624, 14614.707705845824, 14617.342219600143, 14637.194954277511, 14640.707185856223, 13504.527341543097, 13661.590152295446, 13673.24797136157, 13756.862431375659, 13804.481238726095, 12225.144239903211, 12317.34843384941, 12520.0, 12528.874711252862, 12912.677183070404, 4223.894402639928, 4239.512456067561, 4243.108947121108, 4453.421055262795, 4458.576435238647, 66930.3306966929, 68182.9772553417, 69329.65335173292, 80222.5988870052, 82059.5897020517, 11857.466414011391, 11911.583094591648, 11989.160758746804, 12113.818292725617, 12577.811332830015, 21186.788132118483, 21299.254526091514, 21317.29461643065, 21330.0, 21333.573328533654, 8177.1414001530175, 8178.46839955401, 8442.746717598358, 8490.23587877094, 8644.092370301154, 4318.395424640553, 4331.935020974688, 4579.862604121881, 4707.8587642370785, 4864.6594738368685, 3421.0934102462866, 3546.911327216789, 3553.236054248349, 3553.591336996227, 3561.6260292669413, 587.0460501684762, 626.8558231606756, 627.6046090962665, 629.7795771480037, 631.6178711879339, 604.1934018085817, 636.665750480992, 646.9906945165557, 676.4453148418348, 723.9638018099074, 8553.615087321086, 8907.0, 9784.657536986171, 10194.43930583825, 10302.742431439125, 674.865026994603, 718.4360277182386, 734.229059487536, 739.737393225408, 761.474582538047, 4487.708298960557, 4629.490756016836, 4672.976635116803, 4688.109259240733, 4700.647451441156, 122048.0, 123851.0, 126126.36936815258, 128021.0, 132700.0, 108146.0, 109181.0, 109185.0, 111634.0, 112044.4397778006, 96045.51977240069, 96936.0, 98240.50879745555, 100363.99636003692, 100684.0, 8986.82026359456, 9006.954965225132, 9140.862887056566, 9204.95397523008, 9274.397164184307, 39789.40315895206, 43400.131997360506, 49942.500574993785, 53013.469865302395, 54118.458815411344, 48827.0, 50534.0, 60333.0, 62619.0, 75829.24170758222, 12378.195417297848, 12380.938095309466, 12729.29988850622, 12803.807942880678, 13214.86785132175, 13747.243901585109, 13825.861741382458, 14122.858771412155, 14957.401703932157, 15062.397504099714, 13792.862071379157, 14294.356753945889, 14668.486602968846, 14984.775228371365, 15009.549713508612, 13044.152130111512, 13242.867571324548, 13719.314034298444, 13840.515581954583, 13888.444462221398, 12139.939300303442, 12273.938630306791, 13122.60632181036, 13139.408726607517, 13448.46206151743, 10528.157747380037, 10606.000554872766, 11035.613753518588, 11065.0, 11316.60391886313, 9765.193439213768, 9808.65669701557, 9858.30991830565, 10062.295639305179, 10646.8402973957], "value": 7503.210284581107, "unit": "score"}], "name": "dromaeo_css", "value": 7332.075572146913, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 120.0, 114.0, 111.0, 112.0, 113.0, 115.0, 113.0, 127.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 145.0, 179.0, 129.0, 152.0, 145.0, 124.0, 185.0, 122.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 160.0, 161.0, 161.0, 163.0, 161.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 170.0, 76.0, 77.0, 75.0, 76.0, 165.0, 78.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 149.0, 81.0, 82.0, 81.0, 141.0, 82.0, 83.0, 143.0], "value": 100.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, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 51.0, 52.0, 53.0, 51.0, 52.0, 54.0, 52.0, 53.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 62.0, 63.0, 63.0, 63.0, 63.0], "value": 63.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 78.0, 78.0, 79.0, 79.0, 79.0, 77.0, 78.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 138.0, 136.0, 127.0, 128.0, 141.0, 129.0, 128.0, 175.0, 123.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 166.0, 167.0, 167.0, 164.0, 167.0, 165.0, 167.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 67.0, 65.0, 67.0, 65.0, 65.0, 65.0, 64.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1535.7, "alertThreshold": 2.0}]} 18:37:58 INFO - Return code: 0 18:37:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:37:58 INFO - # TBPL SUCCESS # 18:37:58 INFO - Running post-action listener: _resource_record_post_action 18:37:58 INFO - [mozharness: 2016-09-02 01:37:58.662780Z] Finished run-tests step (success) 18:37:58 INFO - Running post-run listener: _resource_record_post_run 18:37:58 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 408330240; Read time: 8; Write time: 394052 18:37:58 INFO - TinderboxPrint: CPU usage
13.1% 18:37:58 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:37:58 INFO - TinderboxPrint: I/O write bytes / time
408,330,240 / 394,052 18:37:58 INFO - TinderboxPrint: CPU idle
8,296.3 (86.9%) 18:37:58 INFO - TinderboxPrint: CPU user
1,196.0 (12.5%) 18:37:58 INFO - TinderboxPrint: Swap in / out
0 / 0 18:37:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 119943168; Read time: 0; Write time: 147580 18:37:58 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 288387072; Read time: 0; Write time: 246472 18:37:59 INFO - Running post-run listener: _upload_blobber_files 18:37:59 INFO - Blob upload gear active. 18:37:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:37:59 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 18:37:59 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:37:59 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:37:59 INFO - (blobuploader) - INFO - Open directory for files ... 18:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:03 INFO - (blobuploader) - INFO - Done attempting. 18:38:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:38:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:38:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:04 INFO - (blobuploader) - INFO - Done attempting. 18:38:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:38:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:38:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:04 INFO - (blobuploader) - INFO - Done attempting. 18:38:04 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:04 INFO - Return code: 0 18:38:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27309fd6ee4178e7434c582ab1830aaecaed302e455c27765ebfee5b8ca9080b91f84213989b9dfdd38838b1271de579d67ca8a53e3a8bfb128dbdf443141ff9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e640be8741f0112b8f5207f8a8cab5761b9c8af153aee64b001f0ff1077cf35e8e9f43c2163cea5b9d5304edb143b56618a7dc86d62daed2f4767991f0951523", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c8f824eb59c0cd906b3d5a3b7b3098a217d14f7f028a4b692cd6e47feff5b66997fd07a41df5cd4736d42929d7aca8a70feb806d19c076f7bd5f344de915e34"} 18:38:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:04 INFO - Contents: 18:38:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27309fd6ee4178e7434c582ab1830aaecaed302e455c27765ebfee5b8ca9080b91f84213989b9dfdd38838b1271de579d67ca8a53e3a8bfb128dbdf443141ff9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e640be8741f0112b8f5207f8a8cab5761b9c8af153aee64b001f0ff1077cf35e8e9f43c2163cea5b9d5304edb143b56618a7dc86d62daed2f4767991f0951523", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c8f824eb59c0cd906b3d5a3b7b3098a217d14f7f028a4b692cd6e47feff5b66997fd07a41df5cd4736d42929d7aca8a70feb806d19c076f7bd5f344de915e34"} 18:38:04 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:04 INFO - Copying logs to upload dir... 18:38:04 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.093994 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-01 18:38:04.478982) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 18:38:04.484541) ========= 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=af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27309fd6ee4178e7434c582ab1830aaecaed302e455c27765ebfee5b8ca9080b91f84213989b9dfdd38838b1271de579d67ca8a53e3a8bfb128dbdf443141ff9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e640be8741f0112b8f5207f8a8cab5761b9c8af153aee64b001f0ff1077cf35e8e9f43c2163cea5b9d5304edb143b56618a7dc86d62daed2f4767991f0951523", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c8f824eb59c0cd906b3d5a3b7b3098a217d14f7f028a4b692cd6e47feff5b66997fd07a41df5cd4736d42929d7aca8a70feb806d19c076f7bd5f344de915e34"} build_url:https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/fkfSQDsCRAOc95ceHQzskA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/27309fd6ee4178e7434c582ab1830aaecaed302e455c27765ebfee5b8ca9080b91f84213989b9dfdd38838b1271de579d67ca8a53e3a8bfb128dbdf443141ff9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/e640be8741f0112b8f5207f8a8cab5761b9c8af153aee64b001f0ff1077cf35e8e9f43c2163cea5b9d5304edb143b56618a7dc86d62daed2f4767991f0951523", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/4c8f824eb59c0cd906b3d5a3b7b3098a217d14f7f028a4b692cd6e47feff5b66997fd07a41df5cd4736d42929d7aca8a70feb806d19c076f7bd5f344de915e34"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 18:38:04.509656) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:38:04.510003) ========= 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=af724c981e2a0f424dbabf99000001e7-1472775269.377427-1304752046 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004381 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 18:38:04.593130) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 18:38:04.593480) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 18:38:04.593783) ========= ========= Total master_lag: 0.16 =========