builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-050 starttime: 1469223197.98 results: success (0) buildid: 20160722133325 builduid: acfd027653484fbeb55e71bcd1983565 revision: 64e2353eb55496dd19f3fe41bf2ae856a0fc3546 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:17.984707) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:17.985499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:17.985868) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:18.000897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:18.001285) ========= 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=6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 14:33:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 68.9M=0s 2016-07-22 14:33:18 (68.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166939 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:18.182665) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:18.183056) ========= 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=6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014064 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:18.236484) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:18.237043) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 64e2353eb55496dd19f3fe41bf2ae856a0fc3546 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 64e2353eb55496dd19f3fe41bf2ae856a0fc3546 --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=6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 14:33:18,431 truncating revision to first 12 chars 2016-07-22 14:33:18,432 Setting DEBUG logging. 2016-07-22 14:33:18,432 attempt 1/10 2016-07-22 14:33:18,432 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/64e2353eb554?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 14:33:18,766 unpacking tar archive at: fx-team-64e2353eb554/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686838 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:19.039338) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:19.039711) ========= script_repo_revision: 64e2353eb55496dd19f3fe41bf2ae856a0fc3546 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:19.040163) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:19.040463) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 14:33:19.055765) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-22 14:33:19.056212) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:33:19 INFO - MultiFileLogger online at 20160722 14:33:19 in /builds/slave/test 14:33:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 14:33:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:33:19 INFO - {'append_to_log': False, 14:33:19 INFO - 'base_work_dir': '/builds/slave/test', 14:33:19 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 14:33:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:33:19 INFO - 'branch': 'Fx-Team-Non-PGO', 14:33:19 INFO - 'buildbot_json_path': 'buildprops.json', 14:33:19 INFO - 'config_files': ('talos/linux_config.py',), 14:33:19 INFO - 'default_actions': ('clobber', 14:33:19 INFO - 'read-buildbot-config', 14:33:19 INFO - 'download-and-extract', 14:33:19 INFO - 'populate-webroot', 14:33:19 INFO - 'create-virtualenv', 14:33:19 INFO - 'install', 14:33:19 INFO - 'run-tests'), 14:33:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:33:19 INFO - 'download_minidump_stackwalk': True, 14:33:19 INFO - 'download_symbols': 'ondemand', 14:33:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:33:19 INFO - 'tooltool.py': '/tools/tooltool.py', 14:33:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:33:19 INFO - '/tools/misc-python/virtualenv.py')}, 14:33:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:33:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:33:19 INFO - 'installer_path': 'installer.exe', 14:33:19 INFO - 'log_level': 'info', 14:33:19 INFO - 'log_name': 'talos', 14:33:19 INFO - 'log_to_console': True, 14:33:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:33:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:33:19 INFO - 'opt_config_files': (), 14:33:19 INFO - 'pip_index': False, 14:33:19 INFO - 'sps_profile': False, 14:33:19 INFO - 'sps_profile_interval': 0, 14:33:19 INFO - 'suite': 'dromaeojs', 14:33:19 INFO - 'system_bits': '32', 14:33:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:33:19 INFO - 'title': 'talos-linux64-ix-050', 14:33:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:33:19 INFO - 'use_talos_json': True, 14:33:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:33:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:33:19 INFO - 'work_dir': 'build'} 14:33:19 INFO - [mozharness: 2016-07-22 21:33:19.346160Z] Running clobber step. 14:33:19 INFO - Running pre-action listener: _resource_record_pre_action 14:33:19 INFO - Running main action method: clobber 14:33:19 INFO - rmtree: /builds/slave/test/build 14:33:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:33:20 INFO - Running post-action listener: _resource_record_post_action 14:33:20 INFO - [mozharness: 2016-07-22 21:33:20.339943Z] Finished clobber step (success) 14:33:20 INFO - [mozharness: 2016-07-22 21:33:20.340049Z] Running read-buildbot-config step. 14:33:20 INFO - Running pre-action listener: _resource_record_pre_action 14:33:20 INFO - Running main action method: read_buildbot_config 14:33:20 INFO - Using buildbot properties: 14:33:20 INFO - { 14:33:20 INFO - "project": "", 14:33:20 INFO - "product": "firefox", 14:33:20 INFO - "script_repo_revision": "production", 14:33:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:33:20 INFO - "repository": "", 14:33:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 14:33:20 INFO - "buildid": "20160722133325", 14:33:20 INFO - "pgo_build": "False", 14:33:20 INFO - "basedir": "/builds/slave/test", 14:33:20 INFO - "buildnumber": 1820, 14:33:20 INFO - "slavename": "talos-linux64-ix-050", 14:33:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:33:20 INFO - "platform": "ubuntu64_hw", 14:33:20 INFO - "scheduler": "tests-fx-team-linux64-talos", 14:33:20 INFO - "branch": "fx-team", 14:33:20 INFO - "repo_path": "integration/fx-team", 14:33:20 INFO - "revision": "64e2353eb55496dd19f3fe41bf2ae856a0fc3546", 14:33:20 INFO - "stage_platform": "linux64", 14:33:20 INFO - "builduid": "acfd027653484fbeb55e71bcd1983565", 14:33:20 INFO - "slavebuilddir": "test" 14:33:20 INFO - } 14:33:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 14:33:20 INFO - Running post-action listener: _resource_record_post_action 14:33:20 INFO - [mozharness: 2016-07-22 21:33:20.342499Z] Finished read-buildbot-config step (success) 14:33:20 INFO - [mozharness: 2016-07-22 21:33:20.342589Z] Running download-and-extract step. 14:33:20 INFO - Running pre-action listener: _resource_record_pre_action 14:33:20 INFO - Running main action method: download_and_extract 14:33:20 INFO - mkdir: /builds/slave/test/build/tests 14:33:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:20 INFO - https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:33:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:33:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:33:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:33:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:33:21 INFO - Downloaded 1361 bytes. 14:33:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:33:21 INFO - Using the following test package requirements: 14:33:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:33:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:33:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:33:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'jsshell-linux-x86_64.zip'], 14:33:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:33:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:33:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:33:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:33:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:33:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:33:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:33:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:33:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:21 INFO - https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:33:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:33:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:33:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:33:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:33:22 INFO - Downloaded 22737593 bytes. 14:33:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:33:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:33:23 INFO - Return code: 0 14:33:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:23 INFO - https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:33:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:33:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:33:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:33:24 INFO - Downloaded 22737593 bytes. 14:33:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:33:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:33:25 INFO - Return code: 0 14:33:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:25 INFO - https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:33:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:33:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:33:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:33:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:33:26 INFO - Downloaded 14403289 bytes. 14:33:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:33:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:33:27 INFO - Return code: 0 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:27 INFO - https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:33:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:33:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:33:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:33:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:33:28 INFO - Downloaded 57157161 bytes. 14:33:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:33:28 INFO - mkdir: /builds/slave/test/properties 14:33:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:28 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:28 INFO - Contents: 14:33:28 INFO - build_url:https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:33:29 INFO - Running post-action listener: _resource_record_post_action 14:33:29 INFO - Running post-action listener: set_extra_try_arguments 14:33:29 INFO - [mozharness: 2016-07-22 21:33:29.147381Z] Finished download-and-extract step (success) 14:33:29 INFO - [mozharness: 2016-07-22 21:33:29.147613Z] Running populate-webroot step. 14:33:29 INFO - Running pre-action listener: _resource_record_pre_action 14:33:29 INFO - Running main action method: populate_webroot 14:33:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:29 INFO - '--tppagecycles', 14:33:29 INFO - '7'], 14:33:29 INFO - 'tests': ['tsvgm']}}, 14:33:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['damp', 'tps']}, 14:33:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'tests': ['damp', 'tps']}, 14:33:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['dromaeo_dom']}, 14:33:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['basic_compositor_video']}, 14:33:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:33:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['a11yr', 14:33:29 INFO - 'ts_paint', 14:33:29 INFO - 'tpaint', 14:33:29 INFO - 'sessionrestore', 14:33:29 INFO - 'sessionrestore_no_auto_restore', 14:33:29 INFO - 'tabpaint']}, 14:33:29 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:29 INFO - 'ts_paint', 14:33:29 INFO - 'tpaint', 14:33:29 INFO - 'sessionrestore', 14:33:29 INFO - 'sessionrestore_no_auto_restore', 14:33:29 INFO - 'tabpaint']}, 14:33:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:29 INFO - 'ts_paint', 14:33:29 INFO - 'tpaint', 14:33:29 INFO - 'sessionrestore', 14:33:29 INFO - 'sessionrestore_no_auto_restore', 14:33:29 INFO - 'tabpaint']}, 14:33:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:29 INFO - 'ts_paint', 14:33:29 INFO - 'tpaint', 14:33:29 INFO - 'sessionrestore', 14:33:29 INFO - 'sessionrestore_no_auto_restore', 14:33:29 INFO - 'tabpaint']}, 14:33:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['a11yr', 14:33:29 INFO - 'ts_paint', 14:33:29 INFO - 'tpaint', 14:33:29 INFO - 'sessionrestore', 14:33:29 INFO - 'sessionrestore_no_auto_restore', 14:33:29 INFO - 'tabpaint']}, 14:33:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['a11yr', 14:33:29 INFO - 'ts_paint', 14:33:29 INFO - 'tpaint', 14:33:29 INFO - 'sessionrestore', 14:33:29 INFO - 'sessionrestore_no_auto_restore', 14:33:29 INFO - 'tabpaint']}, 14:33:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['tsvgx', 14:33:29 INFO - 'tsvgr_opacity', 14:33:29 INFO - 'tart', 14:33:29 INFO - 'tscrollx', 14:33:29 INFO - 'cart']}, 14:33:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:29 INFO - 'tsvgr_opacity', 14:33:29 INFO - 'tart', 14:33:29 INFO - 'tscrollx', 14:33:29 INFO - 'cart']}, 14:33:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'talos_options': ['--disable-e10s'], 14:33:29 INFO - 'tests': ['tp5o']}, 14:33:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'tests': ['tp5o']}, 14:33:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'talos_options': ['--disable-e10s', 14:33:29 INFO - '--xperf_path', 14:33:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:29 INFO - 'tests': ['tp5n']}, 14:33:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:33:29 INFO - 'talos_options': ['--xperf_path', 14:33:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:29 INFO - 'tests': ['tp5n']}}, 14:33:29 INFO - 'talos.zip': {'path': '', 14:33:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:29 INFO - Running post-action listener: _resource_record_post_action 14:33:29 INFO - [mozharness: 2016-07-22 21:33:29.168802Z] Finished populate-webroot step (success) 14:33:29 INFO - [mozharness: 2016-07-22 21:33:29.168892Z] Running create-virtualenv step. 14:33:29 INFO - Running pre-action listener: _resource_record_pre_action 14:33:29 INFO - Running main action method: create_virtualenv 14:33:29 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:29 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 14:33:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:29 INFO - Using real prefix '/usr' 14:33:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:29 INFO - Installing distribute.............................................................................................................................................................................................done. 14:33:31 INFO - Installing pip.................done. 14:33:31 INFO - Return code: 0 14:33:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:33:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:31 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:31 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 14:33:31 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 14:33:31 INFO - Using env: {'DISPLAY': ':0', 14:33:31 INFO - 'HOME': '/home/cltbld', 14:33:31 INFO - 'LANG': 'en_US.UTF-8', 14:33:31 INFO - 'LANGUAGE': 'en_US:en', 14:33:31 INFO - 'LOGNAME': 'cltbld', 14:33:31 INFO - 'MAIL': '/var/mail/cltbld', 14:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:31 INFO - 'MOZ_NO_REMOTE': '1', 14:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:31 INFO - 'NO_EM_RESTART': '1', 14:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:31 INFO - 'PWD': '/builds/slave/test', 14:33:31 INFO - 'SHELL': '/bin/bash', 14:33:31 INFO - 'SHLVL': '1', 14:33:31 INFO - 'TERM': 'linux', 14:33:31 INFO - 'TMOUT': '86400', 14:33:31 INFO - 'USER': 'cltbld', 14:33:31 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:31 INFO - '_': '/tools/buildbot/bin/python'} 14:33:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:31 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 14:33:31 INFO - Cleaning up... 14:33:31 INFO - Return code: 0 14:33:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:33:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:31 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:31 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 14:33:31 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 14:33:31 INFO - Using env: {'DISPLAY': ':0', 14:33:31 INFO - 'HOME': '/home/cltbld', 14:33:31 INFO - 'LANG': 'en_US.UTF-8', 14:33:31 INFO - 'LANGUAGE': 'en_US:en', 14:33:31 INFO - 'LOGNAME': 'cltbld', 14:33:31 INFO - 'MAIL': '/var/mail/cltbld', 14:33:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:31 INFO - 'MOZ_NO_REMOTE': '1', 14:33:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:31 INFO - 'NO_EM_RESTART': '1', 14:33:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:31 INFO - 'PWD': '/builds/slave/test', 14:33:31 INFO - 'SHELL': '/bin/bash', 14:33:31 INFO - 'SHLVL': '1', 14:33:31 INFO - 'TERM': 'linux', 14:33:31 INFO - 'TMOUT': '86400', 14:33:31 INFO - 'USER': 'cltbld', 14:33:31 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:31 INFO - '_': '/tools/buildbot/bin/python'} 14:33:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:32 INFO - Downloading/unpacking psutil>=3.1.1 14:33:32 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 14:33:32 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 14:33:32 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 14:33:32 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 14:33:32 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 14:33:32 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 14:33:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:33:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:34 INFO - Installing collected packages: psutil 14:33:34 INFO - Running setup.py install for psutil 14:33:34 INFO - building 'psutil._psutil_linux' extension 14:33:34 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 14:33:34 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 14:33:34 INFO - building 'psutil._psutil_posix' extension 14:33:34 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 14:33:34 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 14:33:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:34 INFO - Successfully installed psutil 14:33:34 INFO - Cleaning up... 14:33:34 INFO - Return code: 0 14:33:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:33:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:34 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:34 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 14:33:34 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 14:33:34 INFO - Using env: {'DISPLAY': ':0', 14:33:34 INFO - 'HOME': '/home/cltbld', 14:33:34 INFO - 'LANG': 'en_US.UTF-8', 14:33:34 INFO - 'LANGUAGE': 'en_US:en', 14:33:34 INFO - 'LOGNAME': 'cltbld', 14:33:34 INFO - 'MAIL': '/var/mail/cltbld', 14:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:34 INFO - 'MOZ_NO_REMOTE': '1', 14:33:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:34 INFO - 'NO_EM_RESTART': '1', 14:33:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:34 INFO - 'PWD': '/builds/slave/test', 14:33:34 INFO - 'SHELL': '/bin/bash', 14:33:34 INFO - 'SHLVL': '1', 14:33:34 INFO - 'TERM': 'linux', 14:33:34 INFO - 'TMOUT': '86400', 14:33:34 INFO - 'USER': 'cltbld', 14:33:34 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:34 INFO - '_': '/tools/buildbot/bin/python'} 14:33:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:33:34 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 14:33:34 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 14:33:34 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 14:33:34 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 14:33:34 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 14:33:34 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 14:33:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:33:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:33:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:33:36 INFO - Installing collected packages: mozsystemmonitor 14:33:36 INFO - Running setup.py install for mozsystemmonitor 14:33:36 INFO - Successfully installed mozsystemmonitor 14:33:36 INFO - Cleaning up... 14:33:37 INFO - Return code: 0 14:33:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:37 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:37 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 14:33:37 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 14:33:37 INFO - Using env: {'DISPLAY': ':0', 14:33:37 INFO - 'HOME': '/home/cltbld', 14:33:37 INFO - 'LANG': 'en_US.UTF-8', 14:33:37 INFO - 'LANGUAGE': 'en_US:en', 14:33:37 INFO - 'LOGNAME': 'cltbld', 14:33:37 INFO - 'MAIL': '/var/mail/cltbld', 14:33:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:37 INFO - 'MOZ_NO_REMOTE': '1', 14:33:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:37 INFO - 'NO_EM_RESTART': '1', 14:33:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:37 INFO - 'PWD': '/builds/slave/test', 14:33:37 INFO - 'SHELL': '/bin/bash', 14:33:37 INFO - 'SHLVL': '1', 14:33:37 INFO - 'TERM': 'linux', 14:33:37 INFO - 'TMOUT': '86400', 14:33:37 INFO - 'USER': 'cltbld', 14:33:37 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:37 INFO - '_': '/tools/buildbot/bin/python'} 14:33:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:37 INFO - Downloading/unpacking jsonschema==2.5.1 14:33:37 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 14:33:37 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 14:33:37 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 14:33:37 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 14:33:37 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 14:33:37 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 14:33:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:33:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 INFO - Downloading functools32-3.2.3-2.tar.gz 14:33:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:33:39 INFO - warning: no files found matching '*.txt' 14:33:39 INFO - no previously-included directories found matching 'build' 14:33:39 INFO - no previously-included directories found matching 'dist' 14:33:39 INFO - no previously-included directories found matching '.git*' 14:33:39 INFO - Installing collected packages: jsonschema, functools32 14:33:39 INFO - Running setup.py install for functools32 14:33:39 INFO - warning: no files found matching '*.txt' 14:33:39 INFO - no previously-included directories found matching 'build' 14:33:39 INFO - no previously-included directories found matching 'dist' 14:33:39 INFO - no previously-included directories found matching '.git*' 14:33:39 INFO - Successfully installed jsonschema functools32 14:33:39 INFO - Cleaning up... 14:33:39 INFO - Return code: 0 14:33:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:33:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:39 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:39 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 14:33:39 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 14:33:39 INFO - Using env: {'DISPLAY': ':0', 14:33:39 INFO - 'HOME': '/home/cltbld', 14:33:39 INFO - 'LANG': 'en_US.UTF-8', 14:33:39 INFO - 'LANGUAGE': 'en_US:en', 14:33:39 INFO - 'LOGNAME': 'cltbld', 14:33:39 INFO - 'MAIL': '/var/mail/cltbld', 14:33:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:39 INFO - 'MOZ_NO_REMOTE': '1', 14:33:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:39 INFO - 'NO_EM_RESTART': '1', 14:33:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:39 INFO - 'PWD': '/builds/slave/test', 14:33:39 INFO - 'SHELL': '/bin/bash', 14:33:39 INFO - 'SHLVL': '1', 14:33:39 INFO - 'TERM': 'linux', 14:33:39 INFO - 'TMOUT': '86400', 14:33:39 INFO - 'USER': 'cltbld', 14:33:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:39 INFO - '_': '/tools/buildbot/bin/python'} 14:33:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:39 INFO - Downloading/unpacking blobuploader==1.2.4 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 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 14:33:39 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 14:33:41 INFO - Downloading blobuploader-1.2.4.tar.gz 14:33:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:33:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:33:41 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 14:33:41 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 14:33:41 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 14:33:41 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 14:33:41 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 14:33:41 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 14:33:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:33:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:33:42 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 14:33:42 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 14:33:42 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 14:33:42 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 14:33:42 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 14:33:42 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 14:33:42 INFO - Downloading docopt-0.6.1.tar.gz 14:33:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:33:42 INFO - Installing collected packages: blobuploader, requests, docopt 14:33:42 INFO - Running setup.py install for blobuploader 14:33:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:33:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:33:42 INFO - Running setup.py install for requests 14:33:42 INFO - Running setup.py install for docopt 14:33:43 INFO - Successfully installed blobuploader requests docopt 14:33:43 INFO - Cleaning up... 14:33:43 INFO - Return code: 0 14:33:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:43 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:43 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 14:33:43 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 14:33:43 INFO - Using env: {'DISPLAY': ':0', 14:33:43 INFO - 'HOME': '/home/cltbld', 14:33:43 INFO - 'LANG': 'en_US.UTF-8', 14:33:43 INFO - 'LANGUAGE': 'en_US:en', 14:33:43 INFO - 'LOGNAME': 'cltbld', 14:33:43 INFO - 'MAIL': '/var/mail/cltbld', 14:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:43 INFO - 'MOZ_NO_REMOTE': '1', 14:33:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:43 INFO - 'NO_EM_RESTART': '1', 14:33:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:43 INFO - 'PWD': '/builds/slave/test', 14:33:43 INFO - 'SHELL': '/bin/bash', 14:33:43 INFO - 'SHLVL': '1', 14:33:43 INFO - 'TERM': 'linux', 14:33:43 INFO - 'TMOUT': '86400', 14:33:43 INFO - 'USER': 'cltbld', 14:33:43 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:43 INFO - '_': '/tools/buildbot/bin/python'} 14:33:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:33:43 INFO - Running setup.py (path:/tmp/pip-W3CU7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:33:43 INFO - Running setup.py (path:/tmp/pip-qZRSvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:33:43 INFO - Running setup.py (path:/tmp/pip-fI_rfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:33:43 INFO - Running setup.py (path:/tmp/pip-QUVkv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:33:43 INFO - Running setup.py (path:/tmp/pip-Obp2Bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:33:43 INFO - Running setup.py (path:/tmp/pip-_QiFK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:33:43 INFO - Running setup.py (path:/tmp/pip-OdXNhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:33:43 INFO - Running setup.py (path:/tmp/pip-scOdxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:33:43 INFO - Running setup.py (path:/tmp/pip-7mwHJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:33:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:33:43 INFO - Running setup.py (path:/tmp/pip-aKhM4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:33:44 INFO - Running setup.py (path:/tmp/pip-njnnyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:33:44 INFO - Running setup.py (path:/tmp/pip-VcWLBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:33:44 INFO - Running setup.py (path:/tmp/pip-FzxBnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:33:44 INFO - Running setup.py (path:/tmp/pip-M2qDVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:33:44 INFO - Running setup.py (path:/tmp/pip-izy5vQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:33:44 INFO - Running setup.py (path:/tmp/pip-DFPAN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:33:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:33:44 INFO - Running setup.py (path:/tmp/pip-4wwOXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:33:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:33:44 INFO - Running setup.py install for manifestparser 14:33:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:33:44 INFO - Running setup.py install for mozcrash 14:33:44 INFO - Running setup.py install for mozdebug 14:33:44 INFO - Running setup.py install for mozdevice 14:33:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:33:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:33:44 INFO - Running setup.py install for mozfile 14:33:45 INFO - Running setup.py install for mozhttpd 14:33:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Running setup.py install for mozinfo 14:33:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Running setup.py install for mozInstall 14:33:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Running setup.py install for mozleak 14:33:45 INFO - Running setup.py install for mozlog 14:33:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Running setup.py install for moznetwork 14:33:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Running setup.py install for mozprocess 14:33:45 INFO - Running setup.py install for mozprofile 14:33:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:33:45 INFO - Running setup.py install for mozrunner 14:33:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:33:46 INFO - Running setup.py install for mozscreenshot 14:33:46 INFO - Running setup.py install for moztest 14:33:46 INFO - Running setup.py install for mozversion 14:33:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:33:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:33:46 INFO - Cleaning up... 14:33:46 INFO - Return code: 0 14:33:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:46 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:46 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 14:33:46 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 14:33:46 INFO - Using env: {'DISPLAY': ':0', 14:33:46 INFO - 'HOME': '/home/cltbld', 14:33:46 INFO - 'LANG': 'en_US.UTF-8', 14:33:46 INFO - 'LANGUAGE': 'en_US:en', 14:33:46 INFO - 'LOGNAME': 'cltbld', 14:33:46 INFO - 'MAIL': '/var/mail/cltbld', 14:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:46 INFO - 'MOZ_NO_REMOTE': '1', 14:33:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:46 INFO - 'NO_EM_RESTART': '1', 14:33:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:46 INFO - 'PWD': '/builds/slave/test', 14:33:46 INFO - 'SHELL': '/bin/bash', 14:33:46 INFO - 'SHLVL': '1', 14:33:46 INFO - 'TERM': 'linux', 14:33:46 INFO - 'TMOUT': '86400', 14:33:46 INFO - 'USER': 'cltbld', 14:33:46 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:46 INFO - '_': '/tools/buildbot/bin/python'} 14:33:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:33:46 INFO - Running setup.py (path:/tmp/pip-eFZdJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:33:46 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)) 14:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:33:46 INFO - Running setup.py (path:/tmp/pip-ovB6TP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:33:46 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)) 14:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:33:46 INFO - Running setup.py (path:/tmp/pip-4pNJuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:33:46 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)) 14:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:33:46 INFO - Running setup.py (path:/tmp/pip-zKqxPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:33:46 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)) 14:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:33:46 INFO - Running setup.py (path:/tmp/pip-PDD6ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:33:46 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)) 14:33:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:33:46 INFO - Running setup.py (path:/tmp/pip-KvtuVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:33:47 INFO - Running setup.py (path:/tmp/pip-2CHPpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:33:47 INFO - Running setup.py (path:/tmp/pip-M_9PkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:33:47 INFO - Running setup.py (path:/tmp/pip-Kl1ZeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:33:47 INFO - Running setup.py (path:/tmp/pip-emzchQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:33:47 INFO - Running setup.py (path:/tmp/pip-EKDtqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:33:47 INFO - Running setup.py (path:/tmp/pip-9Y7xdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:33:47 INFO - Running setup.py (path:/tmp/pip-hgBsvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:33:47 INFO - Running setup.py (path:/tmp/pip-webn9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:33:47 INFO - Running setup.py (path:/tmp/pip-IgEcsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:33:47 INFO - Running setup.py (path:/tmp/pip-3RVY4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:33:47 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)) 14:33:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:33:47 INFO - Running setup.py (path:/tmp/pip-ljl5_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:33:47 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)) 14:33:47 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)) 14:33:47 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)) 14:33:47 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)) 14:33:47 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)) 14:33:47 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)) 14:33:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:33:47 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 14:33:47 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 14:33:47 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 14:33:47 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 14:33:47 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 14:33:47 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 14:33:49 INFO - Downloading blessings-1.6.tar.gz 14:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:33:49 INFO - Installing collected packages: blessings 14:33:49 INFO - Running setup.py install for blessings 14:33:49 INFO - Successfully installed blessings 14:33:49 INFO - Cleaning up... 14:33:49 INFO - Return code: 0 14:33:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:33:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:33:50 INFO - Reading from file tmpfile_stdout 14:33:50 INFO - Current package versions: 14:33:50 INFO - argparse == 1.2.1 14:33:50 INFO - blessings == 1.6 14:33:50 INFO - blobuploader == 1.2.4 14:33:50 INFO - docopt == 0.6.1 14:33:50 INFO - functools32 == 3.2.3-2 14:33:50 INFO - jsonschema == 2.5.1 14:33:50 INFO - manifestparser == 1.1 14:33:50 INFO - mozInstall == 1.12 14:33:50 INFO - mozcrash == 0.17 14:33:50 INFO - mozdebug == 0.1 14:33:50 INFO - mozdevice == 0.48 14:33:50 INFO - mozfile == 1.2 14:33:50 INFO - mozhttpd == 0.7 14:33:50 INFO - mozinfo == 0.9 14:33:50 INFO - mozleak == 0.1 14:33:50 INFO - mozlog == 3.3 14:33:50 INFO - moznetwork == 0.27 14:33:50 INFO - mozprocess == 0.23 14:33:50 INFO - mozprofile == 0.28 14:33:50 INFO - mozrunner == 6.12 14:33:50 INFO - mozscreenshot == 0.1 14:33:50 INFO - mozsystemmonitor == 0.3 14:33:50 INFO - moztest == 0.7 14:33:50 INFO - mozversion == 1.4 14:33:50 INFO - psutil == 3.1.1 14:33:50 INFO - requests == 1.2.3 14:33:50 INFO - wsgiref == 0.1.2 14:33:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:33:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:50 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 14:33:50 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 14:33:50 INFO - Using env: {'DISPLAY': ':0', 14:33:50 INFO - 'HOME': '/home/cltbld', 14:33:50 INFO - 'LANG': 'en_US.UTF-8', 14:33:50 INFO - 'LANGUAGE': 'en_US:en', 14:33:50 INFO - 'LOGNAME': 'cltbld', 14:33:50 INFO - 'MAIL': '/var/mail/cltbld', 14:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:50 INFO - 'MOZ_NO_REMOTE': '1', 14:33:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:50 INFO - 'NO_EM_RESTART': '1', 14:33:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:50 INFO - 'PWD': '/builds/slave/test', 14:33:50 INFO - 'SHELL': '/bin/bash', 14:33:50 INFO - 'SHLVL': '1', 14:33:50 INFO - 'TERM': 'linux', 14:33:50 INFO - 'TMOUT': '86400', 14:33:50 INFO - 'USER': 'cltbld', 14:33:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:50 INFO - '_': '/tools/buildbot/bin/python'} 14:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 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)) 14:33:50 INFO - Cleaning up... 14:33:50 INFO - Return code: 0 14:33:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:33:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:33:50 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 0x178f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a50180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, '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': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', '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 14:33:50 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 14:33:50 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 14:33:50 INFO - Using env: {'DISPLAY': ':0', 14:33:50 INFO - 'HOME': '/home/cltbld', 14:33:50 INFO - 'LANG': 'en_US.UTF-8', 14:33:50 INFO - 'LANGUAGE': 'en_US:en', 14:33:50 INFO - 'LOGNAME': 'cltbld', 14:33:50 INFO - 'MAIL': '/var/mail/cltbld', 14:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:50 INFO - 'MOZ_NO_REMOTE': '1', 14:33:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:50 INFO - 'NO_EM_RESTART': '1', 14:33:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:50 INFO - 'PWD': '/builds/slave/test', 14:33:50 INFO - 'SHELL': '/bin/bash', 14:33:50 INFO - 'SHLVL': '1', 14:33:50 INFO - 'TERM': 'linux', 14:33:50 INFO - 'TMOUT': '86400', 14:33:50 INFO - 'USER': 'cltbld', 14:33:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:50 INFO - '_': '/tools/buildbot/bin/python'} 14:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:33:50 INFO - Cleaning up... 14:33:50 INFO - Return code: 0 14:33:50 INFO - Running post-action listener: _resource_record_post_action 14:33:50 INFO - Running post-action listener: _start_resource_monitoring 14:33:50 INFO - Starting resource monitoring. 14:33:50 INFO - [mozharness: 2016-07-22 21:33:50.656506Z] Finished create-virtualenv step (success) 14:33:50 INFO - [mozharness: 2016-07-22 21:33:50.656941Z] Running install step. 14:33:50 INFO - Running pre-action listener: _resource_record_pre_action 14:33:50 INFO - Running main action method: install 14:33:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:33:50 INFO - Reading from file tmpfile_stdout 14:33:50 INFO - Detecting whether we're running mozinstall >=1.0... 14:33:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:33:50 INFO - Reading from file tmpfile_stdout 14:33:50 INFO - Output received: 14:33:50 INFO - Usage: mozinstall [options] installer 14:33:50 INFO - Options: 14:33:50 INFO - -h, --help show this help message and exit 14:33:50 INFO - -d DEST, --destination=DEST 14:33:50 INFO - Directory to install application into. [default: 14:33:50 INFO - "/builds/slave/test"] 14:33:50 INFO - --app=APP Application being installed. [default: firefox] 14:33:50 INFO - mkdir: /builds/slave/test/build/application 14:33:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:34:07 INFO - Reading from file tmpfile_stdout 14:34:07 INFO - Output received: 14:34:07 INFO - /builds/slave/test/build/application/firefox/firefox 14:34:07 INFO - Running post-action listener: _resource_record_post_action 14:34:07 INFO - [mozharness: 2016-07-22 21:34:07.337361Z] Finished install step (success) 14:34:07 INFO - [mozharness: 2016-07-22 21:34:07.337699Z] Running run-tests step. 14:34:07 INFO - Running pre-action listener: _resource_record_pre_action 14:34:07 INFO - Running main action method: run_tests 14:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:34:07 INFO - Python 2.7.3 14:34:07 INFO - Return code: 0 14:34:07 INFO - grabbing minidump binary from tooltool 14:34:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:34:07 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 0x1a4e3b0>, '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 0x1a4f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a4f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:34:07 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 14:34:07 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 14:34:07 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 14:34:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:34:07 INFO - Return code: 0 14:34:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:34:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:34:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:34:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:34:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 14:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 14:34:07 INFO - Using env: {'DISPLAY': ':0', 14:34:07 INFO - 'HOME': '/home/cltbld', 14:34:07 INFO - 'LANG': 'en_US.UTF-8', 14:34:07 INFO - 'LANGUAGE': 'en_US:en', 14:34:07 INFO - 'LOGNAME': 'cltbld', 14:34:07 INFO - 'MAIL': '/var/mail/cltbld', 14:34:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:34:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:07 INFO - 'MOZ_NO_REMOTE': '1', 14:34:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:34:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:34:07 INFO - 'NO_EM_RESTART': '1', 14:34:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:07 INFO - 'PWD': '/builds/slave/test', 14:34:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:34:07 INFO - 'SHELL': '/bin/bash', 14:34:07 INFO - 'SHLVL': '1', 14:34:07 INFO - 'TERM': 'linux', 14:34:07 INFO - 'TMOUT': '86400', 14:34:07 INFO - 'USER': 'cltbld', 14:34:07 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687', 14:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:07 INFO - '_': '/tools/buildbot/bin/python'} 14:34:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 14:34:07 INFO - mozversion application_buildid: 20160722133325 14:34:07 INFO - mozversion application_changeset: 64e2353eb55496dd19f3fe41bf2ae856a0fc3546 14:34:07 INFO - mozversion application_display_name: Nightly 14:34:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:34:07 INFO - mozversion application_name: Firefox 14:34:07 INFO - mozversion application_remotingname: firefox 14:34:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:34:07 INFO - mozversion application_vendor: Mozilla 14:34:07 INFO - mozversion application_version: 50.0a1 14:34:07 INFO - mozversion platform_buildid: 20160722133325 14:34:07 INFO - mozversion platform_changeset: 64e2353eb55496dd19f3fe41bf2ae856a0fc3546 14:34:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:34:07 INFO - mozversion platform_version: 50.0a1 14:34:07 INFO - using testdate: 1469223247 14:34:07 INFO - actual date: 1469223247 14:34:07 INFO - starting webserver on 'localhost:43179' 14:34:07 INFO - SUITE-START | Running 2 tests 14:34:07 INFO - TEST-START | dromaeo_css 14:34:07 INFO - operating with platform_type : linux_ 14:34:07 INFO - Initialising browser for dromaeo_css test... 14:34:07 INFO - TEST-INFO | started process 22816 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppPt48z/profile http://localhost:43179/getInfo.html) 14:34:11 INFO - PROCESS | 22816 | JavaScript error: , line 0: uncaught exception: undefined 14:34:11 INFO - PROCESS | 22816 | __metrics Screen width/height:1600/1200 14:34:11 INFO - PROCESS | 22816 | colorDepth:24 14:34:11 INFO - PROCESS | 22816 | Browser inner width/height: 1024/697 14:34:11 INFO - PROCESS | 22816 | __metrics 14:34:12 INFO - PROCESS | 22816 | 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" 14:34:12 INFO - PROCESS | 22816 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:34:13 INFO - TEST-INFO | 22816: exit 0 14:34:13 INFO - Browser initialized. 14:34:13 INFO - Running cycle 1/1 for dromaeo_css test... 14:34:13 INFO - TEST-INFO | started process 22898 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppPt48z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:25 INFO - PROCESS | 22898 | RSS: Main: 159477760 14:34:25 INFO - PROCESS | 22898 | 14:35:16 INFO - PROCESS | 22898 | 1469223316526 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] 14:37:29 INFO - PROCESS | 22898 | Cycle 1(1): loaded http://localhost:43179/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43179/tests/dromaeo/cssquery-ext.html) 14:37:30 INFO - PROCESS | 22898 | RSS: Main: 250368000 14:37:30 INFO - PROCESS | 22898 | 14:40:33 INFO - PROCESS | 22898 | Cycle 1(1): loaded http://localhost:43179/tests/dromaeo/cssquery-ext.html (next: http://localhost:43179/tests/dromaeo/cssquery-jquery.html) 14:40:33 INFO - PROCESS | 22898 | RSS: Main: 242855936 14:40:33 INFO - PROCESS | 22898 | 14:43:35 INFO - PROCESS | 22898 | Cycle 1(1): loaded http://localhost:43179/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43179/tests/dromaeo/cssquery-mootools.html) 14:43:36 INFO - PROCESS | 22898 | RSS: Main: 192098304 14:43:36 INFO - PROCESS | 22898 | 14:46:38 INFO - PROCESS | 22898 | Cycle 1(1): loaded http://localhost:43179/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43179/tests/dromaeo/cssquery-prototype.html) 14:46:39 INFO - PROCESS | 22898 | RSS: Main: 193363968 14:46:39 INFO - PROCESS | 22898 | 14:49:42 INFO - PROCESS | 22898 | Cycle 1(1): loaded http://localhost:43179/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43179/tests/dromaeo/cssquery-yui.html) 14:49:42 INFO - PROCESS | 22898 | RSS: Main: 192811008 14:49:42 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | Cycle 1(1): loaded http://localhost:43179/tests/dromaeo/cssquery-yui.html (next: http://localhost:43179/tests/dromaeo/cssquery-dojo.html) 14:52:46 INFO - PROCESS | 22898 | RSS: Main: 282058752 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | __start_tp_report 14:52:46 INFO - PROCESS | 22898 | _x_x_mozilla_page_load 14:52:46 INFO - PROCESS | 22898 | _x_x_mozilla_page_load_details 14:52:46 INFO - PROCESS | 22898 | |i|pagename|runs| 14:52:46 INFO - PROCESS | 22898 | |0;dojo.html;2572.6141078838177;2623.5277650023;2629.6449979252793;2635.2226093302475;2670.5860591608293;4616.976915115428;4710.764461776915;4781.18719817631;4817.590504807091;4818.132736107499;4739.454962679293;4759.690620109691;4808.086463571927;4817.831375901844;4867.634927380443;4325.956740432587;4397.714148580332;4408.42690450241;4472.753998530079;4582.908341833161;4481.551844815525;4518.728876267434;4536.478304994935;4546.909061818762;4554.567316104982;4334.5882141196535;4364.869053928355;4428.911421771579;4431.756253406062;4440.267355886275;4376.496702879178;4382.912341753179;4390.714603550791;4475.373447717322;4484.372187893698;4426.424564806587;4437.134758722051;4444.088961762864;4445.599896009342;4476.171908196994;4665.2768820832825;4716;4777.187878060738;4845.83039593616;4878.341423907763;5162.586993040548;5174.818881339173;5194.71429071401;5197.610179236534;5210.947890521122;5160.071187186305;5188.11801993662;5208.5051920067535;5219.321488206509;5222.817201397971;4729.148753224453;4791.6406269529225;4838.298446725205;4840.7337596432535;4859.635527335499;5318.308619879468;5320.334958130234;5345.278387417727;5377.946220537823;5384.6499977501335;5109.952459745743;5111.488851114933;5145.562627176657;5156.510131537498;5159.071367153948;5050.292958985745;5137.614678899097;5178;5184.792608295701;5248.737563121828;5150.201718733602;5153.201253805666;5155.200943853709;5161.483851614884;5195.584353251731;5135.922961155586;5141.151709967813;5143.897122057538;5147.948520514822;5193.818216362485;4141.772202528829;4205.600467955601;4209.494860616745;4323.978380108079;4358.5205627380965;3802.524684414448;3818.2745278397015;3849.191669749384;3855.325318069327;3904.472896159039;4475.328700694922;4476.888077798081;4482.058767658828;4486.775661216926;4529.048899731028;5004.84985450437;5011.574016208663;5240.842774716764;5252.527272545489;5309.309789727311;4389.275769497997;4418.55814418562;4423.380726698265;4458.108378324348;4476.037651904791;4644.9767751161025;4645.372874661945;4646.8141274348645;4653.069386122789;4699.647526435463;4598.839040633562;4792.880177995438;4815.855524334275;4840.2255639097575;4881.975590122027;4955.603551715926;5031.7484125792835;5083.186690129562;5128.358955130609;5217.06092903281;4799.616030717464;4803.615710743202;4804.375431193923;4805.7597120143155;4849.878753031202;5075.314832497713;5081.593472522263;5085.7711402986215;5086;5133.640645154804;5058.468860769566;5151.356080489939;5186.377634683862;5211.478852114758;5240.842774716764;5091.1854103343285;5105.157648987875;5108.463611320758;5110.105731496898;5159.948400515947;5061.164907790173;5090.669106508065;5096.490350964874;5100.209467532539;5108.9233661495855;4763.642726795435;4875.536824001714;4905.681130726492;4914.582260507754;5000.274960130833;5059.620528460307;5080.568151706999;5084.51697088776;5085.389753229636;5113.028524580318;4835.226363781778;4908.288298196813;4910.779014944264;4956.529129732669;4957.03337849116 14:52:46 INFO - PROCESS | 22898 | |1;ext.html;575.6546072356585;586.1442294250394;588.2323567744093;589.8171566814286;594.6105301027828;28556.42887142256;29147.708522914763;38354.23291534171;38933;39607.20785584287;39129.60870391295;39546.8022659887;39623.207535849266;39736.60263397365;39808.80095599518;7624.809379765508;7781.144074151839;7785.883211751829;7790.571518566477;7793.376529877624;7602.581857997808;7682.961585192066;7689.653965571535;7691.538507689528;7693.038370203724;7691.653875575585;7733.304002639761;7743.186965368612;7745.573993430359;7749.806254843646;7646.655900484464;7678.577678227695;7691.23087691232;7714.61426928657;7718.228177182237;7646.120696119961;7694.307512323861;7698.4226183035935;7710.730124445618;7713.112992005935;37784;37974;38032.61967380346;38220.70675759436;38223.235535289416;2866.5413533834485;2880.971190288112;2957.556366545014;2974.970250297491;3005.293755967346;27344.726552734417;27635.447291054268;28027.019054333;28039.719602803914;29391.706082939112;3312.1719570107475;3363.1928337199142;3391.796492210475;3417.7607567470286;3434.9656503435144;258422;258863;259437.7028114866;259779;260209;51177.23234151491;51470.742646286526;51585.48414515882;51618.22572661413;51656;33297.66702332946;33918.49122263168;33941.66058339435;54224.728876355366;54350.45649543533;15317.46388876384;15461.072335659896;15492.612684682748;15499.612509687347;15505.534833955;11575.942120289345;11658.18392712519;11662.65012049623;11676.357800320893;11681.88318116808;18162.182701778453;18282.988871224155;18358.806677565917;18378.448646540626;18497.075146242634;6763.966180169067;6807.897881531781;6872.209695885037;7020.368166865007;7037.155541334971;784.3489903380295;913.6710784117712;953.8712273842942;1007.8639383683109;1039.7088815131776;27480.17559473219;28410.573841392397;28441.28896777597;28540.286492837844;28576.857115714705;782.5031105248095;963.9759006024765;997.4613708597421;1033.5245786937942;1040.5785656809005;14207.289635518182;15014.624634384229;15231;15771.44799931997;15986.68026639484;385631;387208;389350;389973;390022;49420.258696119585;49908.750456247486;49947.25079123816;49948.25077623838;50088.74955625198;1112.2492317685692;1189.8036823924128;1193.8925496705338;1223.1988047828686;1231.1382032577233;27465.588016180172;28973.855130724212;29235;29250.976215832343;29775.10674679763;41375.7931210342;42253.1549369017;42286.36570451384;42315.365269520364;42350.576494235895;305109;307918;308369;310116;310299;22649;22779.97490112982;22872.656910146696;23853.211009174036;27014.729852702007;41915;41941.79029104957;42051.36922946097;42133.57866421419;42308.788456057526;35705.28589428145;35713.821430892676;35900.64099358973;36295.455568167024;36396.454053188696;34584.48123278203;36683.63316366909;36725.265494689425;37100.81449592735;37452.43821342628;35174.64825351714;35439.11402214862;35874.10314742152;36381.27237455289;36623.45064823976;31490.89781857624;33597.49603755995;33854.153646159044;34070.14824629404;34154.316913662085;25108.874455628335;25706.37285288666;25991.480170396866;25992.220233392272;26342.341441464116 14:52:46 INFO - PROCESS | 22898 | |2;jquery.html;341.9350323438548;363.5909601698088;365.5595008015341;368.9022409061598;384.98075096245196;12221.877781222196;15741.055536667795;16191.433299834523;16915.661686766256;17113.486595402126;16327.34690612377;16677.583060423465;16711.91644041778;17091.82908170918;17183.140842957833;4951.975240123803;5152.999999999981;5211.244369566411;5445.156000819879;5482.588805839558;3408.6761757632985;5444.809431669893;5479.808206712766;5483.75323110461;5729.598928075036;5977.133315669225;6032.366601506845;6038.909416358736;6052.364501727321;6063.060225665007;5839.153322768218;5950.821475355703;6014.488768454687;6018.337982821886;6031.065184896326;5316.867078323035;5334.1732031535175;5338.439463856317;5340.519353258225;5343.278657381243;11908.34504102274;12047.638570842888;12293.262404255684;12344.259344439362;12371.443285052104;1919.1363886251243;1942.2328180368675;1964.0670681426352;2127.638301488735;2176.6952626632283;8791.296696264284;8873.511956842436;8941.50821704806;9063.27493800501;9236.445813251226;1720.2001069502617;1725.723884178538;1726.2059452651795;1756.8945863248246;1823.5441139715072;68273.65863170702;68818.65590672064;69552;70499.29500704979;71048.6447567764;9761.853572196424;9819.06718861707;9860.26048046385;9939.210942030504;10118.747031324128;13612.93193534026;13631.727365452636;13633.931830340785;13639.908807295393;13641.86358136406;7185.137783466018;7432.293932076444;7464.888026679496;7578.12851522071;7638.465307428427;7113.89329160063;7128.893066604005;7401.925980740231;7405.407567394595;7409.925900741031;4160.563140870226;4222.176675548239;4286.7856607169515;4355.194289056535;4376.277914144194;1803.6122233719814;1820.7906090799463;1838.3933302010305;1839.9540011499819;1846.722991551278;815.8205194857122;886.7650072730731;901.8241442918568;923.8983711791698;930.4417349590327;15704.450344237897;16162.272697728631;16341.26464309109;16508.422205223;17219.741703874453;820.7199454843022;828.8715249136394;858.948463092204;898.3576742628921;919.737018917323;9100.362974591848;9342.439453632804;9465.621375144918;9572;9621.326507144433;392186;404017;407696.23037695995;407908;408838;77526;81520.59239703763;81793;82303.58848205721;83428.16571834325;884.0893879304456;887.8712586674895;909.3816204980677;909.5361365703407;915.8580420034905;14377.712445751236;14777.704445910807;15108.093514389171;16071.35714571404;16764.32942682328;35308.47037294494;37668.81165594154;39002.414963775;40125.59874401218;40699.79650101731;48931.75534122306;51787.48212517826;53862.46137538574;54358.7282063603;54592;6994.895076573754;7376.7418140364825;7403.851922961619;7565.470417070752;7770.106437759504;11110.388928609003;11364.602238921598;11370.431478426211;11882.227655202385;12038.157328987238;9813;9901.356411833207;10018.949905250427;10084.243681724047;10174.491275436347;5785.971070144622;10163.44101074448;10168.694939151837;10575.524101415302;10684.57261709523;9674.661386851427;9782.168515676247;9953;9992.650257241252;10387.065164135263;7381.224971377928;7442.1441534222095;7524.736634217776;7538.849223015399;7781.105172905189;8283.83432331362;9712.514374281116;9804.36271642341;10151.7462063449;10216.029477199654 14:52:46 INFO - PROCESS | 22898 | |3;mootools.html;1258.0878862824459;1348.2382453913538;1412.4067891485577;1468.9485867994615;1675.1624187906039;6783.694733736981;7628.694852205912;7867.291943725071;7881.054273487175;8100.189981001897;7885.053793544768;7943.92056079439;7951.244631759973;7955.641996110175;7957.20427957204;2113.6723807809813;2139.272647299918;2139.3474990128007;2139.732533433321;2143.024923659731;2079.189116244666;2111.883846388448;2161.8054375106235;2164.7943445372744;2164.9783502164933;1934.5647229373349;1951.0537389366145;1952.8380613534996;1954.3257576136211;2101.422108920044;2074.1703318672653;2075.647139986206;2080.355089922129;2080.5214800595872;2081.781412951641;2055.6710926251876;2060.1862264405627;2091.111277706984;2093.1731965873396;2093.7801530839347;7913.050433947963;7924.841503169962;7969.043714754266;7979.56112413817;7981.241782030698;4401.295792673189;4610.585047345721;4630.143423466669;4685.88285292867;4691.437027556715;2760.5169095408423;2827.0953294945625;2881.4957382458197;2893.2622181343622;2905.912822615304;864.8789169516273;880.7049638371153;885.1148851148852;894.3069121430879;901.9503927283997;10504.474776261233;10642.09542188915;10655.573777048909;10665.52005159762;10677.039066483976;3808;3971.3447281198855;4061.1065565575527;4065.6137666921595;4066.3900414938;8491.830163396697;8492.108328625529;8493.660253589911;8501.447405918596;8503.234708876107;4574.405327307495;4579.702319349299;4580.564846339659;4581.725096494221;4582.725036497821;3702.4631428443263;4115.156392939441;4221.092465119953;4223.155368926227;4262.104957958802;3354.4632858742484;3373.645767194409;3488.7906725596545;3489.7906125632544;3520.8415621297104;951.3578334624101;1002.0981116994762;1005.0452070533023;1013;1017.7099526634873;741.9925800741923;783.5142211828703;791.5646394483126;808.623989844725;820.4748960665179;5149.665271757323;5202.271681964528;5257.737113144328;5313.362396512443;5431.592630552725;376.095490345719;391.48324212040404;392.1959982036813;392.2723348189068;394.05623531641555;3475.6350583188905;3537.4163263061823;3629.764065335745;3640.2355505344176;3681.465792838851;9243.676471323472;9714.3199976001;9723.513824308757;9736.902630973742;9783.0706082922;9102.681406150885;9690.030996900394;9697.515124243759;9700.078492543196;9701.07839755222;6996.125484314461;7350.08123984502;7353.080864891996;7416.629168541659;7432.442566807403;5360.356757189163;5471.452854714496;5506.559475241891;5593.216949627137;5617.634853734494;8833.425827321204;8928.464292142493;8932.374733768575;8969.820603588023;9013.549322533716;8970.86543701832;9150.49672268005;9184.448933064037;9222.93936197319;9258.444493330422;5871.588988770745;6570.375814297634;6618.90071648916;6707.993800929899;6737.090492783412;7574.356179724785;7669.118051424158;7679.731209407644;7681.884771728317;7682.692692292246;6825.180978282439;6867.622280774605;6883.724651013903;6892.655367231518;7064.470164737562;6633.2040155181685;6657.167854018247;6763.628000460022;6863.485238607024;7031.578105313698;5827.650340979555;5877.324107727495;5931;5935.228420305332;5936.940630593639;4404.053128577243;4470.926977525435;4577.8168873244695;4605.424321959755;4630.930536042029;4536.206163921234;4600.608948239437;4634.536546345474;4680.204365257846;4850.1997170466475 14:52:46 INFO - PROCESS | 22898 | |4;prototype.html;287.44523070473974;290.1440749788125;296.6306947849927;300.12513523080224;301.2093255205087;4643.326717625942;4772.021735544214;4785.976070119649;4811.518848115186;4836.8065277388905;4591.954080459194;4733.195356789345;4762.333183385813;4775.570198682118;4778.450478195009;3818.369968955133;3830.616938306161;3834.3481608126617;3835.7506762060516;3849.441830934513;3531.894043178696;3560.376934036546;3842.7886466244345;3852.3451013327804;3853.402722578005;3757.1358587524887;3772.9437456586224;3777.376732839078;3782.6217378262227;3783.716221283401;3761.3981762918074;3764.738812497803;3766.4915236443003;3768.5289338832645;3775.848966041355;3336.399448099341;3340.866365345383;3342.59333280038;3343.225123147806;3343.896514150349;4545.340925565807;4658.34783130362;4665.813367465297;4669.696469729491;4692.36653051837;4281.422008028939;4284.742915425085;4285.6142947134595;4293.9479827442165;4304.332828411601;4989.925151122736;4993;4997.2004479283505;5004.699718016931;5012.122878496285;3531.841067151984;3635.800030998294;3640.381135207021;3672.6327367263325;3677.3380791457525;5207.739613019372;5213.426523082458;5217.817376391846;5225.790968361261;5230.973845130788;3678.9448158277646;3683.2743769610297;3698.852045918187;3699.3537875645616;3701.130234394915;3660.7986560739414;3757.755745876509;3766.5291838520184;3773.132179598708;3817.3510503213965;4291.420658211164;4292.163028209532;4295.72077814941;4304.869089800569;4311.159323931804;5018.84943451697;5024.572911302507;5028.510887110903;5049.166887463527;5053.2420136979745;1205.5954812643347;1277.9297138657464;1287.4077924154994;1305.536534530228;1317.4730107956702;894;900;922.3082687984012;922.9400088994194;932.3613324872484;4380.189664911938;4386.232409328419;4395.648348132142;4418.790604697651;4424;5087.821926232638;5116.258142569381;5122.773711687798;5123.6925784452305;5146.974265128651;4376.387305777193;4407.735535867794;4415.977920110379;4418.116376724668;4418.779061046935;2302.9654555181687;2426.59961106419;2427.66012758214;2430.343807172089;2447.9388015299405;4300.677449191323;4318.222719910384;4338.783060846945;4346.456692913386;4368.9126217476105;4688.835890743808;4721.409823772028;4721.527847215251;4722.811087556459;4736.46065028867;4357.9564204358185;4545.795439205243;4881;4930.457649658606;4931.408231012301;5188.247704082812;5222.947770522246;5224.869378265574;5227.947720522746;5250.606204534599;5083.195465609655;5104.846854594368;5120.667156634807;5122.513361230677;5128.769205385841;4720.984988227548;4858.927116093332;4900.191468407816;4903.33804936329;4910.533499317702;3573.1960308930284;3584.534010578608;3588.7308451865692;3595.820208989592;3596.8381422835514;4971.304017437634;5188.299579556711;5194.35070616173;5195.701074731317;5198.610104242121;4870.2938073979785;5025.623078269071;5036.748162591929;5036.84889453317;5045;5035.4712755161645;5041.697498150123;5047.570956468741;5047.939932614192;5048.621353398583;4804.6876952997945;4946.975265123651;4948.851534453972;4950.2079667253065;4954.157793175241;5027.195648696337;5035.370578677665;5048.899022019613;5059;5079.3650793650795;4929.2359684248995;4933.32467311855;4940;4947.233178857283;4955.529224723646 14:52:46 INFO - PROCESS | 22898 | |5;yui.html;605.7183409714482;609.4728060227903;610.1153327674873;639.3031595560841;647.3332467558411;12652.810207846878;13349.399277032533;13728.931355343222;13785.310734463286;13815;13556.457741690345;13632.659183520416;13686.11040282381;13702.246376449291;13720.862791372108;738.3207449146771;769.2807225244414;777.5062835099695;778.0896351269;778.4395235430482;626.7399029402792;748.9176190619028;773.2113244490616;779.7582749347692;788.0897563314381;810.0643756461267;814.2386868278179;814.2834305810878;814.6741303478626;814.7433558429105;806.0569134113101;806.6289506826866;807.8182408957996;808.9029316482001;809.1018968944467;481.4295060353492;489.6156517134068;492.2444048385711;499.04433010784305;574.2936188488142;11990.520379184822;12441.315727634976;12468.937655311665;12512.874871251353;12517.43671534783;12771.680707982281;12890;13261.138026028277;13388;13833.239171845544;11768.70272484736;11919.523219071227;12097.57658481949;12197.390130493528;12652.873471265262;4058.0869304406274;4069.816858241357;4105.199486100211;4228.302330115527;4229.72506787061;67200.32799671989;67990.3200967989;68482;69413;70156.29843701549;9458.101480359355;9894.208463322917;9979.50102494887;10108.696739097839;10130.392176469435;17104.57238569046;17745.11274436273;17837.375691850724;18510.537236569195;18587.90706046488;6672.833179170462;6801.353871382211;6934.202566704894;7035;7052.047973523611;3702.148505843671;3707.2029513654693;3727.012341729444;3750.9812450938116;3767.5290588676417;2743.1496236166854;2845.9715402846123;2846.9430611387656;3314.121757734202;3465.358908601917;561.2255087978564;567.5998421113105;568.139269007454;569.8831739493394;581.3343721438929;505.2421367948078;524.3864678326368;584.4798129664601;592.48453845155;604.2748701558148;7091;8368.630855272619;8588.957055214683;8704.869426958601;9294.349395542247;473.90284991576647;503.75064343150376;517.8787924144261;553.2807350444406;604.0003793721398;3704.292369892821;3773.52830896138;3914.256291304643;3959.2279505496726;4019.678425725935;57327.14009289864;57608;57752;58619;93732.53133734425;71426.64286678533;72488.27511724815;78734.63796086126;81614.59192704117;83239;47766.76116619464;55049;71631;74643.62678186683;76175.61912190403;7663.69345226207;7852.960735196516;7901.68393264263;7942.377855330068;8044.195580442143;42321.153576927674;42852.78573607112;42939.35590966201;43609;43820;57249.71375143098;64150;65435;66204.66897665481;67901.3209867895;11110.277831941221;11216;11401.942990284995;11441;11753.941230293794;11569.768604628029;11826.52693892269;11992.520299187934;12214;12311.692207694521;11274.436278185894;11517.136214784092;11592.942035289769;12098.879011209776;12521.499140034297;7761;8689.6089675966;10606.681799546026;10824.837627435438;11176.217664763388;10829;11027.889721102687;11497.655070347902;11668.533258669559;11886.405679715806;9096.226820720049;9291.581878815605;9669.564869580745;9839.85240221383;10079.496025198858;9839.409635421898;9953.452560108974;10076.445795481319;10175.491225438847;10196.286259961731 14:52:46 INFO - PROCESS | 22898 | __end_tp_report 14:52:46 INFO - PROCESS | 22898 | __start_cc_report 14:52:46 INFO - PROCESS | 22898 | _x_x_mozilla_cycle_collect,980 14:52:46 INFO - PROCESS | 22898 | __end_cc_report 14:52:46 INFO - PROCESS | 22898 | __startTimestamp1469224366334__endTimestamp 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | ------- Summary: start ------- 14:52:46 INFO - PROCESS | 22898 | Number of tests: 6 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | [#0] dojo.html Cycles:165 Average:4764.36 Median:4849.88 stddev:515.40 (10.6%) stddev-sans-first:487.46 14:52:46 INFO - PROCESS | 22898 | Values: 2572.6 2623.5 2629.6 2635.2 2670.6 4617.0 4710.8 4781.2 4817.6 4818.1 4739.5 4759.7 4808.1 4817.8 4867.6 4326.0 4397.7 4408.4 4472.8 4582.9 4481.6 4518.7 4536.5 4546.9 4554.6 4334.6 4364.9 4428.9 4431.8 4440.3 4376.5 4382.9 4390.7 4475.4 4484.4 4426.4 4437.1 4444.1 4445.6 4476.2 4665.3 4716.0 4777.2 4845.8 4878.3 5162.6 5174.8 5194.7 5197.6 5210.9 5160.1 5188.1 5208.5 5219.3 5222.8 4729.1 4791.6 4838.3 4840.7 4859.6 5318.3 5320.3 5345.3 5377.9 5384.6 5110.0 5111.5 5145.6 5156.5 5159.1 5050.3 5137.6 5178.0 5184.8 5248.7 5150.2 5153.2 5155.2 5161.5 5195.6 5135.9 5141.2 5143.9 5147.9 5193.8 4141.8 4205.6 4209.5 4324.0 4358.5 3802.5 3818.3 3849.2 3855.3 3904.5 4475.3 4476.9 4482.1 4486.8 4529.0 5004.8 5011.6 5240.8 5252.5 5309.3 4389.3 4418.6 4423.4 4458.1 4476.0 4645.0 4645.4 4646.8 4653.1 4699.6 4598.8 4792.9 4815.9 4840.2 4882.0 4955.6 5031.7 5083.2 5128.4 5217.1 4799.6 4803.6 4804.4 4805.8 4849.9 5075.3 5081.6 5085.8 5086.0 5133.6 5058.5 5151.4 5186.4 5211.5 5240.8 5091.2 5105.2 5108.5 5110.1 5159.9 5061.2 5090.7 5096.5 5100.2 5108.9 4763.6 4875.5 4905.7 4914.6 5000.3 5059.6 5080.6 5084.5 5085.4 5113.0 4835.2 4908.3 4910.8 4956.5 4957.0 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | [#1] ext.html Cycles:180 Average:46920.16 Median:36098.05 stddev:85023.63 (235.5%) stddev-sans-first:85190.94 14:52:46 INFO - PROCESS | 22898 | Values: 575.7 586.1 588.2 589.8 594.6 28556.4 29147.7 38354.2 38933.0 39607.2 39129.6 39546.8 39623.2 39736.6 39808.8 7624.8 7781.1 7785.9 7790.6 7793.4 7602.6 7683.0 7689.7 7691.5 7693.0 7691.7 7733.3 7743.2 7745.6 7749.8 7646.7 7678.6 7691.2 7714.6 7718.2 7646.1 7694.3 7698.4 7710.7 7713.1 37784.0 37974.0 38032.6 38220.7 38223.2 2866.5 2881.0 2957.6 2975.0 3005.3 27344.7 27635.4 28027.0 28039.7 29391.7 3312.2 3363.2 3391.8 3417.8 3435.0 258422.0 258863.0 259437.7 259779.0 260209.0 51177.2 51470.7 51585.5 51618.2 51656.0 33297.7 33918.5 33941.7 54224.7 54350.5 15317.5 15461.1 15492.6 15499.6 15505.5 11575.9 11658.2 11662.7 11676.4 11681.9 18162.2 18283.0 18358.8 18378.4 18497.1 6764.0 6807.9 6872.2 7020.4 7037.2 784.3 913.7 953.9 1007.9 1039.7 27480.2 28410.6 28441.3 28540.3 28576.9 782.5 964.0 997.5 1033.5 1040.6 14207.3 15014.6 15231.0 15771.4 15986.7 385631.0 387208.0 389350.0 389973.0 390022.0 49420.3 49908.8 49947.3 49948.3 50088.7 1112.2 1189.8 1193.9 1223.2 1231.1 27465.6 28973.9 29235.0 29251.0 29775.1 41375.8 42253.2 42286.4 42315.4 42350.6 305109.0 307918.0 308369.0 310116.0 310299.0 22649.0 22780.0 22872.7 23853.2 27014.7 41915.0 41941.8 42051.4 42133.6 42308.8 35705.3 35713.8 35900.6 36295.5 36396.5 34584.5 36683.6 36725.3 37100.8 37452.4 35174.6 35439.1 35874.1 36381.3 36623.5 31490.9 33597.5 33854.2 34070.1 34154.3 25108.9 25706.4 25991.5 25992.2 26342.3 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | [#2] jquery.html Cycles:180 Average:24601.41 Median:5341.90 stddev:66826.69 (1251.0%) stddev-sans-first:66989.34 14:52:46 INFO - PROCESS | 22898 | Values: 341.9 363.6 365.6 368.9 385.0 12221.9 15741.1 16191.4 16915.7 17113.5 16327.3 16677.6 16711.9 17091.8 17183.1 4952.0 5153.0 5211.2 5445.2 5482.6 3408.7 5444.8 5479.8 5483.8 5729.6 5977.1 6032.4 6038.9 6052.4 6063.1 5839.2 5950.8 6014.5 6018.3 6031.1 5316.9 5334.2 5338.4 5340.5 5343.3 11908.3 12047.6 12293.3 12344.3 12371.4 1919.1 1942.2 1964.1 2127.6 2176.7 8791.3 8873.5 8941.5 9063.3 9236.4 1720.2 1725.7 1726.2 1756.9 1823.5 68273.7 68818.7 69552.0 70499.3 71048.6 9761.9 9819.1 9860.3 9939.2 10118.7 13612.9 13631.7 13633.9 13639.9 13641.9 7185.1 7432.3 7464.9 7578.1 7638.5 7113.9 7128.9 7401.9 7405.4 7409.9 4160.6 4222.2 4286.8 4355.2 4376.3 1803.6 1820.8 1838.4 1840.0 1846.7 815.8 886.8 901.8 923.9 930.4 15704.5 16162.3 16341.3 16508.4 17219.7 820.7 828.9 858.9 898.4 919.7 9100.4 9342.4 9465.6 9572.0 9621.3 392186.0 404017.0 407696.2 407908.0 408838.0 77526.0 81520.6 81793.0 82303.6 83428.2 884.1 887.9 909.4 909.5 915.9 14377.7 14777.7 15108.1 16071.4 16764.3 35308.5 37668.8 39002.4 40125.6 40699.8 48931.8 51787.5 53862.5 54358.7 54592.0 6994.9 7376.7 7403.9 7565.5 7770.1 11110.4 11364.6 11370.4 11882.2 12038.2 9813.0 9901.4 10018.9 10084.2 10174.5 5786.0 10163.4 10168.7 10575.5 10684.6 9674.7 9782.2 9953.0 9992.7 10387.1 7381.2 7442.1 7524.7 7538.8 7781.1 8283.8 9712.5 9804.4 10151.7 10216.0 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | [#3] mootools.html Cycles:180 Average:5086.22 Median:5230.00 stddev:2920.31 (55.8%) stddev-sans-first:2914.33 14:52:46 INFO - PROCESS | 22898 | Values: 1258.1 1348.2 1412.4 1468.9 1675.2 6783.7 7628.7 7867.3 7881.1 8100.2 7885.1 7943.9 7951.2 7955.6 7957.2 2113.7 2139.3 2139.3 2139.7 2143.0 2079.2 2111.9 2161.8 2164.8 2165.0 1934.6 1951.1 1952.8 1954.3 2101.4 2074.2 2075.6 2080.4 2080.5 2081.8 2055.7 2060.2 2091.1 2093.2 2093.8 7913.1 7924.8 7969.0 7979.6 7981.2 4401.3 4610.6 4630.1 4685.9 4691.4 2760.5 2827.1 2881.5 2893.3 2905.9 864.9 880.7 885.1 894.3 902.0 10504.5 10642.1 10655.6 10665.5 10677.0 3808.0 3971.3 4061.1 4065.6 4066.4 8491.8 8492.1 8493.7 8501.4 8503.2 4574.4 4579.7 4580.6 4581.7 4582.7 3702.5 4115.2 4221.1 4223.2 4262.1 3354.5 3373.6 3488.8 3489.8 3520.8 951.4 1002.1 1005.0 1013.0 1017.7 742.0 783.5 791.6 808.6 820.5 5149.7 5202.3 5257.7 5313.4 5431.6 376.1 391.5 392.2 392.3 394.1 3475.6 3537.4 3629.8 3640.2 3681.5 9243.7 9714.3 9723.5 9736.9 9783.1 9102.7 9690.0 9697.5 9700.1 9701.1 6996.1 7350.1 7353.1 7416.6 7432.4 5360.4 5471.5 5506.6 5593.2 5617.6 8833.4 8928.5 8932.4 8969.8 9013.5 8970.9 9150.5 9184.4 9222.9 9258.4 5871.6 6570.4 6618.9 6708.0 6737.1 7574.4 7669.1 7679.7 7681.9 7682.7 6825.2 6867.6 6883.7 6892.7 7064.5 6633.2 6657.2 6763.6 6863.5 7031.6 5827.7 5877.3 5931.0 5935.2 5936.9 4404.1 4470.9 4577.8 4605.4 4630.9 4536.2 4600.6 4634.5 4680.2 4850.2 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | [#4] prototype.html Cycles:180 Average:4139.09 Median:4662.08 stddev:1198.83 (25.7%) stddev-sans-first:1166.82 14:52:46 INFO - PROCESS | 22898 | Values: 287.4 290.1 296.6 300.1 301.2 4643.3 4772.0 4786.0 4811.5 4836.8 4592.0 4733.2 4762.3 4775.6 4778.5 3818.4 3830.6 3834.3 3835.8 3849.4 3531.9 3560.4 3842.8 3852.3 3853.4 3757.1 3772.9 3777.4 3782.6 3783.7 3761.4 3764.7 3766.5 3768.5 3775.8 3336.4 3340.9 3342.6 3343.2 3343.9 4545.3 4658.3 4665.8 4669.7 4692.4 4281.4 4284.7 4285.6 4293.9 4304.3 4989.9 4993.0 4997.2 5004.7 5012.1 3531.8 3635.8 3640.4 3672.6 3677.3 5207.7 5213.4 5217.8 5225.8 5231.0 3678.9 3683.3 3698.9 3699.4 3701.1 3660.8 3757.8 3766.5 3773.1 3817.4 4291.4 4292.2 4295.7 4304.9 4311.2 5018.8 5024.6 5028.5 5049.2 5053.2 1205.6 1277.9 1287.4 1305.5 1317.5 894.0 900.0 922.3 922.9 932.4 4380.2 4386.2 4395.6 4418.8 4424.0 5087.8 5116.3 5122.8 5123.7 5147.0 4376.4 4407.7 4416.0 4418.1 4418.8 2303.0 2426.6 2427.7 2430.3 2447.9 4300.7 4318.2 4338.8 4346.5 4368.9 4688.8 4721.4 4721.5 4722.8 4736.5 4358.0 4545.8 4881.0 4930.5 4931.4 5188.2 5222.9 5224.9 5227.9 5250.6 5083.2 5104.8 5120.7 5122.5 5128.8 4721.0 4858.9 4900.2 4903.3 4910.5 3573.2 3584.5 3588.7 3595.8 3596.8 4971.3 5188.3 5194.4 5195.7 5198.6 4870.3 5025.6 5036.7 5036.8 5045.0 5035.5 5041.7 5047.6 5047.9 5048.6 4804.7 4947.0 4948.9 4950.2 4954.2 5027.2 5035.4 5048.9 5059.0 5079.4 4929.2 4933.3 4940.0 4947.2 4955.5 14:52:46 INFO - PROCESS | 22898 | 14:52:46 INFO - PROCESS | 22898 | [#5] yui.html Cycles:180 Average:16559.80 Median:504.50 stddev:22421.77 (4444.4%) stddev-sans-first:22452.66 14:52:46 INFO - PROCESS | 22898 | Values: 605.7 609.5 610.1 639.3 647.3 12652.8 13349.4 13728.9 13785.3 13815.0 13556.5 13632.7 13686.1 13702.2 13720.9 738.3 769.3 777.5 778.1 778.4 626.7 748.9 773.2 779.8 788.1 810.1 814.2 814.3 814.7 814.7 806.1 806.6 807.8 808.9 809.1 481.4 489.6 492.2 499.0 574.3 11990.5 12441.3 12468.9 12512.9 12517.4 12771.7 12890.0 13261.1 13388.0 13833.2 11768.7 11919.5 12097.6 12197.4 12652.9 4058.1 4069.8 4105.2 4228.3 4229.7 67200.3 67990.3 68482.0 69413.0 70156.3 9458.1 9894.2 9979.5 10108.7 10130.4 17104.6 17745.1 17837.4 18510.5 18587.9 6672.8 6801.4 6934.2 7035.0 7052.0 3702.1 3707.2 3727.0 3751.0 3767.5 2743.1 2846.0 2846.9 3314.1 3465.4 561.2 567.6 568.1 569.9 581.3 505.2 524.4 584.5 592.5 604.3 7091.0 8368.6 8589.0 8704.9 9294.3 473.9 503.8 517.9 553.3 604.0 3704.3 3773.5 3914.3 3959.2 4019.7 57327.1 57608.0 57752.0 58619.0 93732.5 71426.6 72488.3 78734.6 81614.6 83239.0 47766.8 55049.0 71631.0 74643.6 76175.6 7663.7 7853.0 7901.7 7942.4 8044.2 42321.2 42852.8 42939.4 43609.0 43820.0 57249.7 64150.0 65435.0 66204.7 67901.3 11110.3 11216.0 11401.9 11441.0 11753.9 11569.8 11826.5 11992.5 12214.0 12311.7 11274.4 11517.1 11592.9 12098.9 12521.5 7761.0 8689.6 10606.7 10824.8 11176.2 10829.0 11027.9 11497.7 11668.5 11886.4 9096.2 9291.6 9669.6 9839.9 10079.5 9839.4 9953.5 10076.4 10175.5 10196.3 14:52:46 INFO - PROCESS | 22898 | -------- Summary: end -------- 14:52:46 INFO - PROCESS | 22898 | 14:52:47 INFO - TEST-INFO | 22898: exit 0 14:52:47 INFO - TEST-OK | dromaeo_css | took 1119616ms 14:52:47 INFO - TEST-START | kraken 14:52:47 INFO - operating with platform_type : linux_ 14:52:47 INFO - Initialising browser for kraken test... 14:52:47 INFO - TEST-INFO | started process 23085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJg0m04/profile http://localhost:43179/getInfo.html) 14:52:50 INFO - PROCESS | 23085 | JavaScript error: , line 0: uncaught exception: undefined 14:52:51 INFO - PROCESS | 23085 | __metrics Screen width/height:1600/1200 14:52:51 INFO - PROCESS | 23085 | colorDepth:24 14:52:51 INFO - PROCESS | 23085 | Browser inner width/height: 1024/697 14:52:51 INFO - PROCESS | 23085 | __metrics 14:52:52 INFO - PROCESS | 23085 | 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" 14:52:52 INFO - PROCESS | 23085 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:52:52 INFO - PROCESS | 23085 | console.error: PushService: 14:52:52 INFO - PROCESS | 23085 | stateChangeProcessEnqueue: Error transitioning state 14:52:52 INFO - PROCESS | 23085 | UnknownError 14:52:53 INFO - TEST-INFO | 23085: exit 0 14:52:53 INFO - Browser initialized. 14:52:53 INFO - Running cycle 1/1 for kraken test... 14:52:53 INFO - TEST-INFO | started process 23169 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJg0m04/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:05 INFO - PROCESS | 23169 | RSS: Main: 159342592 14:53:05 INFO - PROCESS | 23169 | 14:53:08 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43179/tests/kraken/driver.html?testName=audio-beat-detection) 14:53:08 INFO - PROCESS | 23169 | RSS: Main: 215580672 14:53:08 INFO - PROCESS | 23169 | 14:53:11 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43179/tests/kraken/driver.html?testName=audio-dft) 14:53:12 INFO - PROCESS | 23169 | RSS: Main: 192434176 14:53:12 INFO - PROCESS | 23169 | 14:53:15 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43179/tests/kraken/driver.html?testName=audio-fft) 14:53:16 INFO - PROCESS | 23169 | RSS: Main: 273395712 14:53:16 INFO - PROCESS | 23169 | 14:53:18 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43179/tests/kraken/driver.html?testName=audio-oscillator) 14:53:18 INFO - PROCESS | 23169 | RSS: Main: 182128640 14:53:18 INFO - PROCESS | 23169 | 14:53:21 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43179/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:53:21 INFO - PROCESS | 23169 | RSS: Main: 186753024 14:53:21 INFO - PROCESS | 23169 | 14:53:24 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43179/tests/kraken/driver.html?testName=imaging-darkroom) 14:53:25 INFO - PROCESS | 23169 | RSS: Main: 222834688 14:53:25 INFO - PROCESS | 23169 | 14:53:28 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43179/tests/kraken/driver.html?testName=imaging-desaturate) 14:53:29 INFO - PROCESS | 23169 | RSS: Main: 222519296 14:53:29 INFO - PROCESS | 23169 | 14:53:32 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43179/tests/kraken/driver.html?testName=json-parse-financial) 14:53:33 INFO - PROCESS | 23169 | RSS: Main: 221536256 14:53:33 INFO - PROCESS | 23169 | 14:53:34 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43179/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:53:35 INFO - PROCESS | 23169 | RSS: Main: 186912768 14:53:35 INFO - PROCESS | 23169 | 14:53:37 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:53:37 INFO - PROCESS | 23169 | RSS: Main: 193331200 14:53:37 INFO - PROCESS | 23169 | 14:53:40 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:53:40 INFO - PROCESS | 23169 | RSS: Main: 196186112 14:53:40 INFO - PROCESS | 23169 | 14:53:43 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:53:44 INFO - PROCESS | 23169 | RSS: Main: 190480384 14:53:44 INFO - PROCESS | 23169 | 14:53:47 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:53:47 INFO - PROCESS | 23169 | RSS: Main: 194297856 14:53:47 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | Cycle 1(1): loaded http://localhost:43179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43179/tests/kraken/driver.html?testName=ai-astar) 14:53:49 INFO - PROCESS | 23169 | RSS: Main: 244441088 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | __start_tp_report 14:53:49 INFO - PROCESS | 23169 | _x_x_mozilla_page_load 14:53:49 INFO - PROCESS | 23169 | _x_x_mozilla_page_load_details 14:53:49 INFO - PROCESS | 23169 | |i|pagename|runs| 14:53:49 INFO - PROCESS | 23169 | |0;ai-astar;105;113;114;115;113;114;113;114;113;114 14:53:49 INFO - PROCESS | 23169 | |1;audio-beat-detection;151;130;123;176;122;126;150;123;164;127 14:53:49 INFO - PROCESS | 23169 | |2;audio-dft;161;161;161;160;161;162;160;160;160;161 14:53:49 INFO - PROCESS | 23169 | |3;audio-fft;74;77;76;125;75;79;75;75;100;76 14:53:49 INFO - PROCESS | 23169 | |4;audio-oscillator;85;87;130;85;86;85;124;85;85;123 14:53:49 INFO - PROCESS | 23169 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;116;118;119 14:53:49 INFO - PROCESS | 23169 | |6;imaging-darkroom;147;146;146;147;146;146;146;146;146;146 14:53:49 INFO - PROCESS | 23169 | |7;imaging-desaturate;95;96;95;95;96;95;96;95;95;96 14:53:49 INFO - PROCESS | 23169 | |8;json-parse-financial;62;62;64;70;62;62;63;62;62;61 14:53:49 INFO - PROCESS | 23169 | |9;json-stringify-tinderbox;65;65;66;66;67;66;65;66;66;66 14:53:49 INFO - PROCESS | 23169 | |10;stanford-crypto-aes;79;80;79;79;80;79;79;80;79;83 14:53:49 INFO - PROCESS | 23169 | |11;stanford-crypto-ccm;130;129;128;129;126;125;127;138;217;145 14:53:49 INFO - PROCESS | 23169 | |12;stanford-crypto-pbkdf2;166;164;164;162;159;163;164;163;161;161 14:53:49 INFO - PROCESS | 23169 | |13;stanford-crypto-sha256-iterative;66;67;68;67;68;66;66;65;65;65 14:53:49 INFO - PROCESS | 23169 | __end_tp_report 14:53:49 INFO - PROCESS | 23169 | __start_cc_report 14:53:49 INFO - PROCESS | 23169 | _x_x_mozilla_cycle_collect,1939 14:53:49 INFO - PROCESS | 23169 | __end_cc_report 14:53:49 INFO - PROCESS | 23169 | __startTimestamp1469224429684__endTimestamp 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | ------- Summary: start ------- 14:53:49 INFO - PROCESS | 23169 | Number of tests: 14 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:2.82 (2.5%) stddev-sans-first:0.71 14:53:49 INFO - PROCESS | 23169 | Values: 105.0 113.0 114.0 115.0 113.0 114.0 113.0 114.0 113.0 114.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:140.00 stddev:19.59 (14.0%) stddev-sans-first:20.31 14:53:49 INFO - PROCESS | 23169 | Values: 151.0 130.0 123.0 176.0 122.0 126.0 150.0 123.0 164.0 127.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 14:53:49 INFO - PROCESS | 23169 | Values: 161.0 161.0 161.0 160.0 161.0 162.0 160.0 160.0 160.0 161.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.50 stddev:16.59 (22.0%) stddev-sans-first:17.25 14:53:49 INFO - PROCESS | 23169 | Values: 74.0 77.0 76.0 125.0 75.0 79.0 75.0 75.0 100.0 76.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:85.00 stddev:19.53 (23.0%) stddev-sans-first:20.18 14:53:49 INFO - PROCESS | 23169 | Values: 85.0 87.0 130.0 85.0 86.0 85.0 124.0 85.0 85.0 123.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 14:53:49 INFO - PROCESS | 23169 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 116.0 118.0 119.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 14:53:49 INFO - PROCESS | 23169 | Values: 147.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 14:53:49 INFO - PROCESS | 23169 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:62.00 stddev:2.58 (4.2%) stddev-sans-first:2.71 14:53:49 INFO - PROCESS | 23169 | Values: 62.0 62.0 64.0 70.0 62.0 62.0 63.0 62.0 62.0 61.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 14:53:49 INFO - PROCESS | 23169 | Values: 65.0 65.0 66.0 66.0 67.0 66.0 65.0 66.0 66.0 66.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:79.50 stddev:1.25 (1.6%) stddev-sans-first:1.30 14:53:49 INFO - PROCESS | 23169 | Values: 79.0 80.0 79.0 79.0 80.0 79.0 79.0 80.0 79.0 83.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:129.50 stddev:27.95 (21.6%) stddev-sans-first:29.44 14:53:49 INFO - PROCESS | 23169 | Values: 130.0 129.0 128.0 129.0 126.0 125.0 127.0 138.0 217.0 145.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:163.50 stddev:2.00 (1.2%) stddev-sans-first:1.73 14:53:49 INFO - PROCESS | 23169 | Values: 166.0 164.0 164.0 162.0 159.0 163.0 164.0 163.0 161.0 161.0 14:53:49 INFO - PROCESS | 23169 | 14:53:49 INFO - PROCESS | 23169 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:1.16 (1.7%) stddev-sans-first:1.22 14:53:49 INFO - PROCESS | 23169 | Values: 66.0 67.0 68.0 67.0 68.0 66.0 66.0 65.0 65.0 65.0 14:53:49 INFO - PROCESS | 23169 | -------- Summary: end -------- 14:53:49 INFO - PROCESS | 23169 | 14:53:50 INFO - TEST-INFO | 23169: exit 0 14:53:50 INFO - TEST-OK | kraken | took 63659ms 14:53:50 INFO - SUITE-END | took 1183s 14:53:51 INFO - Completed test suite (00:19:43) 14:53:51 INFO - javascript benchmark 14:53:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2572.6141078838177, 2623.5277650023, 2629.6449979252793, 2635.2226093302475, 2670.5860591608293, 4616.976915115428, 4710.764461776915, 4781.18719817631, 4817.590504807091, 4818.132736107499, 4739.454962679293, 4759.690620109691, 4808.086463571927, 4817.831375901844, 4867.634927380443, 4325.956740432587, 4397.714148580332, 4408.42690450241, 4472.753998530079, 4582.908341833161, 4481.551844815525, 4518.728876267434, 4536.478304994935, 4546.909061818762, 4554.567316104982, 4334.5882141196535, 4364.869053928355, 4428.911421771579, 4431.756253406062, 4440.267355886275, 4376.496702879178, 4382.912341753179, 4390.714603550791, 4475.373447717322, 4484.372187893698, 4426.424564806587, 4437.134758722051, 4444.088961762864, 4445.599896009342, 4476.171908196994, 4665.2768820832825, 4716.0, 4777.187878060738, 4845.83039593616, 4878.341423907763, 5162.586993040548, 5174.818881339173, 5194.71429071401, 5197.610179236534, 5210.947890521122, 5160.071187186305, 5188.11801993662, 5208.5051920067535, 5219.321488206509, 5222.817201397971, 4729.148753224453, 4791.6406269529225, 4838.298446725205, 4840.7337596432535, 4859.635527335499, 5318.308619879468, 5320.334958130234, 5345.278387417727, 5377.946220537823, 5384.6499977501335, 5109.952459745743, 5111.488851114933, 5145.562627176657, 5156.510131537498, 5159.071367153948, 5050.292958985745, 5137.614678899097, 5178.0, 5184.792608295701, 5248.737563121828, 5150.201718733602, 5153.201253805666, 5155.200943853709, 5161.483851614884, 5195.584353251731, 5135.922961155586, 5141.151709967813, 5143.897122057538, 5147.948520514822, 5193.818216362485, 4141.772202528829, 4205.600467955601, 4209.494860616745, 4323.978380108079, 4358.5205627380965, 3802.524684414448, 3818.2745278397015, 3849.191669749384, 3855.325318069327, 3904.472896159039, 4475.328700694922, 4476.888077798081, 4482.058767658828, 4486.775661216926, 4529.048899731028, 5004.84985450437, 5011.574016208663, 5240.842774716764, 5252.527272545489, 5309.309789727311, 4389.275769497997, 4418.55814418562, 4423.380726698265, 4458.108378324348, 4476.037651904791, 4644.9767751161025, 4645.372874661945, 4646.8141274348645, 4653.069386122789, 4699.647526435463, 4598.839040633562, 4792.880177995438, 4815.855524334275, 4840.2255639097575, 4881.975590122027, 4955.603551715926, 5031.7484125792835, 5083.186690129562, 5128.358955130609, 5217.06092903281, 4799.616030717464, 4803.615710743202, 4804.375431193923, 4805.7597120143155, 4849.878753031202, 5075.314832497713, 5081.593472522263, 5085.7711402986215, 5086.0, 5133.640645154804, 5058.468860769566, 5151.356080489939, 5186.377634683862, 5211.478852114758, 5240.842774716764, 5091.1854103343285, 5105.157648987875, 5108.463611320758, 5110.105731496898, 5159.948400515947, 5061.164907790173, 5090.669106508065, 5096.490350964874, 5100.209467532539, 5108.9233661495855, 4763.642726795435, 4875.536824001714, 4905.681130726492, 4914.582260507754, 5000.274960130833, 5059.620528460307, 5080.568151706999, 5084.51697088776, 5085.389753229636, 5113.028524580318, 4835.226363781778, 4908.288298196813, 4910.779014944264, 4956.529129732669, 4957.03337849116], "value": 4730.2261449296275, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.6546072356585, 586.1442294250394, 588.2323567744093, 589.8171566814286, 594.6105301027828, 28556.42887142256, 29147.708522914763, 38354.23291534171, 38933.0, 39607.20785584287, 39129.60870391295, 39546.8022659887, 39623.207535849266, 39736.60263397365, 39808.80095599518, 7624.809379765508, 7781.144074151839, 7785.883211751829, 7790.571518566477, 7793.376529877624, 7602.581857997808, 7682.961585192066, 7689.653965571535, 7691.538507689528, 7693.038370203724, 7691.653875575585, 7733.304002639761, 7743.186965368612, 7745.573993430359, 7749.806254843646, 7646.655900484464, 7678.577678227695, 7691.23087691232, 7714.61426928657, 7718.228177182237, 7646.120696119961, 7694.307512323861, 7698.4226183035935, 7710.730124445618, 7713.112992005935, 37784.0, 37974.0, 38032.61967380346, 38220.70675759436, 38223.235535289416, 2866.5413533834485, 2880.971190288112, 2957.556366545014, 2974.970250297491, 3005.293755967346, 27344.726552734417, 27635.447291054268, 28027.019054333, 28039.719602803914, 29391.706082939112, 3312.1719570107475, 3363.1928337199142, 3391.796492210475, 3417.7607567470286, 3434.9656503435144, 258422.0, 258863.0, 259437.7028114866, 259779.0, 260209.0, 51177.23234151491, 51470.742646286526, 51585.48414515882, 51618.22572661413, 51656.0, 33297.66702332946, 33918.49122263168, 33941.66058339435, 54224.728876355366, 54350.45649543533, 15317.46388876384, 15461.072335659896, 15492.612684682748, 15499.612509687347, 15505.534833955, 11575.942120289345, 11658.18392712519, 11662.65012049623, 11676.357800320893, 11681.88318116808, 18162.182701778453, 18282.988871224155, 18358.806677565917, 18378.448646540626, 18497.075146242634, 6763.966180169067, 6807.897881531781, 6872.209695885037, 7020.368166865007, 7037.155541334971, 784.3489903380295, 913.6710784117712, 953.8712273842942, 1007.8639383683109, 1039.7088815131776, 27480.17559473219, 28410.573841392397, 28441.28896777597, 28540.286492837844, 28576.857115714705, 782.5031105248095, 963.9759006024765, 997.4613708597421, 1033.5245786937942, 1040.5785656809005, 14207.289635518182, 15014.624634384229, 15231.0, 15771.44799931997, 15986.68026639484, 385631.0, 387208.0, 389350.0, 389973.0, 390022.0, 49420.258696119585, 49908.750456247486, 49947.25079123816, 49948.25077623838, 50088.74955625198, 1112.2492317685692, 1189.8036823924128, 1193.8925496705338, 1223.1988047828686, 1231.1382032577233, 27465.588016180172, 28973.855130724212, 29235.0, 29250.976215832343, 29775.10674679763, 41375.7931210342, 42253.1549369017, 42286.36570451384, 42315.365269520364, 42350.576494235895, 305109.0, 307918.0, 308369.0, 310116.0, 310299.0, 22649.0, 22779.97490112982, 22872.656910146696, 23853.211009174036, 27014.729852702007, 41915.0, 41941.79029104957, 42051.36922946097, 42133.57866421419, 42308.788456057526, 35705.28589428145, 35713.821430892676, 35900.64099358973, 36295.455568167024, 36396.454053188696, 34584.48123278203, 36683.63316366909, 36725.265494689425, 37100.81449592735, 37452.43821342628, 35174.64825351714, 35439.11402214862, 35874.10314742152, 36381.27237455289, 36623.45064823976, 31490.89781857624, 33597.49603755995, 33854.153646159044, 34070.14824629404, 34154.316913662085, 25108.874455628335, 25706.37285288666, 25991.480170396866, 25992.220233392272, 26342.341441464116], "value": 17354.27898205971, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.9350323438548, 363.5909601698088, 365.5595008015341, 368.9022409061598, 384.98075096245196, 12221.877781222196, 15741.055536667795, 16191.433299834523, 16915.661686766256, 17113.486595402126, 16327.34690612377, 16677.583060423465, 16711.91644041778, 17091.82908170918, 17183.140842957833, 4951.975240123803, 5152.999999999981, 5211.244369566411, 5445.156000819879, 5482.588805839558, 3408.6761757632985, 5444.809431669893, 5479.808206712766, 5483.75323110461, 5729.598928075036, 5977.133315669225, 6032.366601506845, 6038.909416358736, 6052.364501727321, 6063.060225665007, 5839.153322768218, 5950.821475355703, 6014.488768454687, 6018.337982821886, 6031.065184896326, 5316.867078323035, 5334.1732031535175, 5338.439463856317, 5340.519353258225, 5343.278657381243, 11908.34504102274, 12047.638570842888, 12293.262404255684, 12344.259344439362, 12371.443285052104, 1919.1363886251243, 1942.2328180368675, 1964.0670681426352, 2127.638301488735, 2176.6952626632283, 8791.296696264284, 8873.511956842436, 8941.50821704806, 9063.27493800501, 9236.445813251226, 1720.2001069502617, 1725.723884178538, 1726.2059452651795, 1756.8945863248246, 1823.5441139715072, 68273.65863170702, 68818.65590672064, 69552.0, 70499.29500704979, 71048.6447567764, 9761.853572196424, 9819.06718861707, 9860.26048046385, 9939.210942030504, 10118.747031324128, 13612.93193534026, 13631.727365452636, 13633.931830340785, 13639.908807295393, 13641.86358136406, 7185.137783466018, 7432.293932076444, 7464.888026679496, 7578.12851522071, 7638.465307428427, 7113.89329160063, 7128.893066604005, 7401.925980740231, 7405.407567394595, 7409.925900741031, 4160.563140870226, 4222.176675548239, 4286.7856607169515, 4355.194289056535, 4376.277914144194, 1803.6122233719814, 1820.7906090799463, 1838.3933302010305, 1839.9540011499819, 1846.722991551278, 815.8205194857122, 886.7650072730731, 901.8241442918568, 923.8983711791698, 930.4417349590327, 15704.450344237897, 16162.272697728631, 16341.26464309109, 16508.422205223, 17219.741703874453, 820.7199454843022, 828.8715249136394, 858.948463092204, 898.3576742628921, 919.737018917323, 9100.362974591848, 9342.439453632804, 9465.621375144918, 9572.0, 9621.326507144433, 392186.0, 404017.0, 407696.23037695995, 407908.0, 408838.0, 77526.0, 81520.59239703763, 81793.0, 82303.58848205721, 83428.16571834325, 884.0893879304456, 887.8712586674895, 909.3816204980677, 909.5361365703407, 915.8580420034905, 14377.712445751236, 14777.704445910807, 15108.093514389171, 16071.35714571404, 16764.32942682328, 35308.47037294494, 37668.81165594154, 39002.414963775, 40125.59874401218, 40699.79650101731, 48931.75534122306, 51787.48212517826, 53862.46137538574, 54358.7282063603, 54592.0, 6994.895076573754, 7376.7418140364825, 7403.851922961619, 7565.470417070752, 7770.106437759504, 11110.388928609003, 11364.602238921598, 11370.431478426211, 11882.227655202385, 12038.157328987238, 9813.0, 9901.356411833207, 10018.949905250427, 10084.243681724047, 10174.491275436347, 5785.971070144622, 10163.44101074448, 10168.694939151837, 10575.524101415302, 10684.57261709523, 9674.661386851427, 9782.168515676247, 9953.0, 9992.650257241252, 10387.065164135263, 7381.224971377928, 7442.1441534222095, 7524.736634217776, 7538.849223015399, 7781.105172905189, 8283.83432331362, 9712.514374281116, 9804.36271642341, 10151.7462063449, 10216.029477199654], "value": 8097.5028793965685, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.0878862824459, 1348.2382453913538, 1412.4067891485577, 1468.9485867994615, 1675.1624187906039, 6783.694733736981, 7628.694852205912, 7867.291943725071, 7881.054273487175, 8100.189981001897, 7885.053793544768, 7943.92056079439, 7951.244631759973, 7955.641996110175, 7957.20427957204, 2113.6723807809813, 2139.272647299918, 2139.3474990128007, 2139.732533433321, 2143.024923659731, 2079.189116244666, 2111.883846388448, 2161.8054375106235, 2164.7943445372744, 2164.9783502164933, 1934.5647229373349, 1951.0537389366145, 1952.8380613534996, 1954.3257576136211, 2101.422108920044, 2074.1703318672653, 2075.647139986206, 2080.355089922129, 2080.5214800595872, 2081.781412951641, 2055.6710926251876, 2060.1862264405627, 2091.111277706984, 2093.1731965873396, 2093.7801530839347, 7913.050433947963, 7924.841503169962, 7969.043714754266, 7979.56112413817, 7981.241782030698, 4401.295792673189, 4610.585047345721, 4630.143423466669, 4685.88285292867, 4691.437027556715, 2760.5169095408423, 2827.0953294945625, 2881.4957382458197, 2893.2622181343622, 2905.912822615304, 864.8789169516273, 880.7049638371153, 885.1148851148852, 894.3069121430879, 901.9503927283997, 10504.474776261233, 10642.09542188915, 10655.573777048909, 10665.52005159762, 10677.039066483976, 3808.0, 3971.3447281198855, 4061.1065565575527, 4065.6137666921595, 4066.3900414938, 8491.830163396697, 8492.108328625529, 8493.660253589911, 8501.447405918596, 8503.234708876107, 4574.405327307495, 4579.702319349299, 4580.564846339659, 4581.725096494221, 4582.725036497821, 3702.4631428443263, 4115.156392939441, 4221.092465119953, 4223.155368926227, 4262.104957958802, 3354.4632858742484, 3373.645767194409, 3488.7906725596545, 3489.7906125632544, 3520.8415621297104, 951.3578334624101, 1002.0981116994762, 1005.0452070533023, 1013.0, 1017.7099526634873, 741.9925800741923, 783.5142211828703, 791.5646394483126, 808.623989844725, 820.4748960665179, 5149.665271757323, 5202.271681964528, 5257.737113144328, 5313.362396512443, 5431.592630552725, 376.095490345719, 391.48324212040404, 392.1959982036813, 392.2723348189068, 394.05623531641555, 3475.6350583188905, 3537.4163263061823, 3629.764065335745, 3640.2355505344176, 3681.465792838851, 9243.676471323472, 9714.3199976001, 9723.513824308757, 9736.902630973742, 9783.0706082922, 9102.681406150885, 9690.030996900394, 9697.515124243759, 9700.078492543196, 9701.07839755222, 6996.125484314461, 7350.08123984502, 7353.080864891996, 7416.629168541659, 7432.442566807403, 5360.356757189163, 5471.452854714496, 5506.559475241891, 5593.216949627137, 5617.634853734494, 8833.425827321204, 8928.464292142493, 8932.374733768575, 8969.820603588023, 9013.549322533716, 8970.86543701832, 9150.49672268005, 9184.448933064037, 9222.93936197319, 9258.444493330422, 5871.588988770745, 6570.375814297634, 6618.90071648916, 6707.993800929899, 6737.090492783412, 7574.356179724785, 7669.118051424158, 7679.731209407644, 7681.884771728317, 7682.692692292246, 6825.180978282439, 6867.622280774605, 6883.724651013903, 6892.655367231518, 7064.470164737562, 6633.2040155181685, 6657.167854018247, 6763.628000460022, 6863.485238607024, 7031.578105313698, 5827.650340979555, 5877.324107727495, 5931.0, 5935.228420305332, 5936.940630593639, 4404.053128577243, 4470.926977525435, 4577.8168873244695, 4605.424321959755, 4630.930536042029, 4536.206163921234, 4600.608948239437, 4634.536546345474, 4680.204365257846, 4850.1997170466475], "value": 3975.7456823831053, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.44523070473974, 290.1440749788125, 296.6306947849927, 300.12513523080224, 301.2093255205087, 4643.326717625942, 4772.021735544214, 4785.976070119649, 4811.518848115186, 4836.8065277388905, 4591.954080459194, 4733.195356789345, 4762.333183385813, 4775.570198682118, 4778.450478195009, 3818.369968955133, 3830.616938306161, 3834.3481608126617, 3835.7506762060516, 3849.441830934513, 3531.894043178696, 3560.376934036546, 3842.7886466244345, 3852.3451013327804, 3853.402722578005, 3757.1358587524887, 3772.9437456586224, 3777.376732839078, 3782.6217378262227, 3783.716221283401, 3761.3981762918074, 3764.738812497803, 3766.4915236443003, 3768.5289338832645, 3775.848966041355, 3336.399448099341, 3340.866365345383, 3342.59333280038, 3343.225123147806, 3343.896514150349, 4545.340925565807, 4658.34783130362, 4665.813367465297, 4669.696469729491, 4692.36653051837, 4281.422008028939, 4284.742915425085, 4285.6142947134595, 4293.9479827442165, 4304.332828411601, 4989.925151122736, 4993.0, 4997.2004479283505, 5004.699718016931, 5012.122878496285, 3531.841067151984, 3635.800030998294, 3640.381135207021, 3672.6327367263325, 3677.3380791457525, 5207.739613019372, 5213.426523082458, 5217.817376391846, 5225.790968361261, 5230.973845130788, 3678.9448158277646, 3683.2743769610297, 3698.852045918187, 3699.3537875645616, 3701.130234394915, 3660.7986560739414, 3757.755745876509, 3766.5291838520184, 3773.132179598708, 3817.3510503213965, 4291.420658211164, 4292.163028209532, 4295.72077814941, 4304.869089800569, 4311.159323931804, 5018.84943451697, 5024.572911302507, 5028.510887110903, 5049.166887463527, 5053.2420136979745, 1205.5954812643347, 1277.9297138657464, 1287.4077924154994, 1305.536534530228, 1317.4730107956702, 894.0, 900.0, 922.3082687984012, 922.9400088994194, 932.3613324872484, 4380.189664911938, 4386.232409328419, 4395.648348132142, 4418.790604697651, 4424.0, 5087.821926232638, 5116.258142569381, 5122.773711687798, 5123.6925784452305, 5146.974265128651, 4376.387305777193, 4407.735535867794, 4415.977920110379, 4418.116376724668, 4418.779061046935, 2302.9654555181687, 2426.59961106419, 2427.66012758214, 2430.343807172089, 2447.9388015299405, 4300.677449191323, 4318.222719910384, 4338.783060846945, 4346.456692913386, 4368.9126217476105, 4688.835890743808, 4721.409823772028, 4721.527847215251, 4722.811087556459, 4736.46065028867, 4357.9564204358185, 4545.795439205243, 4881.0, 4930.457649658606, 4931.408231012301, 5188.247704082812, 5222.947770522246, 5224.869378265574, 5227.947720522746, 5250.606204534599, 5083.195465609655, 5104.846854594368, 5120.667156634807, 5122.513361230677, 5128.769205385841, 4720.984988227548, 4858.927116093332, 4900.191468407816, 4903.33804936329, 4910.533499317702, 3573.1960308930284, 3584.534010578608, 3588.7308451865692, 3595.820208989592, 3596.8381422835514, 4971.304017437634, 5188.299579556711, 5194.35070616173, 5195.701074731317, 5198.610104242121, 4870.2938073979785, 5025.623078269071, 5036.748162591929, 5036.84889453317, 5045.0, 5035.4712755161645, 5041.697498150123, 5047.570956468741, 5047.939932614192, 5048.621353398583, 4804.6876952997945, 4946.975265123651, 4948.851534453972, 4950.2079667253065, 4954.157793175241, 5027.195648696337, 5035.370578677665, 5048.899022019613, 5059.0, 5079.3650793650795, 4929.2359684248995, 4933.32467311855, 4940.0, 4947.233178857283, 4955.529224723646], "value": 3761.449553628578, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.7183409714482, 609.4728060227903, 610.1153327674873, 639.3031595560841, 647.3332467558411, 12652.810207846878, 13349.399277032533, 13728.931355343222, 13785.310734463286, 13815.0, 13556.457741690345, 13632.659183520416, 13686.11040282381, 13702.246376449291, 13720.862791372108, 738.3207449146771, 769.2807225244414, 777.5062835099695, 778.0896351269, 778.4395235430482, 626.7399029402792, 748.9176190619028, 773.2113244490616, 779.7582749347692, 788.0897563314381, 810.0643756461267, 814.2386868278179, 814.2834305810878, 814.6741303478626, 814.7433558429105, 806.0569134113101, 806.6289506826866, 807.8182408957996, 808.9029316482001, 809.1018968944467, 481.4295060353492, 489.6156517134068, 492.2444048385711, 499.04433010784305, 574.2936188488142, 11990.520379184822, 12441.315727634976, 12468.937655311665, 12512.874871251353, 12517.43671534783, 12771.680707982281, 12890.0, 13261.138026028277, 13388.0, 13833.239171845544, 11768.70272484736, 11919.523219071227, 12097.57658481949, 12197.390130493528, 12652.873471265262, 4058.0869304406274, 4069.816858241357, 4105.199486100211, 4228.302330115527, 4229.72506787061, 67200.32799671989, 67990.3200967989, 68482.0, 69413.0, 70156.29843701549, 9458.101480359355, 9894.208463322917, 9979.50102494887, 10108.696739097839, 10130.392176469435, 17104.57238569046, 17745.11274436273, 17837.375691850724, 18510.537236569195, 18587.90706046488, 6672.833179170462, 6801.353871382211, 6934.202566704894, 7035.0, 7052.047973523611, 3702.148505843671, 3707.2029513654693, 3727.012341729444, 3750.9812450938116, 3767.5290588676417, 2743.1496236166854, 2845.9715402846123, 2846.9430611387656, 3314.121757734202, 3465.358908601917, 561.2255087978564, 567.5998421113105, 568.139269007454, 569.8831739493394, 581.3343721438929, 505.2421367948078, 524.3864678326368, 584.4798129664601, 592.48453845155, 604.2748701558148, 7091.0, 8368.630855272619, 8588.957055214683, 8704.869426958601, 9294.349395542247, 473.90284991576647, 503.75064343150376, 517.8787924144261, 553.2807350444406, 604.0003793721398, 3704.292369892821, 3773.52830896138, 3914.256291304643, 3959.2279505496726, 4019.678425725935, 57327.14009289864, 57608.0, 57752.0, 58619.0, 93732.53133734425, 71426.64286678533, 72488.27511724815, 78734.63796086126, 81614.59192704117, 83239.0, 47766.76116619464, 55049.0, 71631.0, 74643.62678186683, 76175.61912190403, 7663.69345226207, 7852.960735196516, 7901.68393264263, 7942.377855330068, 8044.195580442143, 42321.153576927674, 42852.78573607112, 42939.35590966201, 43609.0, 43820.0, 57249.71375143098, 64150.0, 65435.0, 66204.66897665481, 67901.3209867895, 11110.277831941221, 11216.0, 11401.942990284995, 11441.0, 11753.941230293794, 11569.768604628029, 11826.52693892269, 11992.520299187934, 12214.0, 12311.692207694521, 11274.436278185894, 11517.136214784092, 11592.942035289769, 12098.879011209776, 12521.499140034297, 7761.0, 8689.6089675966, 10606.681799546026, 10824.837627435438, 11176.217664763388, 10829.0, 11027.889721102687, 11497.655070347902, 11668.533258669559, 11886.405679715806, 9096.226820720049, 9291.581878815605, 9669.564869580745, 9839.85240221383, 10079.496025198858, 9839.409635421898, 9953.452560108974, 10076.445795481319, 10175.491225438847, 10196.286259961731], "value": 6421.7907680961225, "unit": "score"}], "name": "dromaeo_css", "value": 6321.983583964637, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 114.0, 115.0, 113.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 130.0, 123.0, 176.0, 122.0, 126.0, 150.0, 123.0, 164.0, 127.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 161.0, 162.0, 160.0, 160.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 76.0, 125.0, 75.0, 79.0, 75.0, 75.0, 100.0, 76.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 130.0, 85.0, 86.0, 85.0, 124.0, 85.0, 85.0, 123.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 118.0, 119.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 70.0, 62.0, 62.0, 63.0, 62.0, 62.0, 61.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 79.0, 80.0, 79.0, 79.0, 80.0, 79.0, 83.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 129.0, 128.0, 129.0, 126.0, 125.0, 127.0, 138.0, 217.0, 145.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 164.0, 162.0, 159.0, 163.0, 164.0, 163.0, 161.0, 161.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 67.0, 68.0, 66.0, 66.0, 65.0, 65.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1529.0000000000002, "alertThreshold": 2.0}]} 14:53:51 INFO - Return code: 0 14:53:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:53:51 INFO - # TBPL SUCCESS # 14:53:51 INFO - Running post-action listener: _resource_record_post_action 14:53:51 INFO - [mozharness: 2016-07-22 21:53:51.474506Z] Finished run-tests step (success) 14:53:51 INFO - Running post-run listener: _resource_record_post_run 14:53:51 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 552599552; Read time: 8; Write time: 436668 14:53:51 INFO - TinderboxPrint: CPU usage
13.0% 14:53:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 14:53:51 INFO - TinderboxPrint: I/O write bytes / time
552,599,552 / 436,668 14:53:51 INFO - TinderboxPrint: CPU idle
8,332.8 (87.0%) 14:53:51 INFO - TinderboxPrint: CPU user
1,199.7 (12.5%) 14:53:51 INFO - TinderboxPrint: Swap in / out
0 / 0 14:53:51 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 259661824; Read time: 0; Write time: 220296 14:53:51 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288370688; Read time: 0; Write time: 215804 14:53:52 INFO - Running post-run listener: _upload_blobber_files 14:53:52 INFO - Blob upload gear active. 14:53:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:53:52 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 14:53:52 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:53:52 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:53:52 INFO - (blobuploader) - INFO - Open directory for files ... 14:53:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:53:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:53:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:53 INFO - (blobuploader) - INFO - Done attempting. 14:53:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:53:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:05 INFO - (blobuploader) - INFO - Done attempting. 14:54:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:54:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:54:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:06 INFO - (blobuploader) - INFO - Done attempting. 14:54:06 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:06 INFO - Return code: 0 14:54:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9430d021818c5548f32a20a4578448c736b378705e5d9614989d1998b7504887765be3abb9b1a53852bc4068c9ebcc6c9a82d60bbfbdf0e9c5d870fb819e09d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9c41d263ba7a6cf54dad9a5b6a96a7dd28b20d0a0b200093a4ebfa953f3090f06139b61563ac9252696cc4714afea8b213d19abe513b0ca16a3d8c3427fcd1d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/244bb3e66423c4fbd0f095ba451f89dca75be95c7c4f9727a29f6edc73fbc0dbe899579c3391ed3dc6c2aae425b63c61416ab62c8d59d4e1f47700119240c89a"} 14:54:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:06 INFO - Contents: 14:54:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9430d021818c5548f32a20a4578448c736b378705e5d9614989d1998b7504887765be3abb9b1a53852bc4068c9ebcc6c9a82d60bbfbdf0e9c5d870fb819e09d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9c41d263ba7a6cf54dad9a5b6a96a7dd28b20d0a0b200093a4ebfa953f3090f06139b61563ac9252696cc4714afea8b213d19abe513b0ca16a3d8c3427fcd1d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/244bb3e66423c4fbd0f095ba451f89dca75be95c7c4f9727a29f6edc73fbc0dbe899579c3391ed3dc6c2aae425b63c61416ab62c8d59d4e1f47700119240c89a"} 14:54:06 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:06 INFO - Copying logs to upload dir... 14:54:06 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.160058 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-22 14:54:06.240870) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 14:54:06.245533) ========= 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=6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9430d021818c5548f32a20a4578448c736b378705e5d9614989d1998b7504887765be3abb9b1a53852bc4068c9ebcc6c9a82d60bbfbdf0e9c5d870fb819e09d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9c41d263ba7a6cf54dad9a5b6a96a7dd28b20d0a0b200093a4ebfa953f3090f06139b61563ac9252696cc4714afea8b213d19abe513b0ca16a3d8c3427fcd1d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/244bb3e66423c4fbd0f095ba451f89dca75be95c7c4f9727a29f6edc73fbc0dbe899579c3391ed3dc6c2aae425b63c61416ab62c8d59d4e1f47700119240c89a"} build_url:https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011271 build_url: 'https://queue.taskcluster.net/v1/task/J-7wSKxDTTWdCNcIf2VBSw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9430d021818c5548f32a20a4578448c736b378705e5d9614989d1998b7504887765be3abb9b1a53852bc4068c9ebcc6c9a82d60bbfbdf0e9c5d870fb819e09d6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9c41d263ba7a6cf54dad9a5b6a96a7dd28b20d0a0b200093a4ebfa953f3090f06139b61563ac9252696cc4714afea8b213d19abe513b0ca16a3d8c3427fcd1d6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/244bb3e66423c4fbd0f095ba451f89dca75be95c7c4f9727a29f6edc73fbc0dbe899579c3391ed3dc6c2aae425b63c61416ab62c8d59d4e1f47700119240c89a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 14:54:06.278578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:54:06.278966) ========= 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=6dbc10da6d83fee962c624be000001a8-1469223165.49321-1134048687 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 14:54:06.363210) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 14:54:06.363583) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 14:54:06.363973) ========= ========= Total master_lag: 0.30 =========