builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-067 starttime: 1463003313.28 results: success (0) buildid: 20160511140805 builduid: 4c66d5d41d264cf28ca32a0d23a89fa7 revision: 6581bbe179b96e153cbc54335681cfc5c9195d62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.282953) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.283418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.284008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.327566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.327823) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 14:48:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.2M=0s 2016-05-11 14:48:33 (57.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.440741) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.441029) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.010066 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.490404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:33.491172) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6581bbe179b96e153cbc54335681cfc5c9195d62 --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 6581bbe179b96e153cbc54335681cfc5c9195d62 --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=e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 14:48:33,851 truncating revision to first 12 chars 2016-05-11 14:48:33,851 Setting DEBUG logging. 2016-05-11 14:48:33,851 attempt 1/10 2016-05-11 14:48:33,851 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6581bbe179b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 14:48:34,111 unpacking tar archive at: fx-team-6581bbe179b9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.518513 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:34.018490) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:34.018758) ========= script_repo_revision: 6581bbe179b96e153cbc54335681cfc5c9195d62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:34.019144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:34.019415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 14:48:34.026304) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-11 14:48:34.026596) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:48:34 INFO - MultiFileLogger online at 20160511 14:48:34 in /builds/slave/test 14:48:34 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:48:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:48:34 INFO - {'append_to_log': False, 14:48:34 INFO - 'base_work_dir': '/builds/slave/test', 14:48:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 14:48:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:48:34 INFO - 'branch': 'Fx-Team-Non-PGO', 14:48:34 INFO - 'buildbot_json_path': 'buildprops.json', 14:48:34 INFO - 'config_files': ('talos/linux_config.py',), 14:48:34 INFO - 'default_actions': ('clobber', 14:48:34 INFO - 'read-buildbot-config', 14:48:34 INFO - 'download-and-extract', 14:48:34 INFO - 'populate-webroot', 14:48:34 INFO - 'create-virtualenv', 14:48:34 INFO - 'install', 14:48:34 INFO - 'run-tests'), 14:48:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:48:34 INFO - 'download_minidump_stackwalk': True, 14:48:34 INFO - 'download_symbols': 'ondemand', 14:48:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:48:34 INFO - 'tooltool.py': '/tools/tooltool.py', 14:48:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:48:34 INFO - '/tools/misc-python/virtualenv.py')}, 14:48:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:48:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:48:34 INFO - 'installer_path': 'installer.exe', 14:48:34 INFO - 'log_level': 'info', 14:48:34 INFO - 'log_name': 'talos', 14:48:34 INFO - 'log_to_console': True, 14:48:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:48:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:48:34 INFO - 'opt_config_files': (), 14:48:34 INFO - 'pip_index': False, 14:48:34 INFO - 'sps_profile': False, 14:48:34 INFO - 'sps_profile_interval': 0, 14:48:34 INFO - 'suite': 'dromaeojs', 14:48:34 INFO - 'system_bits': '32', 14:48:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:48:34 INFO - 'title': 'talos-linux64-ix-067', 14:48:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:48:34 INFO - 'use_talos_json': True, 14:48:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:48:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:48:34 INFO - 'work_dir': 'build'} 14:48:34 INFO - ##### 14:48:34 INFO - ##### Running clobber step. 14:48:34 INFO - ##### 14:48:34 INFO - Running pre-action listener: _resource_record_pre_action 14:48:34 INFO - Running main action method: clobber 14:48:34 INFO - rmtree: /builds/slave/test/build 14:48:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:48:34 INFO - Running post-action listener: _resource_record_post_action 14:48:34 INFO - ##### 14:48:34 INFO - ##### Finished clobber step (success) 14:48:34 INFO - ##### 14:48:34 INFO - ##### 14:48:34 INFO - ##### Running read-buildbot-config step. 14:48:34 INFO - ##### 14:48:34 INFO - Running pre-action listener: _resource_record_pre_action 14:48:34 INFO - Running main action method: read_buildbot_config 14:48:34 INFO - Using buildbot properties: 14:48:34 INFO - { 14:48:34 INFO - "project": "", 14:48:34 INFO - "product": "firefox", 14:48:34 INFO - "script_repo_revision": "production", 14:48:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:48:34 INFO - "repository": "", 14:48:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 14:48:34 INFO - "buildid": "20160511140805", 14:48:34 INFO - "pgo_build": "False", 14:48:34 INFO - "basedir": "/builds/slave/test", 14:48:34 INFO - "buildnumber": 1674, 14:48:34 INFO - "slavename": "talos-linux64-ix-067", 14:48:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:48:34 INFO - "platform": "ubuntu64_hw", 14:48:34 INFO - "scheduler": "tests-fx-team-linux64-talos", 14:48:34 INFO - "branch": "fx-team", 14:48:34 INFO - "repo_path": "integration/fx-team", 14:48:34 INFO - "revision": "6581bbe179b96e153cbc54335681cfc5c9195d62", 14:48:34 INFO - "stage_platform": "linux64", 14:48:34 INFO - "builduid": "4c66d5d41d264cf28ca32a0d23a89fa7", 14:48:34 INFO - "slavebuilddir": "test" 14:48:34 INFO - } 14:48:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:48:34 INFO - Running post-action listener: _resource_record_post_action 14:48:34 INFO - ##### 14:48:34 INFO - ##### Finished read-buildbot-config step (success) 14:48:34 INFO - ##### 14:48:34 INFO - ##### 14:48:34 INFO - ##### Running download-and-extract step. 14:48:34 INFO - ##### 14:48:34 INFO - Running pre-action listener: _resource_record_pre_action 14:48:34 INFO - Running main action method: download_and_extract 14:48:34 INFO - mkdir: /builds/slave/test/build/tests 14:48: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:48:34 INFO - https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:48:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:48:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:48:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:48:39 INFO - Downloaded 1361 bytes. 14:48:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:48:39 INFO - Using the following test package requirements: 14:48:39 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:48:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:48:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:48:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'jsshell-linux-x86_64.zip'], 14:48:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:48:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:48:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:48:39 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:48:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:48:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:48:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:48:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:48: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:48:39 INFO - https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:48:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:48:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:48:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:48:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:48:41 INFO - Downloaded 22329056 bytes. 14:48:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:48:42 INFO - Return code: 0 14:48:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:48:42 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:48:42 INFO - https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:48:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:48:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:48:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:48:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:48:50 INFO - Downloaded 22329056 bytes. 14:48:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:48:51 INFO - Return code: 0 14:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:48:51 INFO - https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:48:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:48:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:48:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:48:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:48:52 INFO - Downloaded 10840152 bytes. 14:48:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:48:52 INFO - Return code: 0 14:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:48:52 INFO - https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:48:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:48:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:48:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:48:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:48:53 INFO - Downloaded 54680285 bytes. 14:48:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:48:53 INFO - mkdir: /builds/slave/test/properties 14:48:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:48:53 INFO - Writing to file /builds/slave/test/properties/build_url 14:48:53 INFO - Contents: 14:48:53 INFO - build_url:https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:48:54 INFO - Running post-action listener: _resource_record_post_action 14:48:54 INFO - Running post-action listener: set_extra_try_arguments 14:48:54 INFO - ##### 14:48:54 INFO - ##### Finished download-and-extract step (success) 14:48:54 INFO - ##### 14:48:54 INFO - ##### 14:48:54 INFO - ##### Running populate-webroot step. 14:48:54 INFO - ##### 14:48:54 INFO - Running pre-action listener: _resource_record_pre_action 14:48:54 INFO - Running main action method: populate_webroot 14:48:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:48:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:48:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:48:54 INFO - '--tppagecycles', 14:48:54 INFO - '7'], 14:48:54 INFO - 'tests': ['tsvgm']}}, 14:48:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:48:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:48:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:48:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:48:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['damp', 'tps']}, 14:48:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'tests': ['damp', 'tps']}, 14:48:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['dromaeo_dom']}, 14:48:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:48:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['a11yr', 14:48:54 INFO - 'ts_paint', 14:48:54 INFO - 'tpaint', 14:48:54 INFO - 'sessionrestore', 14:48:54 INFO - 'sessionrestore_no_auto_restore', 14:48:54 INFO - 'tabpaint']}, 14:48:54 INFO - 'other-e10s': {'tests': ['a11yr', 14:48:54 INFO - 'ts_paint', 14:48:54 INFO - 'tpaint', 14:48:54 INFO - 'sessionrestore', 14:48:54 INFO - 'sessionrestore_no_auto_restore', 14:48:54 INFO - 'tabpaint']}, 14:48:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:48:54 INFO - 'ts_paint', 14:48:54 INFO - 'tpaint', 14:48:54 INFO - 'sessionrestore', 14:48:54 INFO - 'sessionrestore_no_auto_restore', 14:48:54 INFO - 'tabpaint']}, 14:48:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:48:54 INFO - 'ts_paint', 14:48:54 INFO - 'tpaint', 14:48:54 INFO - 'sessionrestore', 14:48:54 INFO - 'sessionrestore_no_auto_restore', 14:48:54 INFO - 'tabpaint']}, 14:48:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['a11yr', 14:48:54 INFO - 'ts_paint', 14:48:54 INFO - 'tpaint', 14:48:54 INFO - 'sessionrestore', 14:48:54 INFO - 'sessionrestore_no_auto_restore', 14:48:54 INFO - 'tabpaint']}, 14:48:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['a11yr', 14:48:54 INFO - 'ts_paint', 14:48:54 INFO - 'tpaint', 14:48:54 INFO - 'sessionrestore', 14:48:54 INFO - 'sessionrestore_no_auto_restore', 14:48:54 INFO - 'tabpaint']}, 14:48:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['tsvgx', 14:48:54 INFO - 'tsvgr_opacity', 14:48:54 INFO - 'tart', 14:48:54 INFO - 'tscrollx', 14:48:54 INFO - 'cart']}, 14:48:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:48:54 INFO - 'tsvgr_opacity', 14:48:54 INFO - 'tart', 14:48:54 INFO - 'tscrollx', 14:48:54 INFO - 'cart']}, 14:48:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'talos_options': ['--disable-e10s'], 14:48:54 INFO - 'tests': ['tp5o']}, 14:48:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'tests': ['tp5o']}, 14:48:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'talos_options': ['--disable-e10s', 14:48:54 INFO - '--xperf_path', 14:48:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:54 INFO - 'tests': ['tp5n']}, 14:48:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:48:54 INFO - 'talos_options': ['--xperf_path', 14:48:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:54 INFO - 'tests': ['tp5n']}}, 14:48:54 INFO - 'talos.zip': {'path': '', 14:48:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:48:54 INFO - Running post-action listener: _resource_record_post_action 14:48:54 INFO - ##### 14:48:54 INFO - ##### Finished populate-webroot step (success) 14:48:54 INFO - ##### 14:48:54 INFO - ##### 14:48:54 INFO - ##### Running create-virtualenv step. 14:48:54 INFO - ##### 14:48:54 INFO - Running pre-action listener: _resource_record_pre_action 14:48:54 INFO - Running main action method: create_virtualenv 14:48:54 INFO - Creating virtualenv /builds/slave/test/build/venv 14:48:54 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:48:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:48:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:48:54 INFO - Using real prefix '/usr' 14:48:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:48:55 INFO - Installing distribute.............................................................................................................................................................................................done. 14:48:57 INFO - Installing pip.................done. 14:48:57 INFO - Return code: 0 14:48:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:48:57 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:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 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:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 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 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:48:57 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:48:57 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:48:57 INFO - Using env: {'DISPLAY': ':0', 14:48:57 INFO - 'HOME': '/home/cltbld', 14:48:57 INFO - 'LANG': 'en_US.UTF-8', 14:48:57 INFO - 'LANGUAGE': 'en_US:en', 14:48:57 INFO - 'LOGNAME': 'cltbld', 14:48:57 INFO - 'MAIL': '/var/mail/cltbld', 14:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:57 INFO - 'MOZ_NO_REMOTE': '1', 14:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:57 INFO - 'NO_EM_RESTART': '1', 14:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:57 INFO - 'PWD': '/builds/slave/test', 14:48:57 INFO - 'SHELL': '/bin/bash', 14:48:57 INFO - 'SHLVL': '1', 14:48:57 INFO - 'TERM': 'linux', 14:48:57 INFO - 'TMOUT': '86400', 14:48:57 INFO - 'USER': 'cltbld', 14:48:57 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:57 INFO - '_': '/tools/buildbot/bin/python'} 14:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:57 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:48:57 INFO - Cleaning up... 14:48:57 INFO - Return code: 0 14:48:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:48:57 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:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 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:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:48:57 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>=0.7.1'] in /builds/slave/test/build 14:48:57 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>=0.7.1 14:48:57 INFO - Using env: {'DISPLAY': ':0', 14:48:57 INFO - 'HOME': '/home/cltbld', 14:48:57 INFO - 'LANG': 'en_US.UTF-8', 14:48:57 INFO - 'LANGUAGE': 'en_US:en', 14:48:57 INFO - 'LOGNAME': 'cltbld', 14:48:57 INFO - 'MAIL': '/var/mail/cltbld', 14:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:57 INFO - 'MOZ_NO_REMOTE': '1', 14:48:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:57 INFO - 'NO_EM_RESTART': '1', 14:48:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:57 INFO - 'PWD': '/builds/slave/test', 14:48:57 INFO - 'SHELL': '/bin/bash', 14:48:57 INFO - 'SHLVL': '1', 14:48:57 INFO - 'TERM': 'linux', 14:48:57 INFO - 'TMOUT': '86400', 14:48:57 INFO - 'USER': 'cltbld', 14:48:57 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:57 INFO - '_': '/tools/buildbot/bin/python'} 14:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:57 INFO - Downloading/unpacking psutil>=0.7.1 14:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:57 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:48:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:48:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:59 INFO - Installing collected packages: psutil 14:48:59 INFO - Running setup.py install for psutil 14:48:59 INFO - building 'psutil._psutil_linux' extension 14:48:59 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:48:59 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:48:59 INFO - building 'psutil._psutil_posix' extension 14:48:59 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:48:59 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:48:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:48:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:48:59 INFO - Successfully installed psutil 14:48:59 INFO - Cleaning up... 14:48:59 INFO - Return code: 0 14:48:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:48:59 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:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:59 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:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:59 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:48:59 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.0.0'] in /builds/slave/test/build 14:48:59 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.0.0 14:48:59 INFO - Using env: {'DISPLAY': ':0', 14:48:59 INFO - 'HOME': '/home/cltbld', 14:48:59 INFO - 'LANG': 'en_US.UTF-8', 14:48:59 INFO - 'LANGUAGE': 'en_US:en', 14:48:59 INFO - 'LOGNAME': 'cltbld', 14:48:59 INFO - 'MAIL': '/var/mail/cltbld', 14:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:59 INFO - 'MOZ_NO_REMOTE': '1', 14:48:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:48:59 INFO - 'NO_EM_RESTART': '1', 14:48:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:59 INFO - 'PWD': '/builds/slave/test', 14:48:59 INFO - 'SHELL': '/bin/bash', 14:48:59 INFO - 'SHLVL': '1', 14:48:59 INFO - 'TERM': 'linux', 14:48:59 INFO - 'TMOUT': '86400', 14:48:59 INFO - 'USER': 'cltbld', 14:48:59 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:59 INFO - '_': '/tools/buildbot/bin/python'} 14:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:00 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:49:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:49:01 INFO - Installing collected packages: mozsystemmonitor 14:49:01 INFO - Running setup.py install for mozsystemmonitor 14:49:02 INFO - Successfully installed mozsystemmonitor 14:49:02 INFO - Cleaning up... 14:49:02 INFO - Return code: 0 14:49:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:49:02 INFO - Using env: {'DISPLAY': ':0', 14:49:02 INFO - 'HOME': '/home/cltbld', 14:49:02 INFO - 'LANG': 'en_US.UTF-8', 14:49:02 INFO - 'LANGUAGE': 'en_US:en', 14:49:02 INFO - 'LOGNAME': 'cltbld', 14:49:02 INFO - 'MAIL': '/var/mail/cltbld', 14:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:02 INFO - 'MOZ_NO_REMOTE': '1', 14:49:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:02 INFO - 'NO_EM_RESTART': '1', 14:49:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:02 INFO - 'PWD': '/builds/slave/test', 14:49:02 INFO - 'SHELL': '/bin/bash', 14:49:02 INFO - 'SHLVL': '1', 14:49:02 INFO - 'TERM': 'linux', 14:49:02 INFO - 'TMOUT': '86400', 14:49:02 INFO - 'USER': 'cltbld', 14:49:02 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:02 INFO - '_': '/tools/buildbot/bin/python'} 14:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:02 INFO - Downloading/unpacking blobuploader==1.2.4 14:49:02 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:49:02 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:49:02 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:49:02 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:49:02 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:49:02 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:49:04 INFO - Downloading blobuploader-1.2.4.tar.gz 14:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:49:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:49:04 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:49:04 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:49:04 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:49:04 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:49:04 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:49:04 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:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:49:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:49:04 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:49:04 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:49:04 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:49:04 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:49:04 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:49:04 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:49:04 INFO - Downloading docopt-0.6.1.tar.gz 14:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:49:04 INFO - Installing collected packages: blobuploader, requests, docopt 14:49:04 INFO - Running setup.py install for blobuploader 14:49:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:49:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:49:04 INFO - Running setup.py install for requests 14:49:05 INFO - Running setup.py install for docopt 14:49:05 INFO - Successfully installed blobuploader requests docopt 14:49:05 INFO - Cleaning up... 14:49:05 INFO - Return code: 0 14:49:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:05 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:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:05 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:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:05 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 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:49:05 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:49:05 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:49:05 INFO - Using env: {'DISPLAY': ':0', 14:49:05 INFO - 'HOME': '/home/cltbld', 14:49:05 INFO - 'LANG': 'en_US.UTF-8', 14:49:05 INFO - 'LANGUAGE': 'en_US:en', 14:49:05 INFO - 'LOGNAME': 'cltbld', 14:49:05 INFO - 'MAIL': '/var/mail/cltbld', 14:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:05 INFO - 'MOZ_NO_REMOTE': '1', 14:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:05 INFO - 'NO_EM_RESTART': '1', 14:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:05 INFO - 'PWD': '/builds/slave/test', 14:49:05 INFO - 'SHELL': '/bin/bash', 14:49:05 INFO - 'SHLVL': '1', 14:49:05 INFO - 'TERM': 'linux', 14:49:05 INFO - 'TMOUT': '86400', 14:49:05 INFO - 'USER': 'cltbld', 14:49:05 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:05 INFO - '_': '/tools/buildbot/bin/python'} 14:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:49:05 INFO - Running setup.py (path:/tmp/pip-ROO_8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:49:05 INFO - Running setup.py (path:/tmp/pip-85UJFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:49:05 INFO - Running setup.py (path:/tmp/pip-y7tteJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:49:05 INFO - Running setup.py (path:/tmp/pip-98EeLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:49:05 INFO - Running setup.py (path:/tmp/pip-ESsHCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:49:05 INFO - Running setup.py (path:/tmp/pip-0gB5wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:49:05 INFO - Running setup.py (path:/tmp/pip-__xUp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:49:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:49:05 INFO - Running setup.py (path:/tmp/pip-o_o_O3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:49:06 INFO - Running setup.py (path:/tmp/pip-TMcTEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:49:06 INFO - Running setup.py (path:/tmp/pip-ajRx8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:49:06 INFO - Running setup.py (path:/tmp/pip-LWLBG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:49:06 INFO - Running setup.py (path:/tmp/pip-Fo3TWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:49:06 INFO - Running setup.py (path:/tmp/pip-HVfjcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:49:06 INFO - Running setup.py (path:/tmp/pip-o8wv9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:06 INFO - Running setup.py (path:/tmp/pip-XZ5G3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:49:06 INFO - Running setup.py (path:/tmp/pip-ugY0r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:49:06 INFO - Running setup.py (path:/tmp/pip-axSxP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:49:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:49:06 INFO - Running setup.py install for manifestparser 14:49:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:49:06 INFO - Running setup.py install for mozcrash 14:49:06 INFO - Running setup.py install for mozdebug 14:49:06 INFO - Running setup.py install for mozdevice 14:49:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:49:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:49:06 INFO - Running setup.py install for mozfile 14:49:07 INFO - Running setup.py install for mozhttpd 14:49:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for mozinfo 14:49:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for mozInstall 14:49:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for mozleak 14:49:07 INFO - Running setup.py install for mozlog 14:49:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for moznetwork 14:49:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for mozprocess 14:49:07 INFO - Running setup.py install for mozprofile 14:49:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for mozrunner 14:49:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:49:07 INFO - Running setup.py install for mozscreenshot 14:49:07 INFO - Running setup.py install for moztest 14:49:08 INFO - Running setup.py install for mozversion 14:49:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:49:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:49:08 INFO - Cleaning up... 14:49:08 INFO - Return code: 0 14:49:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:08 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 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:49:08 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:49:08 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:49:08 INFO - Using env: {'DISPLAY': ':0', 14:49:08 INFO - 'HOME': '/home/cltbld', 14:49:08 INFO - 'LANG': 'en_US.UTF-8', 14:49:08 INFO - 'LANGUAGE': 'en_US:en', 14:49:08 INFO - 'LOGNAME': 'cltbld', 14:49:08 INFO - 'MAIL': '/var/mail/cltbld', 14:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:08 INFO - 'MOZ_NO_REMOTE': '1', 14:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:08 INFO - 'NO_EM_RESTART': '1', 14:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:08 INFO - 'PWD': '/builds/slave/test', 14:49:08 INFO - 'SHELL': '/bin/bash', 14:49:08 INFO - 'SHLVL': '1', 14:49:08 INFO - 'TERM': 'linux', 14:49:08 INFO - 'TMOUT': '86400', 14:49:08 INFO - 'USER': 'cltbld', 14:49:08 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:08 INFO - '_': '/tools/buildbot/bin/python'} 14:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:49:08 INFO - Running setup.py (path:/tmp/pip-RUWEPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:49:08 INFO - Running setup.py (path:/tmp/pip-Ej94n4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:49:08 INFO - Running setup.py (path:/tmp/pip-Mk3YeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:49:08 INFO - Running setup.py (path:/tmp/pip-wso6et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:49:08 INFO - Running setup.py (path:/tmp/pip-AnwI8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:49:08 INFO - Running setup.py (path:/tmp/pip-mCSDtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:49:08 INFO - Running setup.py (path:/tmp/pip-_IITr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:49:08 INFO - Running setup.py (path:/tmp/pip-W1HySM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:49:08 INFO - Running setup.py (path:/tmp/pip-912Dd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:49:08 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:49:08 INFO - Running setup.py (path:/tmp/pip-SgNB_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:49:08 INFO - Running setup.py (path:/tmp/pip-SrlV0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:49:09 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:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:49:09 INFO - Running setup.py (path:/tmp/pip-s9dl9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:49:09 INFO - Running setup.py (path:/tmp/pip-Pmclp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:49:09 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:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:49:09 INFO - Running setup.py (path:/tmp/pip-LpWMIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:49:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:09 INFO - Running setup.py (path:/tmp/pip-8phvPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:09 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:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:49:09 INFO - Running setup.py (path:/tmp/pip-PvZnKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:49:09 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:49:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:49:09 INFO - Running setup.py (path:/tmp/pip-zMdRIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:09 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:49:11 INFO - Downloading blessings-1.6.tar.gz 14:49:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:49:11 INFO - Installing collected packages: blessings 14:49:11 INFO - Running setup.py install for blessings 14:49:11 INFO - Successfully installed blessings 14:49:11 INFO - Cleaning up... 14:49:11 INFO - Return code: 0 14:49:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:49:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:11 INFO - Reading from file tmpfile_stdout 14:49:11 INFO - Current package versions: 14:49:11 INFO - argparse == 1.2.1 14:49:11 INFO - blessings == 1.6 14:49:11 INFO - blobuploader == 1.2.4 14:49:11 INFO - docopt == 0.6.1 14:49:11 INFO - manifestparser == 1.1 14:49:11 INFO - mozInstall == 1.12 14:49:11 INFO - mozcrash == 0.17 14:49:11 INFO - mozdebug == 0.1 14:49:11 INFO - mozdevice == 0.48 14:49:11 INFO - mozfile == 1.2 14:49:11 INFO - mozhttpd == 0.7 14:49:11 INFO - mozinfo == 0.9 14:49:11 INFO - mozleak == 0.1 14:49:11 INFO - mozlog == 3.1 14:49:11 INFO - moznetwork == 0.27 14:49:11 INFO - mozprocess == 0.22 14:49:11 INFO - mozprofile == 0.28 14:49:11 INFO - mozrunner == 6.11 14:49:11 INFO - mozscreenshot == 0.1 14:49:11 INFO - mozsystemmonitor == 0.0 14:49:11 INFO - moztest == 0.7 14:49:11 INFO - mozversion == 1.4 14:49:11 INFO - psutil == 3.1.1 14:49:11 INFO - requests == 1.2.3 14:49:11 INFO - wsgiref == 0.1.2 14:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:11 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 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:49:11 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:49:11 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:49:11 INFO - Using env: {'DISPLAY': ':0', 14:49:11 INFO - 'HOME': '/home/cltbld', 14:49:11 INFO - 'LANG': 'en_US.UTF-8', 14:49:11 INFO - 'LANGUAGE': 'en_US:en', 14:49:11 INFO - 'LOGNAME': 'cltbld', 14:49:11 INFO - 'MAIL': '/var/mail/cltbld', 14:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:11 INFO - 'MOZ_NO_REMOTE': '1', 14:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:11 INFO - 'NO_EM_RESTART': '1', 14:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:11 INFO - 'PWD': '/builds/slave/test', 14:49:11 INFO - 'SHELL': '/bin/bash', 14:49:11 INFO - 'SHLVL': '1', 14:49:11 INFO - 'TERM': 'linux', 14:49:11 INFO - 'TMOUT': '86400', 14:49:11 INFO - 'USER': 'cltbld', 14:49:11 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:11 INFO - '_': '/tools/buildbot/bin/python'} 14:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 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:49:11 INFO - Cleaning up... 14:49:11 INFO - Return code: 0 14:49:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:49:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:49:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:49:11 INFO - Using env: {'DISPLAY': ':0', 14:49:11 INFO - 'HOME': '/home/cltbld', 14:49:11 INFO - 'LANG': 'en_US.UTF-8', 14:49:11 INFO - 'LANGUAGE': 'en_US:en', 14:49:11 INFO - 'LOGNAME': 'cltbld', 14:49:11 INFO - 'MAIL': '/var/mail/cltbld', 14:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:11 INFO - 'MOZ_NO_REMOTE': '1', 14:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:11 INFO - 'NO_EM_RESTART': '1', 14:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:11 INFO - 'PWD': '/builds/slave/test', 14:49:11 INFO - 'SHELL': '/bin/bash', 14:49:11 INFO - 'SHLVL': '1', 14:49:11 INFO - 'TERM': 'linux', 14:49:11 INFO - 'TMOUT': '86400', 14:49:11 INFO - 'USER': 'cltbld', 14:49:11 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:11 INFO - '_': '/tools/buildbot/bin/python'} 14:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:12 INFO - Downloading/unpacking jsonschema 14:49:12 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:49:12 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:49:12 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:49:12 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:49:12 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:49:12 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:49:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:49:13 INFO - Downloading/unpacking functools32 (from jsonschema) 14:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:14 INFO - Downloading functools32-3.2.3-2.tar.gz 14:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:49:14 INFO - warning: no files found matching '*.txt' 14:49:14 INFO - no previously-included directories found matching 'build' 14:49:14 INFO - no previously-included directories found matching 'dist' 14:49:14 INFO - no previously-included directories found matching '.git*' 14:49:14 INFO - Installing collected packages: jsonschema, functools32 14:49:14 INFO - Running setup.py install for functools32 14:49:14 INFO - warning: no files found matching '*.txt' 14:49:14 INFO - no previously-included directories found matching 'build' 14:49:14 INFO - no previously-included directories found matching 'dist' 14:49:14 INFO - no previously-included directories found matching '.git*' 14:49:14 INFO - Successfully installed jsonschema functools32 14:49:14 INFO - Cleaning up... 14:49:14 INFO - Return code: 0 14:49:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:49:14 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:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:14 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:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:14 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f11d51b3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c03770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', '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:49:14 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', 'flake8'] in /builds/slave/test/build 14:49:14 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 flake8 14:49:14 INFO - Using env: {'DISPLAY': ':0', 14:49:14 INFO - 'HOME': '/home/cltbld', 14:49:14 INFO - 'LANG': 'en_US.UTF-8', 14:49:14 INFO - 'LANGUAGE': 'en_US:en', 14:49:14 INFO - 'LOGNAME': 'cltbld', 14:49:14 INFO - 'MAIL': '/var/mail/cltbld', 14:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:14 INFO - 'MOZ_NO_REMOTE': '1', 14:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:14 INFO - 'NO_EM_RESTART': '1', 14:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:14 INFO - 'PWD': '/builds/slave/test', 14:49:14 INFO - 'SHELL': '/bin/bash', 14:49:14 INFO - 'SHLVL': '1', 14:49:14 INFO - 'TERM': 'linux', 14:49:14 INFO - 'TMOUT': '86400', 14:49:14 INFO - 'USER': 'cltbld', 14:49:14 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:14 INFO - '_': '/tools/buildbot/bin/python'} 14:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:14 INFO - Downloading/unpacking flake8 14:49:14 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:49:14 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:49:14 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:49:14 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:49:14 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:49:14 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:49:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:49:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:49:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:49:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:49:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:49:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:49:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:49:17 INFO - no previously-included directories found matching 'docs/_build' 14:49:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:49:17 INFO - Running setup.py install for pep8 14:49:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:49:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:49:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:49:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:49:17 INFO - no previously-included directories found matching 'docs/_build' 14:49:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:49:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:49:17 INFO - Cleaning up... 14:49:17 INFO - Return code: 0 14:49:17 INFO - Running post-action listener: _resource_record_post_action 14:49:17 INFO - Running post-action listener: _start_resource_monitoring 14:49:17 INFO - Starting resource monitoring. 14:49:17 INFO - ##### 14:49:17 INFO - ##### Finished create-virtualenv step (success) 14:49:17 INFO - ##### 14:49:17 INFO - ##### 14:49:17 INFO - ##### Running install step. 14:49:17 INFO - ##### 14:49:17 INFO - Running pre-action listener: _resource_record_pre_action 14:49:17 INFO - Running main action method: install 14:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:17 INFO - Reading from file tmpfile_stdout 14:49:17 INFO - Detecting whether we're running mozinstall >=1.0... 14:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:49:17 INFO - Reading from file tmpfile_stdout 14:49:17 INFO - Output received: 14:49:17 INFO - Usage: mozinstall [options] installer 14:49:17 INFO - Options: 14:49:17 INFO - -h, --help show this help message and exit 14:49:17 INFO - -d DEST, --destination=DEST 14:49:17 INFO - Directory to install application into. [default: 14:49:17 INFO - "/builds/slave/test"] 14:49:17 INFO - --app=APP Application being installed. [default: firefox] 14:49:17 INFO - mkdir: /builds/slave/test/build/application 14:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:49:33 INFO - Reading from file tmpfile_stdout 14:49:33 INFO - Output received: 14:49:33 INFO - /builds/slave/test/build/application/firefox/firefox 14:49:33 INFO - Running post-action listener: _resource_record_post_action 14:49:33 INFO - ##### 14:49:33 INFO - ##### Finished install step (success) 14:49:33 INFO - ##### 14:49:33 INFO - ##### 14:49:33 INFO - ##### Running run-tests step. 14:49:33 INFO - ##### 14:49:33 INFO - Running pre-action listener: _resource_record_pre_action 14:49:33 INFO - Running main action method: run_tests 14:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:49:33 INFO - Python 2.7.3 14:49:33 INFO - Return code: 0 14:49:33 INFO - grabbing minidump binary from tooltool 14:49:33 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:49:33 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 0x1c929c0>, '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 0x1c938c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c93d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:49:33 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:49:33 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:49:33 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:49:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:49:33 INFO - Return code: 0 14:49:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:49:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:49:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:49:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:49:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:49:34 INFO - Return code: 0 14:49:34 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/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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:49:34 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/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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:49:34 INFO - Using env: {'DISPLAY': ':0', 14:49:34 INFO - 'HOME': '/home/cltbld', 14:49:34 INFO - 'LANG': 'en_US.UTF-8', 14:49:34 INFO - 'LANGUAGE': 'en_US:en', 14:49:34 INFO - 'LOGNAME': 'cltbld', 14:49:34 INFO - 'MAIL': '/var/mail/cltbld', 14:49:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:49:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:34 INFO - 'MOZ_NO_REMOTE': '1', 14:49:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:49:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:49:34 INFO - 'NO_EM_RESTART': '1', 14:49:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:34 INFO - 'PWD': '/builds/slave/test', 14:49:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:49:34 INFO - 'SHELL': '/bin/bash', 14:49:34 INFO - 'SHLVL': '1', 14:49:34 INFO - 'TERM': 'linux', 14:49:34 INFO - 'TMOUT': '86400', 14:49:34 INFO - 'USER': 'cltbld', 14:49:34 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479', 14:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:34 INFO - '_': '/tools/buildbot/bin/python'} 14:49:34 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/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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:49:34 INFO - mozversion application_buildid: 20160511140805 14:49:34 INFO - mozversion application_changeset: 6581bbe179b96e153cbc54335681cfc5c9195d62 14:49:34 INFO - mozversion application_display_name: Nightly 14:49:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:49:34 INFO - mozversion application_name: Firefox 14:49:34 INFO - mozversion application_remotingname: firefox 14:49:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:49:34 INFO - mozversion application_vendor: Mozilla 14:49:34 INFO - mozversion application_version: 49.0a1 14:49:34 INFO - mozversion platform_buildid: 20160511140805 14:49:34 INFO - mozversion platform_changeset: 6581bbe179b96e153cbc54335681cfc5c9195d62 14:49:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:49:34 INFO - mozversion platform_version: 49.0a1 14:49:34 INFO - using testdate: 1463003374 14:49:34 INFO - actual date: 1463003374 14:49:34 INFO - starting webserver on 'localhost:52924' 14:49:34 INFO - SUITE-START | Running 2 tests 14:49:34 INFO - TEST-START | dromaeo_css 14:49:34 INFO - operating with platform_type : linux_ 14:49:34 INFO - Initialising browser for dromaeo_css test... 14:49:34 INFO - TEST-INFO | started process 27139 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp37sxyz/profile http://localhost:52924/getInfo.html) 14:49:38 INFO - PROCESS | 27139 | __metrics Screen width/height:1600/1200 14:49:38 INFO - PROCESS | 27139 | colorDepth:24 14:49:38 INFO - PROCESS | 27139 | Browser inner width/height: 1024/697 14:49:38 INFO - PROCESS | 27139 | __metrics 14:49:38 ERROR - PROCESS | 27139 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:49:39 INFO - TEST-INFO | 27139: exit 0 14:49:39 INFO - Browser initialized. 14:49:39 INFO - Running cycle 1/1 for dromaeo_css test... 14:49:39 INFO - TEST-INFO | started process 27217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp37sxyz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:49:51 INFO - PROCESS | 27217 | RSS: Main: 153161728 14:49:51 INFO - PROCESS | 27217 | 14:50:42 INFO - PROCESS | 27217 | 1463003442634 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:52:54 INFO - PROCESS | 27217 | Cycle 1(1): loaded http://localhost:52924/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52924/tests/dromaeo/cssquery-ext.html) 14:52:55 INFO - PROCESS | 27217 | RSS: Main: 261636096 14:52:55 INFO - PROCESS | 27217 | 14:55:58 INFO - PROCESS | 27217 | Cycle 1(1): loaded http://localhost:52924/tests/dromaeo/cssquery-ext.html (next: http://localhost:52924/tests/dromaeo/cssquery-jquery.html) 14:55:58 INFO - PROCESS | 27217 | RSS: Main: 237252608 14:55:58 INFO - PROCESS | 27217 | 14:59:00 INFO - PROCESS | 27217 | Cycle 1(1): loaded http://localhost:52924/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52924/tests/dromaeo/cssquery-mootools.html) 14:59:01 INFO - PROCESS | 27217 | RSS: Main: 240828416 14:59:01 INFO - PROCESS | 27217 | 15:02:03 INFO - PROCESS | 27217 | Cycle 1(1): loaded http://localhost:52924/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52924/tests/dromaeo/cssquery-prototype.html) 15:02:03 INFO - PROCESS | 27217 | RSS: Main: 238702592 15:02:03 INFO - PROCESS | 27217 | 15:05:06 INFO - PROCESS | 27217 | Cycle 1(1): loaded http://localhost:52924/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52924/tests/dromaeo/cssquery-yui.html) 15:05:07 INFO - PROCESS | 27217 | RSS: Main: 214990848 15:05:07 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | Cycle 1(1): loaded http://localhost:52924/tests/dromaeo/cssquery-yui.html (next: http://localhost:52924/tests/dromaeo/cssquery-dojo.html) 15:08:10 INFO - PROCESS | 27217 | RSS: Main: 285741056 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | __start_tp_report 15:08:10 INFO - PROCESS | 27217 | _x_x_mozilla_page_load 15:08:10 INFO - PROCESS | 27217 | _x_x_mozilla_page_load_details 15:08:10 INFO - PROCESS | 27217 | |i|pagename|runs| 15:08:10 INFO - PROCESS | 27217 | |0;dojo.html;2623.3179373362927;2638.51187530307;2679.5980602909567;2683.4096498770277;2686.543287641101;4562.224421848286;4609.2855607380825;4722.858314250574;4783.043391321737;4841.3222148899185;4633.1891918914225;4652.97673511632;4662.603678687307;4708.858734237979;4758.238681810911;4213.452251207339;4224.176285624312;4309.762963037032;4335.9566404335865;4408.779561021952;4370.650347972155;4386.9561304387025;4405.603495685387;4416.072624748806;4446.444194475691;4201.222773786829;4246.384274280243;4277.743335399886;4296.63478604319;4326.9567304326865;4310.827566897352;4358.3244597087505;4359.302511598129;4383.978080109579;4416.447944006999;4344.695871289011;4352.912941741148;4363.040131171138;4380.737155770632;4440.955590444087;4528.230200865861;4557.635389168859;4595.609373203281;4651.976740116312;4699.436067671867;5008.949910500921;5063.974680126613;5102.438731739506;5111.514406131411;5116.309298244727;5037.949620503785;5038.445770965191;5048.899022019576;5095.133827249339;5112.974435127838;4580.129775342708;4727.550882666142;4762.238041913277;4792.616590672737;4800.807967681254;5193.246979187997;5219.634625576249;5257.553107985787;5261.710605916712;5273.6572122811895;4997.275395067768;5008.499150084962;5009.674371165936;5075.416327122354;5102.979404119117;4841.983183531498;4878.682885612424;4895.412550493964;4924.015196960622;4930.852074437773;4852.441969173519;4948.579370753526;5064.873378165502;5068.82259120929;5087.694738315713;5054.873628159252;5080.339555857715;5092.668976516515;5100.285959965608;5162.45794191612;4195.538490766014;4331.0904710011255;4355.932796464886;4369.169857727022;4419.513853476115;3745.363288240951;3761.39817629178;3786.6024067472517;3821.407681809324;3837.4435706822915;4346.913061738811;4386.495553011381;4387.956120438755;4415.690901636919;4423.203823311835;4849.709017458893;5067.746612669425;5094.2358646203365;5100.923486147711;5113.181890897513;4212.789360531961;4258.276093064186;4295.119500502389;4319.136172765459;4331.393604895317;4536.160810250114;4537.069900670421;4566.954330456653;4595.632349412106;4642.908916404642;4552.772361381852;4655.813767449264;4838.588719958772;4839.346688197048;4851.369321988119;4924.581410580141;4937.580305674058;4958.6281028923695;4959.156943319716;5021.046001259749;4675.883102922318;4682.555157260055;4691.695039822401;4703.576678098988;4704.317873908196;4857.465678775261;4885.169521181337;4899.534544218293;4922.630802689884;4939.53074457926;5040.722760248222;5060.924086138784;5083.771230294719;5105.846824595268;5110.386753589593;4957.206846904478;4965.304857319906;4986.7257300848805;4994.475580064186;4996.975015124901;4688.601468875047;4892.804287828447;4943.307936888767;4944.728039957837;4983.20853752578;4820.084184005028;4857.1742803724155;4869.488703686204;4918.4343800461975;4955.331030310862;4987.750612469435;4999.8750031248055;5027.522385373383;5040.294358789699;5043.117454445531;4743.739094349844;4756.833510827105;4805.519448055306;4843.98276361968;4862.05189987949 15:08:10 INFO - PROCESS | 27217 | |1;ext.html;555.8526990347559;562.2887047884425;578.7829563913532;593.5815250248576;596.263614436171;25050.123245686384;40548.39177412341;41879;42120.78939605306;45797.626071217834;42681;42810.14379712404;43098.35352469708;43201.783991080076;43427.3485897711;7204.567725936435;7778.455508114448;7898.368130549542;7936.44444888858;7951.244631759973;9777.413355198676;10036.74908127299;10098.89901100987;10323.070923616873;10343.172546196287;10027.097561219489;10080.94959525205;10106.191504679608;10132.54403551842;10143;7440.516366436218;9764.462954537497;9906.603735850558;9990.400575965428;10095;9845.803083938352;9956.850647240297;9970.501474926297;9971.601135954554;10015.298929074968;37926.43110353347;38162.80918595389;38188.04529886747;38321.23357532862;38386.61613383859;3106.409782141386;3164.68353164684;3214.3249917517114;3230.4669729494603;3269.673032696735;29141;29718.257043573867;29803.25491862699;29940.850295748383;30636.846815766003;3197.1527545200306;3209.422303985282;3279.0982479818003;3290.440625093716;3343.7157841583494;245773;273589;276590;278659;370536.14731926436;57090.71454642783;57507;57818;57867.71066144642;57963.710181449664;34483.82758086194;35610.28779424397;35641.28717425637;60752.392476075554;61003.694981525696;15797.447089351817;15818.9209053954;15831.525054248392;15867.761983570255;15997.120158391168;10186.847197292047;10337.63818266372;11189.776204476027;11498.540058397737;11518.769624607461;18804.153813078443;18829.870207787575;18966.905165474083;18981.145848436852;18983.145758440904;6989.720411183597;7024.473164512682;7143.964125201921;7161.319674630901;7308.086489188851;874.3748220022715;938.4791440750313;963.5037955452947;965.1988849255101;988.5008070924281;28079.859600701864;28942.13173604795;29214.26964325909;29437.705622943926;29836.40327193444;943.4669411782321;973.4013581647378;984.532347135105;1019.0268293779422;1053.210092430677;15386.461473848585;15493.225338733018;15574.532764017098;15696;15826.28781704826;360359;362230;363758;366108;367601;51621.22568161481;51898.740506297225;52293.47706522886;52415.47584524106;52472.737636311576;1156.1906665334132;1251.2617555642214;1252.5616034387892;1253.4610117649497;1269.0862578943147;25305.87347063253;29344.706552934196;29700.405991880474;29958.850205748833;29990.700092999665;42293.577064228964;42880.57119428766;43016.784916075216;43017;43048.56951430446;268536;270079;385305;397834.1840943552;398882;35749.64250357463;38100.61899380971;38119.80940095282;38948.41577376398;40038.599614003484;42903.14193716171;43016;43325.133497330506;43398.34902476529;43439.56560434355;36432.08919777027;36663.63336366602;37398.252034958605;37618.811905940296;37834.62165378311;37255.81372093122;37374;37380.439293410076;37382.62617373792;37442;36431.27137457289;36764.26471470609;36769.63230367769;37381.6261837389;37641.62358376491;32268;33015;33354.83322583372;33736;33963.66036339605;24637.38406539851;25139.371515712253;25197.11810086638;25584.488310234065;25687 15:08:10 INFO - PROCESS | 27217 | |2;jquery.html;373.99209131390893;381.66032231314125;385.69722767627417;387.5465705125004;400.3374415342609;16491.422800201995;16947;17568.29726810926;17568.385106521273;17797.466076017725;16325.510234692949;16822.663546729058;16850.83149168508;17109.657806843854;17199.656006879886;4777.570018698317;4916.115099282128;5125.923111153317;5141.82003629873;5184.818531351403;3641.617630148836;5702.885942281152;6030.788922387717;6034.939650603504;6186.81439556814;6029.336772954972;6074.119252708355;6082;6108.3280839107665;6406.519511036668;6766.3233676632435;6869.381755641966;6895.620740859251;6903.723851045952;6951.4786391020625;3899.200663863901;6176.413240742122;6587.044878492592;6595.406413422767;6695.899561506581;22902.541949160925;22984;23565;24012.159574414985;24084.27747167588;1929.2861641192849;2163.816075633573;2360.4216966843233;2503.524330377241;2587.1721049264243;12462.252264864137;14078.732914037786;14386.496472623408;14595.635109122357;14804.40782368704;1753.6317373351612;1811.5018369948239;1896.497428181533;1970.980290197094;2014.405750303664;65219.67390163066;84408.57795710982;85441;85795.57102214512;86357;15814.76277855833;16486.67026659484;16555.17224138788;16593.83406165923;16652.83347166513;29777.404451911272;29874.850625747167;30156.698433015827;30239;30261.243468913013;8418.831623367498;8542.957285213533;8695.08701586337;8790.516521591373;9129.680461183827;6986.091808064919;7027.929720702829;7424.591647459333;7501.324880760758;7653.502522336031;8410.453320534145;8629;8638;8662.350323725746;8683.609237584324;2660.401409682806;3296.4396052671104;3333.1667083229195;3407.30150319184;3519.3665140274998;715.8245539653846;786.6342150899859;871.5511511571545;893.8703887936343;905.4250550900226;15712.21438928049;15894;16119;16261.918690406472;16491.505254842374;850.7915560687547;896.8340856941487;909.6725178935578;916.8578870275118;922.1608336413831;8518.446300990416;9026.593803278869;9110.225630821322;9250.352475326798;9257.444553326823;361174;363264;363849;364239;384497;79889.20110798934;82316.58841705753;82994.58502707448;83349.58325208335;83895;870.525563567857;884.5179377239419;888.4047688049022;888.5201990924828;888.9911100888909;13710.794338085136;13882.51411200603;14004.649883752987;14218.644533886736;14299.285035747964;32144.19639509031;32793.67206327906;32918.67081329156;32960.83519582387;33400.33199336048;47444;49867.750661246464;50696.74651626718;50725;51134.74432627813;6467.773627923;6942.2363540011565;7071.681774320065;7508.662110205163;7873.842523149619;8805.779855503664;10388.168946484415;10852.18608604342;11101.611443599435;11594.71013224676;9990.750231243986;10122;10247.897521024992;10305.639302624371;10472.214583906085;9871.457069861226;9915.55380007887;10039.04629060238;10072.899271007196;10292.330998485075;9708.640790289506;9975.650852220428;10238.539265733209;10342.017508336696;10468.68593942183;7392.630368481447;7441.479096463196;7483.251674832473;7563.659635316314;7634.045744281965;9807.754806129904;10324.122449591869;10348.482575871025;10382.377057376581;10483.37099774016 15:08:10 INFO - PROCESS | 27217 | |3;mootools.html;1290.7031382781965;1470.1840478534416;1509.5697726148048;1652.0830938828954;1826.4246762269875;7304.160021597518;7568.091828980516;7636.541807491548;7747.883781743264;7845.60771961401;7606.467547271679;7748.380129589634;7764.029496312961;7772.378209743221;7812.3750099992;2178.5533965537065;2213.513027134028;2221.7222847144108;2222.1333679864783;2223.2329846203033;2219.411855858199;2239.9552008959813;2258.141906075689;2264.7735226477384;2267.85258958168;2224.5662095891307;2226.2319499772634;2244.887755612221;2260.547890421914;2260.762619924909;2143.7856214378594;2159.622066138435;2178.4444966533433;2178.716766820311;2180.2805074325433;2085.5411809401908;2091.6967039779147;2092.968605470919;2106.052276475592;2106.4312635588303;7665.348445382094;7736.767896963044;7775;7820.921790782077;8146.144654811219;4658.976705116453;4760.095581839474;4805.591524720403;4805.951940480585;4818.036392721435;2845.914622561306;2862.656481222246;2876.6548014238215;2891.103757835078;2924.38587896542;871.9346049046347;885.5395194498889;886.7029545102398;920.3833431600776;947.4646824544111;10886.510107045202;10976.725581860437;11031.889681103086;11101.1674124441;11117.72205694856;4149;4201.789910504463;4287.764172970454;4292.935605965913;4305.5048669402795;8726.389152759246;8756.51839148853;8792.120787921156;8795.604197811113;8807.295416366675;4661.277501987197;4679.9298010529865;4686.484486706459;4705.905881882343;4716.976415117971;3731.421629647409;4060.573639767841;4246.957530424718;4248.787560621957;4430.579094985971;3465.7400694947473;3550.520679708258;3606.7294952878638;3634.400323946519;3676.8896933091514;1064.2337516987757;1064.882862885082;1067.9626213082504;1073.7476692977143;1091.4324551233315;731.9853602928018;924.6070420071443;931.5202670624634;936.2509992006368;943.6555657185078;5615.634983726045;5668.234788303609;5736.397678243724;5763.8559036024435;5807.941920580824;900.4687234531658;912.3795818843117;933.3886304470583;952.8475443928938;965.2278177458005;3836.7889766063136;3845.6346647068485;3846.576876543578;3876.186000939778;3912.8239229234755;9607;9966.80066398668;9985.700428987142;10104.797904041878;10133.290669653054;9953.701388958198;9971.252156088178;10019.949900250453;10060.949695251476;10070.144037756727;7390.852182956311;7549.924500755032;7683.731069412655;7749.7287594933905;7753.224677532202;5460.662267522581;5514.310711161105;5538.947599955995;5619.409961954099;5718.285214348207;9218.585163667516;9355.672551460666;9361.765955850886;9392.530373481162;9457.95271023668;8992.820143597222;9216.769580760534;9262.259019278576;9348.626054957998;9547.188488978514;6564.113844631104;7419.258074192753;7423.220561840929;7584.8483030340185;7637.6563054661565;7869.48848324857;7929.762107136795;7946.8807967881685;7964.402669799672;8025.799355016171;7030.472714546532;7228.168760592599;7253.165885922976;7318.707251709872;7366.189719130784;6688.431483323972;6843.863122737617;6960.269171736894;7013.193482749549;7159.248278930637;6536.182977127859;6760.898586521108;6775.695093720696;6782.152230971129;6790.490713196431;4629.166749985035;4809.374781278551;4832.879178020437;4843.588294994824;4907.165781817028;4871.756412179306;4890.364252647133;4941.481144479922;4966.652334336562;4985.8005679772405 15:08:10 INFO - PROCESS | 27217 | |4;prototype.html;287.4581414034543;299.87705040933224;300.3722220559032;303.3054305640084;310.2167028253659;4537.296719008551;4606.424196975378;4654.697444666099;4722.834700785477;4734.171519984006;4487.596116349528;4645;4655.231886738692;4665.973485833119;4681.976590117045;3720.627937206285;3722.976181550075;3723.7765734055906;3732.701383889282;3736.327461057009;3542.574891013082;3561.376759067166;3676.264747050587;3716.9442458363146;3747.625237476258;3636.381815091441;3664.102294937744;3683.2449347883753;3693.8522459101605;3699.3156266090723;3642.3261696586214;3664.963350366489;3674.191677830873;3687.520622319108;3710.7959062251566;3277.6066871975277;3281.671832816723;3289.227031647561;3295.439775238215;3306.437905556061;4272.230998420283;4463.843765468226;4490.865274041784;4514.548545145492;4535.387722657432;4063.4920634920636;4069.1658210066867;4076.0027586482706;4088.3867419886956;4105.815238314283;4815.494373090845;4836.443808961979;4858;4863.562279394871;4867.123917694814;3241.692039256267;3413.2661477782394;3418.8632454701783;3439.6904278614793;3441.2773317603333;5014.24786282057;5031.924521132186;5051.217061355495;5055.393352797688;5066.265391518209;3506.281212351462;3509.666581674737;3517.0679769860494;3526.612072672004;3543.20277937462;3544.556930383702;3562.7352289937216;3565.732570057256;3582.659647333499;3595.6224596417524;4007.3788562772816;4054.432379466877;4076.30702780528;4094.9385759213637;4119.855805046809;4589.954100458953;4795.976020119947;4807;4837.540433658797;4840.080384726891;1164.9708757280966;1192.6541303022027;1198.7113852608306;1199.5501686867424;1213.9757204855853;775.5308038636656;792.6155814429997;799.3134486234042;807.2653884964772;808.5472135604081;3739.364308067635;3748.194138260287;3769.453429252743;3788.905277368033;3794.6205379461835;4233.449651545279;4321.191225703318;4342.913141737147;4350.32569951658;4362;3718.6467285607823;3751.662350388478;3753.268112717994;3757.3612485877466;3764.985732633028;2158.978410215909;2308.2226685452106;2308.9191878284178;2337.4623836517694;2340.403197184707;4166.937495937564;4179.91640167195;4203.444725451542;4212.178625168062;4215.557366476476;4416.624586910084;4437.179121862466;4446.666500012448;4452.799624016862;4496.7077139985795;4099.585642953177;4107.486564179478;4147.979260103741;4170.270202714512;4503.324501324828;5042.302368232978;5075.365579302587;5082.542571168613;5087.3386459759995;5105.183170692671;4916;4925.605951523941;4929.137400954747;4944.381952255968;4947.628927830355;4594.954050459452;4767.141914455431;4816.662833601755;4835.492273311393;4858.441279252931;3575.7496975211484;3587.7847329160336;3589.887134988162;3593.3352329819068;3599.3521166190335;4839.7096174229655;5077.365329333833;5077.492250774893;5082.949170508396;5085.372680742275;4762.833300834454;4842.539958703917;4877.6585639004925;4888.1690112681645;4909.091818013679;4839.225723884161;4858.708477491362;4860.3681521401995;4862.319275301531;4898.730569818552;4549.658775591778;4766.046790641816;4782.628247085866;4804.711717296834;4812.374391329105;4906.075139888004;4911.312416261798;4911.754412279301;4924.7537623119415;4925.980348057279;4798.800299925018;4812.171374877524;4832.420109586872;4849.418069831643;4859.635527335535 15:08:10 INFO - PROCESS | 27217 | |5;yui.html;760.8136006678363;770.5184259837602;818.9549574773392;822.8107535266888;824.7690646618948;11529.077673786098;11587.710307242301;11622.767544649101;11632.418379081044;11666.358350290751;11724.355160466172;11742.647720568375;11771.882281177186;11796;11805.881941180585;661.6228749612723;813.808754942588;845.2350622686463;854.0989256334565;856.2293935458107;843.641452382738;846.551327796269;847.5846835050843;854.747849384433;856.8671855862351;907.3421769217331;911.9968035161303;919.5356345045742;919.5678031325266;921.6313474610176;703.6798256793147;899.7255836969722;916.2532535983219;922.2345453273766;927.9860802087974;654.8002859127964;656.5929123943138;660.4254298760061;662.7415308029872;664.1963224498363;11698.649040528799;11730.413479326;11790.941045294805;11826.113041521834;11867.109966752529;12703.872961270361;13133.60599182026;13168.934155329258;13207.141535800238;13331.400086996011;9871.90128098717;9897.505124743733;10109.090181883666;10164.440955747505;10164.745881353025;3322.435186018383;3385.238321377695;3406.608240052376;3490.7381946354126;3507.9649203507893;44632;44711;44744.3288350675;44888.551114488764;46549.99999999966;10081.344712593658;10126.898731012596;10183.18534517237;10207.183425325957;10299.794004119876;18381.908090459463;18413.81586184121;18464;18466;18499;6864.347886950732;6935.826604334932;6947.7220911163995;6992.860142797115;7080.468964827659;3755.4930084438797;3789.4694742736037;3864.072622570619;3930.3514920037874;3949.960500394959;2722.7821774258014;2771.251762024242;2831.320483084045;2833.4049849531416;2838.6593608766666;499.2336763068672;503.37581399064896;506.7846165379773;509.3302307465745;512.869218349326;536.63776950559;566.9794370133743;579.4697851465861;584.0859055578006;592.5437413191904;7547.886781698279;7679.961600191963;7721.92278077212;7814.68741250355;7849.058113026359;484.6462082679593;495.46984726343186;586.3462239602898;598.5570677698472;603.840625998076;3506.088417011544;3526.541549598587;3630.3283892479976;3631.1829838286703;3685.2076803486843;42373;43388.34917476177;43451.78274108609;43494;43600;43079.56920430818;45220.09559808786;45577.860553486425;46354.536454635025;46986.530134698216;41850;41864.790676046425;42668.78665606652;42809.35785963213;43094.56905430905;6657.267700553032;6741.426978706865;6744.696488657924;6781.677572873243;6896.482763792836;28322.85838570794;29018;29460.852695736383;29610.407791843612;29771.255718607208;34111.14722131967;35488;37011.44482832706;37216.25567488581;37534.249315014094;9337.159655630754;10164.644237451654;10203.438810865176;10981.725456863323;11291.661250162508;10698.946505267735;11110.111191104854;11744.647660570197;12753.936230319161;12762.76797990687;10715.35707857531;11250.21248512629;11598.478068487108;11986.880131198577;12131.878681213075;11011.724706882393;11771.293722376684;11784.82322765142;11799.587014454453;12000.1599888007;10173.287869849335;10236.279385284455;10547.52536135891;10829.67510974672;11180.440977951233;9278.489683067495;9479.241660667269;9526.952365238407;9540.856887146838;9664.420134791935;8999.370044097112;9312.115349041831;9405.670801521914;9413.576389062646;9511.952440237754 15:08:10 INFO - PROCESS | 27217 | __end_tp_report 15:08:10 INFO - PROCESS | 27217 | __start_cc_report 15:08:10 INFO - PROCESS | 27217 | _x_x_mozilla_cycle_collect,954 15:08:10 INFO - PROCESS | 27217 | __end_cc_report 15:08:10 INFO - PROCESS | 27217 | __startTimestamp1463004490319__endTimestamp 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | ------- Summary: start ------- 15:08:10 INFO - PROCESS | 27217 | Number of tests: 6 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | [#0] dojo.html Cycles:165 Average:4663.84 Median:4792.62 stddev:485.45 (10.1%) stddev-sans-first:459.79 15:08:10 INFO - PROCESS | 27217 | Values: 2623.3 2638.5 2679.6 2683.4 2686.5 4562.2 4609.3 4722.9 4783.0 4841.3 4633.2 4653.0 4662.6 4708.9 4758.2 4213.5 4224.2 4309.8 4336.0 4408.8 4370.7 4387.0 4405.6 4416.1 4446.4 4201.2 4246.4 4277.7 4296.6 4327.0 4310.8 4358.3 4359.3 4384.0 4416.4 4344.7 4352.9 4363.0 4380.7 4441.0 4528.2 4557.6 4595.6 4652.0 4699.4 5008.9 5064.0 5102.4 5111.5 5116.3 5037.9 5038.4 5048.9 5095.1 5113.0 4580.1 4727.6 4762.2 4792.6 4800.8 5193.2 5219.6 5257.6 5261.7 5273.7 4997.3 5008.5 5009.7 5075.4 5103.0 4842.0 4878.7 4895.4 4924.0 4930.9 4852.4 4948.6 5064.9 5068.8 5087.7 5054.9 5080.3 5092.7 5100.3 5162.5 4195.5 4331.1 4355.9 4369.2 4419.5 3745.4 3761.4 3786.6 3821.4 3837.4 4346.9 4386.5 4388.0 4415.7 4423.2 4849.7 5067.7 5094.2 5100.9 5113.2 4212.8 4258.3 4295.1 4319.1 4331.4 4536.2 4537.1 4567.0 4595.6 4642.9 4552.8 4655.8 4838.6 4839.3 4851.4 4924.6 4937.6 4958.6 4959.2 5021.0 4675.9 4682.6 4691.7 4703.6 4704.3 4857.5 4885.2 4899.5 4922.6 4939.5 5040.7 5060.9 5083.8 5105.8 5110.4 4957.2 4965.3 4986.7 4994.5 4997.0 4688.6 4892.8 4943.3 4944.7 4983.2 4820.1 4857.2 4869.5 4918.4 4955.3 4987.8 4999.9 5027.5 5040.3 5043.1 4743.7 4756.8 4805.5 4844.0 4862.1 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | [#1] ext.html Cycles:180 Average:49470.37 Median:202132.32 stddev:88472.93 (43.8%) stddev-sans-first:88644.90 15:08:10 INFO - PROCESS | 27217 | Values: 555.9 562.3 578.8 593.6 596.3 25050.1 40548.4 41879.0 42120.8 45797.6 42681.0 42810.1 43098.4 43201.8 43427.3 7204.6 7778.5 7898.4 7936.4 7951.2 9777.4 10036.7 10098.9 10323.1 10343.2 10027.1 10080.9 10106.2 10132.5 10143.0 7440.5 9764.5 9906.6 9990.4 10095.0 9845.8 9956.9 9970.5 9971.6 10015.3 37926.4 38162.8 38188.0 38321.2 38386.6 3106.4 3164.7 3214.3 3230.5 3269.7 29141.0 29718.3 29803.3 29940.9 30636.8 3197.2 3209.4 3279.1 3290.4 3343.7 245773.0 273589.0 276590.0 278659.0 370536.1 57090.7 57507.0 57818.0 57867.7 57963.7 34483.8 35610.3 35641.3 60752.4 61003.7 15797.4 15818.9 15831.5 15867.8 15997.1 10186.8 10337.6 11189.8 11498.5 11518.8 18804.2 18829.9 18966.9 18981.1 18983.1 6989.7 7024.5 7144.0 7161.3 7308.1 874.4 938.5 963.5 965.2 988.5 28079.9 28942.1 29214.3 29437.7 29836.4 943.5 973.4 984.5 1019.0 1053.2 15386.5 15493.2 15574.5 15696.0 15826.3 360359.0 362230.0 363758.0 366108.0 367601.0 51621.2 51898.7 52293.5 52415.5 52472.7 1156.2 1251.3 1252.6 1253.5 1269.1 25305.9 29344.7 29700.4 29958.9 29990.7 42293.6 42880.6 43016.8 43017.0 43048.6 268536.0 270079.0 385305.0 397834.2 398882.0 35749.6 38100.6 38119.8 38948.4 40038.6 42903.1 43016.0 43325.1 43398.3 43439.6 36432.1 36663.6 37398.3 37618.8 37834.6 37255.8 37374.0 37380.4 37382.6 37442.0 36431.3 36764.3 36769.6 37381.6 37641.6 32268.0 33015.0 33354.8 33736.0 33963.7 24637.4 25139.4 25197.1 25584.5 25687.0 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | [#2] jquery.html Cycles:180 Average:25056.88 Median:26110.79 stddev:61094.69 (234.0%) stddev-sans-first:61237.96 15:08:10 INFO - PROCESS | 27217 | Values: 374.0 381.7 385.7 387.5 400.3 16491.4 16947.0 17568.3 17568.4 17797.5 16325.5 16822.7 16850.8 17109.7 17199.7 4777.6 4916.1 5125.9 5141.8 5184.8 3641.6 5702.9 6030.8 6034.9 6186.8 6029.3 6074.1 6082.0 6108.3 6406.5 6766.3 6869.4 6895.6 6903.7 6951.5 3899.2 6176.4 6587.0 6595.4 6695.9 22902.5 22984.0 23565.0 24012.2 24084.3 1929.3 2163.8 2360.4 2503.5 2587.2 12462.3 14078.7 14386.5 14595.6 14804.4 1753.6 1811.5 1896.5 1971.0 2014.4 65219.7 84408.6 85441.0 85795.6 86357.0 15814.8 16486.7 16555.2 16593.8 16652.8 29777.4 29874.9 30156.7 30239.0 30261.2 8418.8 8543.0 8695.1 8790.5 9129.7 6986.1 7027.9 7424.6 7501.3 7653.5 8410.5 8629.0 8638.0 8662.4 8683.6 2660.4 3296.4 3333.2 3407.3 3519.4 715.8 786.6 871.6 893.9 905.4 15712.2 15894.0 16119.0 16261.9 16491.5 850.8 896.8 909.7 916.9 922.2 8518.4 9026.6 9110.2 9250.4 9257.4 361174.0 363264.0 363849.0 364239.0 384497.0 79889.2 82316.6 82994.6 83349.6 83895.0 870.5 884.5 888.4 888.5 889.0 13710.8 13882.5 14004.6 14218.6 14299.3 32144.2 32793.7 32918.7 32960.8 33400.3 47444.0 49867.8 50696.7 50725.0 51134.7 6467.8 6942.2 7071.7 7508.7 7873.8 8805.8 10388.2 10852.2 11101.6 11594.7 9990.8 10122.0 10247.9 10305.6 10472.2 9871.5 9915.6 10039.0 10072.9 10292.3 9708.6 9975.7 10238.5 10342.0 10468.7 7392.6 7441.5 7483.3 7563.7 7634.0 9807.8 10324.1 10348.5 10382.4 10483.4 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | [#3] mootools.html Cycles:180 Average:5303.36 Median:5487.49 stddev:2975.02 (54.2%) stddev-sans-first:2968.08 15:08:10 INFO - PROCESS | 27217 | Values: 1290.7 1470.2 1509.6 1652.1 1826.4 7304.2 7568.1 7636.5 7747.9 7845.6 7606.5 7748.4 7764.0 7772.4 7812.4 2178.6 2213.5 2221.7 2222.1 2223.2 2219.4 2240.0 2258.1 2264.8 2267.9 2224.6 2226.2 2244.9 2260.5 2260.8 2143.8 2159.6 2178.4 2178.7 2180.3 2085.5 2091.7 2093.0 2106.1 2106.4 7665.3 7736.8 7775.0 7820.9 8146.1 4659.0 4760.1 4805.6 4806.0 4818.0 2845.9 2862.7 2876.7 2891.1 2924.4 871.9 885.5 886.7 920.4 947.5 10886.5 10976.7 11031.9 11101.2 11117.7 4149.0 4201.8 4287.8 4292.9 4305.5 8726.4 8756.5 8792.1 8795.6 8807.3 4661.3 4679.9 4686.5 4705.9 4717.0 3731.4 4060.6 4247.0 4248.8 4430.6 3465.7 3550.5 3606.7 3634.4 3676.9 1064.2 1064.9 1068.0 1073.7 1091.4 732.0 924.6 931.5 936.3 943.7 5615.6 5668.2 5736.4 5763.9 5807.9 900.5 912.4 933.4 952.8 965.2 3836.8 3845.6 3846.6 3876.2 3912.8 9607.0 9966.8 9985.7 10104.8 10133.3 9953.7 9971.3 10019.9 10060.9 10070.1 7390.9 7549.9 7683.7 7749.7 7753.2 5460.7 5514.3 5538.9 5619.4 5718.3 9218.6 9355.7 9361.8 9392.5 9458.0 8992.8 9216.8 9262.3 9348.6 9547.2 6564.1 7419.3 7423.2 7584.8 7637.7 7869.5 7929.8 7946.9 7964.4 8025.8 7030.5 7228.2 7253.2 7318.7 7366.2 6688.4 6843.9 6960.3 7013.2 7159.2 6536.2 6760.9 6775.7 6782.2 6790.5 4629.2 4809.4 4832.9 4843.6 4907.2 4871.8 4890.4 4941.5 4966.7 4985.8 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | [#4] prototype.html Cycles:180 Average:3942.76 Median:4296.71 stddev:1155.61 (26.9%) stddev-sans-first:1125.81 15:08:10 INFO - PROCESS | 27217 | Values: 287.5 299.9 300.4 303.3 310.2 4537.3 4606.4 4654.7 4722.8 4734.2 4487.6 4645.0 4655.2 4666.0 4682.0 3720.6 3723.0 3723.8 3732.7 3736.3 3542.6 3561.4 3676.3 3716.9 3747.6 3636.4 3664.1 3683.2 3693.9 3699.3 3642.3 3665.0 3674.2 3687.5 3710.8 3277.6 3281.7 3289.2 3295.4 3306.4 4272.2 4463.8 4490.9 4514.5 4535.4 4063.5 4069.2 4076.0 4088.4 4105.8 4815.5 4836.4 4858.0 4863.6 4867.1 3241.7 3413.3 3418.9 3439.7 3441.3 5014.2 5031.9 5051.2 5055.4 5066.3 3506.3 3509.7 3517.1 3526.6 3543.2 3544.6 3562.7 3565.7 3582.7 3595.6 4007.4 4054.4 4076.3 4094.9 4119.9 4590.0 4796.0 4807.0 4837.5 4840.1 1165.0 1192.7 1198.7 1199.6 1214.0 775.5 792.6 799.3 807.3 808.5 3739.4 3748.2 3769.5 3788.9 3794.6 4233.4 4321.2 4342.9 4350.3 4362.0 3718.6 3751.7 3753.3 3757.4 3765.0 2159.0 2308.2 2308.9 2337.5 2340.4 4166.9 4179.9 4203.4 4212.2 4215.6 4416.6 4437.2 4446.7 4452.8 4496.7 4099.6 4107.5 4148.0 4170.3 4503.3 5042.3 5075.4 5082.5 5087.3 5105.2 4916.0 4925.6 4929.1 4944.4 4947.6 4595.0 4767.1 4816.7 4835.5 4858.4 3575.7 3587.8 3589.9 3593.3 3599.4 4839.7 5077.4 5077.5 5082.9 5085.4 4762.8 4842.5 4877.7 4888.2 4909.1 4839.2 4858.7 4860.4 4862.3 4898.7 4549.7 4766.0 4782.6 4804.7 4812.4 4906.1 4911.3 4911.8 4924.8 4926.0 4798.8 4812.2 4832.4 4849.4 4859.6 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - PROCESS | 27217 | [#5] yui.html Cycles:180 Average:12367.07 Median:42739.07 stddev:13563.50 (31.7%) stddev-sans-first:13573.54 15:08:10 INFO - PROCESS | 27217 | Values: 760.8 770.5 819.0 822.8 824.8 11529.1 11587.7 11622.8 11632.4 11666.4 11724.4 11742.6 11771.9 11796.0 11805.9 661.6 813.8 845.2 854.1 856.2 843.6 846.6 847.6 854.7 856.9 907.3 912.0 919.5 919.6 921.6 703.7 899.7 916.3 922.2 928.0 654.8 656.6 660.4 662.7 664.2 11698.6 11730.4 11790.9 11826.1 11867.1 12703.9 13133.6 13168.9 13207.1 13331.4 9871.9 9897.5 10109.1 10164.4 10164.7 3322.4 3385.2 3406.6 3490.7 3508.0 44632.0 44711.0 44744.3 44888.6 46550.0 10081.3 10126.9 10183.2 10207.2 10299.8 18381.9 18413.8 18464.0 18466.0 18499.0 6864.3 6935.8 6947.7 6992.9 7080.5 3755.5 3789.5 3864.1 3930.4 3950.0 2722.8 2771.3 2831.3 2833.4 2838.7 499.2 503.4 506.8 509.3 512.9 536.6 567.0 579.5 584.1 592.5 7547.9 7680.0 7721.9 7814.7 7849.1 484.6 495.5 586.3 598.6 603.8 3506.1 3526.5 3630.3 3631.2 3685.2 42373.0 43388.3 43451.8 43494.0 43600.0 43079.6 45220.1 45577.9 46354.5 46986.5 41850.0 41864.8 42668.8 42809.4 43094.6 6657.3 6741.4 6744.7 6781.7 6896.5 28322.9 29018.0 29460.9 29610.4 29771.3 34111.1 35488.0 37011.4 37216.3 37534.2 9337.2 10164.6 10203.4 10981.7 11291.7 10698.9 11110.1 11744.6 12753.9 12762.8 10715.4 11250.2 11598.5 11986.9 12131.9 11011.7 11771.3 11784.8 11799.6 12000.2 10173.3 10236.3 10547.5 10829.7 11180.4 9278.5 9479.2 9527.0 9540.9 9664.4 8999.4 9312.1 9405.7 9413.6 9512.0 15:08:10 INFO - PROCESS | 27217 | -------- Summary: end -------- 15:08:10 INFO - PROCESS | 27217 | 15:08:10 INFO - TEST-INFO | 27217: exit 0 15:08:10 INFO - TEST-OK | dromaeo_css | took 1116366ms 15:08:10 INFO - TEST-START | kraken 15:08:10 INFO - operating with platform_type : linux_ 15:08:10 INFO - Initialising browser for kraken test... 15:08:10 INFO - TEST-INFO | started process 27394 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6PpIjt/profile http://localhost:52924/getInfo.html) 15:08:14 INFO - PROCESS | 27394 | __metrics Screen width/height:1600/1200 15:08:14 INFO - PROCESS | 27394 | colorDepth:24 15:08:14 INFO - PROCESS | 27394 | Browser inner width/height: 1024/697 15:08:14 INFO - PROCESS | 27394 | __metrics 15:08:15 ERROR - PROCESS | 27394 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:08:15 INFO - TEST-INFO | 27394: exit 0 15:08:15 INFO - Browser initialized. 15:08:15 INFO - Running cycle 1/1 for kraken test... 15:08:15 INFO - TEST-INFO | started process 27475 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6PpIjt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:27 INFO - PROCESS | 27475 | RSS: Main: 153636864 15:08:27 INFO - PROCESS | 27475 | 15:08:30 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52924/tests/kraken/driver.html?testName=audio-beat-detection) 15:08:31 INFO - PROCESS | 27475 | RSS: Main: 208826368 15:08:31 INFO - PROCESS | 27475 | 15:08:33 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52924/tests/kraken/driver.html?testName=audio-dft) 15:08:34 INFO - PROCESS | 27475 | RSS: Main: 197062656 15:08:34 INFO - PROCESS | 27475 | 15:08:37 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52924/tests/kraken/driver.html?testName=audio-fft) 15:08:38 INFO - PROCESS | 27475 | RSS: Main: 265699328 15:08:38 INFO - PROCESS | 27475 | 15:08:40 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52924/tests/kraken/driver.html?testName=audio-oscillator) 15:08:41 INFO - PROCESS | 27475 | RSS: Main: 174346240 15:08:41 INFO - PROCESS | 27475 | 15:08:43 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52924/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:08:44 INFO - PROCESS | 27475 | RSS: Main: 178794496 15:08:44 INFO - PROCESS | 27475 | 15:08:47 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52924/tests/kraken/driver.html?testName=imaging-darkroom) 15:08:48 INFO - PROCESS | 27475 | RSS: Main: 214863872 15:08:48 INFO - PROCESS | 27475 | 15:08:51 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52924/tests/kraken/driver.html?testName=imaging-desaturate) 15:08:52 INFO - PROCESS | 27475 | RSS: Main: 213979136 15:08:52 INFO - PROCESS | 27475 | 15:08:55 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52924/tests/kraken/driver.html?testName=json-parse-financial) 15:08:56 INFO - PROCESS | 27475 | RSS: Main: 214089728 15:08:56 INFO - PROCESS | 27475 | 15:08:57 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52924/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:08:58 INFO - PROCESS | 27475 | RSS: Main: 206782464 15:08:58 INFO - PROCESS | 27475 | 15:09:00 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:09:00 INFO - PROCESS | 27475 | RSS: Main: 209707008 15:09:00 INFO - PROCESS | 27475 | 15:09:03 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:09:03 INFO - PROCESS | 27475 | RSS: Main: 212250624 15:09:03 INFO - PROCESS | 27475 | 15:09:06 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:09:07 INFO - PROCESS | 27475 | RSS: Main: 217817088 15:09:07 INFO - PROCESS | 27475 | 15:09:10 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:09:11 INFO - PROCESS | 27475 | RSS: Main: 216260608 15:09:11 INFO - PROCESS | 27475 | 15:09:12 INFO - PROCESS | 27475 | Cycle 1(1): loaded http://localhost:52924/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52924/tests/kraken/driver.html?testName=ai-astar) 15:09:13 INFO - PROCESS | 27475 | RSS: Main: 262144000 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | __start_tp_report 15:09:13 INFO - PROCESS | 27475 | _x_x_mozilla_page_load 15:09:13 INFO - PROCESS | 27475 | _x_x_mozilla_page_load_details 15:09:13 INFO - PROCESS | 27475 | |i|pagename|runs| 15:09:13 INFO - PROCESS | 27475 | |0;ai-astar;119;114;119;118;115;117;116;114;114;115 15:09:13 INFO - PROCESS | 27475 | |1;audio-beat-detection;171;147;160;218;136;165;160;147;190;137 15:09:13 INFO - PROCESS | 27475 | |2;audio-dft;161;163;163;163;162;162;162;163;163;163 15:09:13 INFO - PROCESS | 27475 | |3;audio-fft;79;79;78;148;78;78;79;77;127;78 15:09:13 INFO - PROCESS | 27475 | |4;audio-oscillator;95;96;146;95;95;95;122;94;94;119 15:09:13 INFO - PROCESS | 27475 | |5;imaging-gaussian-blur;119;118;119;119;119;119;119;119;119;118 15:09:13 INFO - PROCESS | 27475 | |6;imaging-darkroom;149;150;151;148;148;147;147;148;150;149 15:09:13 INFO - PROCESS | 27475 | |7;imaging-desaturate;96;95;97;97;96;95;95;96;96;96 15:09:13 INFO - PROCESS | 27475 | |8;json-parse-financial;67;70;68;67;68;69;68;67;66;67 15:09:13 INFO - PROCESS | 27475 | |9;json-stringify-tinderbox;65;65;65;65;66;66;64;65;64;65 15:09:13 INFO - PROCESS | 27475 | |10;stanford-crypto-aes;85;89;85;86;83;87;92;89;153;91 15:09:13 INFO - PROCESS | 27475 | |11;stanford-crypto-ccm;158;140;147;140;143;139;139;137;160;144 15:09:13 INFO - PROCESS | 27475 | |12;stanford-crypto-pbkdf2;181;184;181;181;179;182;178;181;184;177 15:09:13 INFO - PROCESS | 27475 | |13;stanford-crypto-sha256-iterative;71;72;72;71;72;74;70;68;70;69 15:09:13 INFO - PROCESS | 27475 | __end_tp_report 15:09:13 INFO - PROCESS | 27475 | __start_cc_report 15:09:13 INFO - PROCESS | 27475 | _x_x_mozilla_cycle_collect,1945 15:09:13 INFO - PROCESS | 27475 | __end_cc_report 15:09:13 INFO - PROCESS | 27475 | __startTimestamp1463004553121__endTimestamp 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | ------- Summary: start ------- 15:09:13 INFO - PROCESS | 27475 | Number of tests: 14 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#0] ai-astar Cycles:10 Average:116.10 Median:116.50 stddev:2.02 (1.7%) stddev-sans-first:1.86 15:09:13 INFO - PROCESS | 27475 | Values: 119.0 114.0 119.0 118.0 115.0 117.0 116.0 114.0 114.0 115.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#1] audio-beat-detection Cycles:10 Average:163.10 Median:162.50 stddev:25.29 (15.6%) stddev-sans-first:26.66 15:09:13 INFO - PROCESS | 27475 | Values: 171.0 147.0 160.0 218.0 136.0 165.0 160.0 147.0 190.0 137.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:0.71 (0.4%) stddev-sans-first:0.50 15:09:13 INFO - PROCESS | 27475 | Values: 161.0 163.0 163.0 163.0 162.0 162.0 162.0 163.0 163.0 163.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#3] audio-fft Cycles:10 Average:90.10 Median:78.00 stddev:25.48 (32.7%) stddev-sans-first:26.70 15:09:13 INFO - PROCESS | 27475 | Values: 79.0 79.0 78.0 148.0 78.0 78.0 79.0 77.0 127.0 78.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#4] audio-oscillator Cycles:10 Average:105.10 Median:95.00 stddev:17.92 (18.9%) stddev-sans-first:18.63 15:09:13 INFO - PROCESS | 27475 | Values: 95.0 96.0 146.0 95.0 95.0 95.0 122.0 94.0 94.0 119.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#5] imaging-gaussian-blur Cycles:10 Average:118.80 Median:119.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 15:09:13 INFO - PROCESS | 27475 | Values: 119.0 118.0 119.0 119.0 119.0 119.0 119.0 119.0 119.0 118.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:1.34 (0.9%) stddev-sans-first:1.41 15:09:13 INFO - PROCESS | 27475 | Values: 149.0 150.0 151.0 148.0 148.0 147.0 147.0 148.0 150.0 149.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 15:09:13 INFO - PROCESS | 27475 | Values: 96.0 95.0 97.0 97.0 96.0 95.0 95.0 96.0 96.0 96.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:68.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 15:09:13 INFO - PROCESS | 27475 | Values: 67.0 70.0 68.0 67.0 68.0 69.0 68.0 67.0 66.0 67.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 15:09:13 INFO - PROCESS | 27475 | Values: 65.0 65.0 65.0 65.0 66.0 66.0 64.0 65.0 64.0 65.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:88.00 stddev:20.92 (23.8%) stddev-sans-first:21.94 15:09:13 INFO - PROCESS | 27475 | Values: 85.0 89.0 85.0 86.0 83.0 87.0 92.0 89.0 153.0 91.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:143.50 stddev:8.08 (5.6%) stddev-sans-first:7.00 15:09:13 INFO - PROCESS | 27475 | Values: 158.0 140.0 147.0 140.0 143.0 139.0 139.0 137.0 160.0 144.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:181.00 stddev:2.30 (1.3%) stddev-sans-first:2.44 15:09:13 INFO - PROCESS | 27475 | Values: 181.0 184.0 181.0 181.0 179.0 182.0 178.0 181.0 184.0 177.0 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - PROCESS | 27475 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:71.50 stddev:1.73 (2.4%) stddev-sans-first:1.83 15:09:13 INFO - PROCESS | 27475 | Values: 71.0 72.0 72.0 71.0 72.0 74.0 70.0 68.0 70.0 69.0 15:09:13 INFO - PROCESS | 27475 | -------- Summary: end -------- 15:09:13 INFO - PROCESS | 27475 | 15:09:13 INFO - TEST-INFO | 27475: exit 0 15:09:13 INFO - TEST-OK | kraken | took 63078ms 15:09:13 INFO - SUITE-END | took 1179s 15:09:14 INFO - Completed test suite (00:19:39) 15:09:14 INFO - javascript benchmark 15:09:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2623.3179373362927, 2638.51187530307, 2679.5980602909567, 2683.4096498770277, 2686.543287641101, 4562.224421848286, 4609.2855607380825, 4722.858314250574, 4783.043391321737, 4841.3222148899185, 4633.1891918914225, 4652.97673511632, 4662.603678687307, 4708.858734237979, 4758.238681810911, 4213.452251207339, 4224.176285624312, 4309.762963037032, 4335.9566404335865, 4408.779561021952, 4370.650347972155, 4386.9561304387025, 4405.603495685387, 4416.072624748806, 4446.444194475691, 4201.222773786829, 4246.384274280243, 4277.743335399886, 4296.63478604319, 4326.9567304326865, 4310.827566897352, 4358.3244597087505, 4359.302511598129, 4383.978080109579, 4416.447944006999, 4344.695871289011, 4352.912941741148, 4363.040131171138, 4380.737155770632, 4440.955590444087, 4528.230200865861, 4557.635389168859, 4595.609373203281, 4651.976740116312, 4699.436067671867, 5008.949910500921, 5063.974680126613, 5102.438731739506, 5111.514406131411, 5116.309298244727, 5037.949620503785, 5038.445770965191, 5048.899022019576, 5095.133827249339, 5112.974435127838, 4580.129775342708, 4727.550882666142, 4762.238041913277, 4792.616590672737, 4800.807967681254, 5193.246979187997, 5219.634625576249, 5257.553107985787, 5261.710605916712, 5273.6572122811895, 4997.275395067768, 5008.499150084962, 5009.674371165936, 5075.416327122354, 5102.979404119117, 4841.983183531498, 4878.682885612424, 4895.412550493964, 4924.015196960622, 4930.852074437773, 4852.441969173519, 4948.579370753526, 5064.873378165502, 5068.82259120929, 5087.694738315713, 5054.873628159252, 5080.339555857715, 5092.668976516515, 5100.285959965608, 5162.45794191612, 4195.538490766014, 4331.0904710011255, 4355.932796464886, 4369.169857727022, 4419.513853476115, 3745.363288240951, 3761.39817629178, 3786.6024067472517, 3821.407681809324, 3837.4435706822915, 4346.913061738811, 4386.495553011381, 4387.956120438755, 4415.690901636919, 4423.203823311835, 4849.709017458893, 5067.746612669425, 5094.2358646203365, 5100.923486147711, 5113.181890897513, 4212.789360531961, 4258.276093064186, 4295.119500502389, 4319.136172765459, 4331.393604895317, 4536.160810250114, 4537.069900670421, 4566.954330456653, 4595.632349412106, 4642.908916404642, 4552.772361381852, 4655.813767449264, 4838.588719958772, 4839.346688197048, 4851.369321988119, 4924.581410580141, 4937.580305674058, 4958.6281028923695, 4959.156943319716, 5021.046001259749, 4675.883102922318, 4682.555157260055, 4691.695039822401, 4703.576678098988, 4704.317873908196, 4857.465678775261, 4885.169521181337, 4899.534544218293, 4922.630802689884, 4939.53074457926, 5040.722760248222, 5060.924086138784, 5083.771230294719, 5105.846824595268, 5110.386753589593, 4957.206846904478, 4965.304857319906, 4986.7257300848805, 4994.475580064186, 4996.975015124901, 4688.601468875047, 4892.804287828447, 4943.307936888767, 4944.728039957837, 4983.20853752578, 4820.084184005028, 4857.1742803724155, 4869.488703686204, 4918.4343800461975, 4955.331030310862, 4987.750612469435, 4999.8750031248055, 5027.522385373383, 5040.294358789699, 5043.117454445531, 4743.739094349844, 4756.833510827105, 4805.519448055306, 4843.98276361968, 4862.05189987949], "value": 4633.441841028246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.8526990347559, 562.2887047884425, 578.7829563913532, 593.5815250248576, 596.263614436171, 25050.123245686384, 40548.39177412341, 41879.0, 42120.78939605306, 45797.626071217834, 42681.0, 42810.14379712404, 43098.35352469708, 43201.783991080076, 43427.3485897711, 7204.567725936435, 7778.455508114448, 7898.368130549542, 7936.44444888858, 7951.244631759973, 9777.413355198676, 10036.74908127299, 10098.89901100987, 10323.070923616873, 10343.172546196287, 10027.097561219489, 10080.94959525205, 10106.191504679608, 10132.54403551842, 10143.0, 7440.516366436218, 9764.462954537497, 9906.603735850558, 9990.400575965428, 10095.0, 9845.803083938352, 9956.850647240297, 9970.501474926297, 9971.601135954554, 10015.298929074968, 37926.43110353347, 38162.80918595389, 38188.04529886747, 38321.23357532862, 38386.61613383859, 3106.409782141386, 3164.68353164684, 3214.3249917517114, 3230.4669729494603, 3269.673032696735, 29141.0, 29718.257043573867, 29803.25491862699, 29940.850295748383, 30636.846815766003, 3197.1527545200306, 3209.422303985282, 3279.0982479818003, 3290.440625093716, 3343.7157841583494, 245773.0, 273589.0, 276590.0, 278659.0, 370536.14731926436, 57090.71454642783, 57507.0, 57818.0, 57867.71066144642, 57963.710181449664, 34483.82758086194, 35610.28779424397, 35641.28717425637, 60752.392476075554, 61003.694981525696, 15797.447089351817, 15818.9209053954, 15831.525054248392, 15867.761983570255, 15997.120158391168, 10186.847197292047, 10337.63818266372, 11189.776204476027, 11498.540058397737, 11518.769624607461, 18804.153813078443, 18829.870207787575, 18966.905165474083, 18981.145848436852, 18983.145758440904, 6989.720411183597, 7024.473164512682, 7143.964125201921, 7161.319674630901, 7308.086489188851, 874.3748220022715, 938.4791440750313, 963.5037955452947, 965.1988849255101, 988.5008070924281, 28079.859600701864, 28942.13173604795, 29214.26964325909, 29437.705622943926, 29836.40327193444, 943.4669411782321, 973.4013581647378, 984.532347135105, 1019.0268293779422, 1053.210092430677, 15386.461473848585, 15493.225338733018, 15574.532764017098, 15696.0, 15826.28781704826, 360359.0, 362230.0, 363758.0, 366108.0, 367601.0, 51621.22568161481, 51898.740506297225, 52293.47706522886, 52415.47584524106, 52472.737636311576, 1156.1906665334132, 1251.2617555642214, 1252.5616034387892, 1253.4610117649497, 1269.0862578943147, 25305.87347063253, 29344.706552934196, 29700.405991880474, 29958.850205748833, 29990.700092999665, 42293.577064228964, 42880.57119428766, 43016.784916075216, 43017.0, 43048.56951430446, 268536.0, 270079.0, 385305.0, 397834.1840943552, 398882.0, 35749.64250357463, 38100.61899380971, 38119.80940095282, 38948.41577376398, 40038.599614003484, 42903.14193716171, 43016.0, 43325.133497330506, 43398.34902476529, 43439.56560434355, 36432.08919777027, 36663.63336366602, 37398.252034958605, 37618.811905940296, 37834.62165378311, 37255.81372093122, 37374.0, 37380.439293410076, 37382.62617373792, 37442.0, 36431.27137457289, 36764.26471470609, 36769.63230367769, 37381.6261837389, 37641.62358376491, 32268.0, 33015.0, 33354.83322583372, 33736.0, 33963.66036339605, 24637.38406539851, 25139.371515712253, 25197.11810086638, 25584.488310234065, 25687.0], "value": 18559.38775189238, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.99209131390893, 381.66032231314125, 385.69722767627417, 387.5465705125004, 400.3374415342609, 16491.422800201995, 16947.0, 17568.29726810926, 17568.385106521273, 17797.466076017725, 16325.510234692949, 16822.663546729058, 16850.83149168508, 17109.657806843854, 17199.656006879886, 4777.570018698317, 4916.115099282128, 5125.923111153317, 5141.82003629873, 5184.818531351403, 3641.617630148836, 5702.885942281152, 6030.788922387717, 6034.939650603504, 6186.81439556814, 6029.336772954972, 6074.119252708355, 6082.0, 6108.3280839107665, 6406.519511036668, 6766.3233676632435, 6869.381755641966, 6895.620740859251, 6903.723851045952, 6951.4786391020625, 3899.200663863901, 6176.413240742122, 6587.044878492592, 6595.406413422767, 6695.899561506581, 22902.541949160925, 22984.0, 23565.0, 24012.159574414985, 24084.27747167588, 1929.2861641192849, 2163.816075633573, 2360.4216966843233, 2503.524330377241, 2587.1721049264243, 12462.252264864137, 14078.732914037786, 14386.496472623408, 14595.635109122357, 14804.40782368704, 1753.6317373351612, 1811.5018369948239, 1896.497428181533, 1970.980290197094, 2014.405750303664, 65219.67390163066, 84408.57795710982, 85441.0, 85795.57102214512, 86357.0, 15814.76277855833, 16486.67026659484, 16555.17224138788, 16593.83406165923, 16652.83347166513, 29777.404451911272, 29874.850625747167, 30156.698433015827, 30239.0, 30261.243468913013, 8418.831623367498, 8542.957285213533, 8695.08701586337, 8790.516521591373, 9129.680461183827, 6986.091808064919, 7027.929720702829, 7424.591647459333, 7501.324880760758, 7653.502522336031, 8410.453320534145, 8629.0, 8638.0, 8662.350323725746, 8683.609237584324, 2660.401409682806, 3296.4396052671104, 3333.1667083229195, 3407.30150319184, 3519.3665140274998, 715.8245539653846, 786.6342150899859, 871.5511511571545, 893.8703887936343, 905.4250550900226, 15712.21438928049, 15894.0, 16119.0, 16261.918690406472, 16491.505254842374, 850.7915560687547, 896.8340856941487, 909.6725178935578, 916.8578870275118, 922.1608336413831, 8518.446300990416, 9026.593803278869, 9110.225630821322, 9250.352475326798, 9257.444553326823, 361174.0, 363264.0, 363849.0, 364239.0, 384497.0, 79889.20110798934, 82316.58841705753, 82994.58502707448, 83349.58325208335, 83895.0, 870.525563567857, 884.5179377239419, 888.4047688049022, 888.5201990924828, 888.9911100888909, 13710.794338085136, 13882.51411200603, 14004.649883752987, 14218.644533886736, 14299.285035747964, 32144.19639509031, 32793.67206327906, 32918.67081329156, 32960.83519582387, 33400.33199336048, 47444.0, 49867.750661246464, 50696.74651626718, 50725.0, 51134.74432627813, 6467.773627923, 6942.2363540011565, 7071.681774320065, 7508.662110205163, 7873.842523149619, 8805.779855503664, 10388.168946484415, 10852.18608604342, 11101.611443599435, 11594.71013224676, 9990.750231243986, 10122.0, 10247.897521024992, 10305.639302624371, 10472.214583906085, 9871.457069861226, 9915.55380007887, 10039.04629060238, 10072.899271007196, 10292.330998485075, 9708.640790289506, 9975.650852220428, 10238.539265733209, 10342.017508336696, 10468.68593942183, 7392.630368481447, 7441.479096463196, 7483.251674832473, 7563.659635316314, 7634.045744281965, 9807.754806129904, 10324.122449591869, 10348.482575871025, 10382.377057376581, 10483.37099774016], "value": 9099.762713539845, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1290.7031382781965, 1470.1840478534416, 1509.5697726148048, 1652.0830938828954, 1826.4246762269875, 7304.160021597518, 7568.091828980516, 7636.541807491548, 7747.883781743264, 7845.60771961401, 7606.467547271679, 7748.380129589634, 7764.029496312961, 7772.378209743221, 7812.3750099992, 2178.5533965537065, 2213.513027134028, 2221.7222847144108, 2222.1333679864783, 2223.2329846203033, 2219.411855858199, 2239.9552008959813, 2258.141906075689, 2264.7735226477384, 2267.85258958168, 2224.5662095891307, 2226.2319499772634, 2244.887755612221, 2260.547890421914, 2260.762619924909, 2143.7856214378594, 2159.622066138435, 2178.4444966533433, 2178.716766820311, 2180.2805074325433, 2085.5411809401908, 2091.6967039779147, 2092.968605470919, 2106.052276475592, 2106.4312635588303, 7665.348445382094, 7736.767896963044, 7775.0, 7820.921790782077, 8146.144654811219, 4658.976705116453, 4760.095581839474, 4805.591524720403, 4805.951940480585, 4818.036392721435, 2845.914622561306, 2862.656481222246, 2876.6548014238215, 2891.103757835078, 2924.38587896542, 871.9346049046347, 885.5395194498889, 886.7029545102398, 920.3833431600776, 947.4646824544111, 10886.510107045202, 10976.725581860437, 11031.889681103086, 11101.1674124441, 11117.72205694856, 4149.0, 4201.789910504463, 4287.764172970454, 4292.935605965913, 4305.5048669402795, 8726.389152759246, 8756.51839148853, 8792.120787921156, 8795.604197811113, 8807.295416366675, 4661.277501987197, 4679.9298010529865, 4686.484486706459, 4705.905881882343, 4716.976415117971, 3731.421629647409, 4060.573639767841, 4246.957530424718, 4248.787560621957, 4430.579094985971, 3465.7400694947473, 3550.520679708258, 3606.7294952878638, 3634.400323946519, 3676.8896933091514, 1064.2337516987757, 1064.882862885082, 1067.9626213082504, 1073.7476692977143, 1091.4324551233315, 731.9853602928018, 924.6070420071443, 931.5202670624634, 936.2509992006368, 943.6555657185078, 5615.634983726045, 5668.234788303609, 5736.397678243724, 5763.8559036024435, 5807.941920580824, 900.4687234531658, 912.3795818843117, 933.3886304470583, 952.8475443928938, 965.2278177458005, 3836.7889766063136, 3845.6346647068485, 3846.576876543578, 3876.186000939778, 3912.8239229234755, 9607.0, 9966.80066398668, 9985.700428987142, 10104.797904041878, 10133.290669653054, 9953.701388958198, 9971.252156088178, 10019.949900250453, 10060.949695251476, 10070.144037756727, 7390.852182956311, 7549.924500755032, 7683.731069412655, 7749.7287594933905, 7753.224677532202, 5460.662267522581, 5514.310711161105, 5538.947599955995, 5619.409961954099, 5718.285214348207, 9218.585163667516, 9355.672551460666, 9361.765955850886, 9392.530373481162, 9457.95271023668, 8992.820143597222, 9216.769580760534, 9262.259019278576, 9348.626054957998, 9547.188488978514, 6564.113844631104, 7419.258074192753, 7423.220561840929, 7584.8483030340185, 7637.6563054661565, 7869.48848324857, 7929.762107136795, 7946.8807967881685, 7964.402669799672, 8025.799355016171, 7030.472714546532, 7228.168760592599, 7253.165885922976, 7318.707251709872, 7366.189719130784, 6688.431483323972, 6843.863122737617, 6960.269171736894, 7013.193482749549, 7159.248278930637, 6536.182977127859, 6760.898586521108, 6775.695093720696, 6782.152230971129, 6790.490713196431, 4629.166749985035, 4809.374781278551, 4832.879178020437, 4843.588294994824, 4907.165781817028, 4871.756412179306, 4890.364252647133, 4941.481144479922, 4966.652334336562, 4985.8005679772405], "value": 4255.19238692979, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.4581414034543, 299.87705040933224, 300.3722220559032, 303.3054305640084, 310.2167028253659, 4537.296719008551, 4606.424196975378, 4654.697444666099, 4722.834700785477, 4734.171519984006, 4487.596116349528, 4645.0, 4655.231886738692, 4665.973485833119, 4681.976590117045, 3720.627937206285, 3722.976181550075, 3723.7765734055906, 3732.701383889282, 3736.327461057009, 3542.574891013082, 3561.376759067166, 3676.264747050587, 3716.9442458363146, 3747.625237476258, 3636.381815091441, 3664.102294937744, 3683.2449347883753, 3693.8522459101605, 3699.3156266090723, 3642.3261696586214, 3664.963350366489, 3674.191677830873, 3687.520622319108, 3710.7959062251566, 3277.6066871975277, 3281.671832816723, 3289.227031647561, 3295.439775238215, 3306.437905556061, 4272.230998420283, 4463.843765468226, 4490.865274041784, 4514.548545145492, 4535.387722657432, 4063.4920634920636, 4069.1658210066867, 4076.0027586482706, 4088.3867419886956, 4105.815238314283, 4815.494373090845, 4836.443808961979, 4858.0, 4863.562279394871, 4867.123917694814, 3241.692039256267, 3413.2661477782394, 3418.8632454701783, 3439.6904278614793, 3441.2773317603333, 5014.24786282057, 5031.924521132186, 5051.217061355495, 5055.393352797688, 5066.265391518209, 3506.281212351462, 3509.666581674737, 3517.0679769860494, 3526.612072672004, 3543.20277937462, 3544.556930383702, 3562.7352289937216, 3565.732570057256, 3582.659647333499, 3595.6224596417524, 4007.3788562772816, 4054.432379466877, 4076.30702780528, 4094.9385759213637, 4119.855805046809, 4589.954100458953, 4795.976020119947, 4807.0, 4837.540433658797, 4840.080384726891, 1164.9708757280966, 1192.6541303022027, 1198.7113852608306, 1199.5501686867424, 1213.9757204855853, 775.5308038636656, 792.6155814429997, 799.3134486234042, 807.2653884964772, 808.5472135604081, 3739.364308067635, 3748.194138260287, 3769.453429252743, 3788.905277368033, 3794.6205379461835, 4233.449651545279, 4321.191225703318, 4342.913141737147, 4350.32569951658, 4362.0, 3718.6467285607823, 3751.662350388478, 3753.268112717994, 3757.3612485877466, 3764.985732633028, 2158.978410215909, 2308.2226685452106, 2308.9191878284178, 2337.4623836517694, 2340.403197184707, 4166.937495937564, 4179.91640167195, 4203.444725451542, 4212.178625168062, 4215.557366476476, 4416.624586910084, 4437.179121862466, 4446.666500012448, 4452.799624016862, 4496.7077139985795, 4099.585642953177, 4107.486564179478, 4147.979260103741, 4170.270202714512, 4503.324501324828, 5042.302368232978, 5075.365579302587, 5082.542571168613, 5087.3386459759995, 5105.183170692671, 4916.0, 4925.605951523941, 4929.137400954747, 4944.381952255968, 4947.628927830355, 4594.954050459452, 4767.141914455431, 4816.662833601755, 4835.492273311393, 4858.441279252931, 3575.7496975211484, 3587.7847329160336, 3589.887134988162, 3593.3352329819068, 3599.3521166190335, 4839.7096174229655, 5077.365329333833, 5077.492250774893, 5082.949170508396, 5085.372680742275, 4762.833300834454, 4842.539958703917, 4877.6585639004925, 4888.1690112681645, 4909.091818013679, 4839.225723884161, 4858.708477491362, 4860.3681521401995, 4862.319275301531, 4898.730569818552, 4549.658775591778, 4766.046790641816, 4782.628247085866, 4804.711717296834, 4812.374391329105, 4906.075139888004, 4911.312416261798, 4911.754412279301, 4924.7537623119415, 4925.980348057279, 4798.800299925018, 4812.171374877524, 4832.420109586872, 4849.418069831643, 4859.635527335535], "value": 3579.551273487881, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.8136006678363, 770.5184259837602, 818.9549574773392, 822.8107535266888, 824.7690646618948, 11529.077673786098, 11587.710307242301, 11622.767544649101, 11632.418379081044, 11666.358350290751, 11724.355160466172, 11742.647720568375, 11771.882281177186, 11796.0, 11805.881941180585, 661.6228749612723, 813.808754942588, 845.2350622686463, 854.0989256334565, 856.2293935458107, 843.641452382738, 846.551327796269, 847.5846835050843, 854.747849384433, 856.8671855862351, 907.3421769217331, 911.9968035161303, 919.5356345045742, 919.5678031325266, 921.6313474610176, 703.6798256793147, 899.7255836969722, 916.2532535983219, 922.2345453273766, 927.9860802087974, 654.8002859127964, 656.5929123943138, 660.4254298760061, 662.7415308029872, 664.1963224498363, 11698.649040528799, 11730.413479326, 11790.941045294805, 11826.113041521834, 11867.109966752529, 12703.872961270361, 13133.60599182026, 13168.934155329258, 13207.141535800238, 13331.400086996011, 9871.90128098717, 9897.505124743733, 10109.090181883666, 10164.440955747505, 10164.745881353025, 3322.435186018383, 3385.238321377695, 3406.608240052376, 3490.7381946354126, 3507.9649203507893, 44632.0, 44711.0, 44744.3288350675, 44888.551114488764, 46549.99999999966, 10081.344712593658, 10126.898731012596, 10183.18534517237, 10207.183425325957, 10299.794004119876, 18381.908090459463, 18413.81586184121, 18464.0, 18466.0, 18499.0, 6864.347886950732, 6935.826604334932, 6947.7220911163995, 6992.860142797115, 7080.468964827659, 3755.4930084438797, 3789.4694742736037, 3864.072622570619, 3930.3514920037874, 3949.960500394959, 2722.7821774258014, 2771.251762024242, 2831.320483084045, 2833.4049849531416, 2838.6593608766666, 499.2336763068672, 503.37581399064896, 506.7846165379773, 509.3302307465745, 512.869218349326, 536.63776950559, 566.9794370133743, 579.4697851465861, 584.0859055578006, 592.5437413191904, 7547.886781698279, 7679.961600191963, 7721.92278077212, 7814.68741250355, 7849.058113026359, 484.6462082679593, 495.46984726343186, 586.3462239602898, 598.5570677698472, 603.840625998076, 3506.088417011544, 3526.541549598587, 3630.3283892479976, 3631.1829838286703, 3685.2076803486843, 42373.0, 43388.34917476177, 43451.78274108609, 43494.0, 43600.0, 43079.56920430818, 45220.09559808786, 45577.860553486425, 46354.536454635025, 46986.530134698216, 41850.0, 41864.790676046425, 42668.78665606652, 42809.35785963213, 43094.56905430905, 6657.267700553032, 6741.426978706865, 6744.696488657924, 6781.677572873243, 6896.482763792836, 28322.85838570794, 29018.0, 29460.852695736383, 29610.407791843612, 29771.255718607208, 34111.14722131967, 35488.0, 37011.44482832706, 37216.25567488581, 37534.249315014094, 9337.159655630754, 10164.644237451654, 10203.438810865176, 10981.725456863323, 11291.661250162508, 10698.946505267735, 11110.111191104854, 11744.647660570197, 12753.936230319161, 12762.76797990687, 10715.35707857531, 11250.21248512629, 11598.478068487108, 11986.880131198577, 12131.878681213075, 11011.724706882393, 11771.293722376684, 11784.82322765142, 11799.587014454453, 12000.1599888007, 10173.287869849335, 10236.279385284455, 10547.52536135891, 10829.67510974672, 11180.440977951233, 9278.489683067495, 9479.241660667269, 9526.952365238407, 9540.856887146838, 9664.420134791935, 8999.370044097112, 9312.115349041831, 9405.670801521914, 9413.576389062646, 9511.952440237754], "value": 5898.698584649046, "unit": "score"}], "name": "dromaeo_css", "value": 6424.553452560714, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 119.0, 118.0, 115.0, 117.0, 116.0, 114.0, 114.0, 115.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 147.0, 160.0, 218.0, 136.0, 165.0, 160.0, 147.0, 190.0, 137.0], "value": 163.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 163.0, 163.0, 162.0, 162.0, 162.0, 163.0, 163.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 148.0, 78.0, 78.0, 79.0, 77.0, 127.0, 78.0], "value": 90.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 146.0, 95.0, 95.0, 95.0, 122.0, 94.0, 94.0, 119.0], "value": 105.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 119.0, 119.0, 119.0, 119.0, 119.0, 119.0, 119.0, 118.0], "value": 118.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 151.0, 148.0, 148.0, 147.0, 147.0, 148.0, 150.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 97.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 67.0, 68.0, 69.0, 68.0, 67.0, 66.0, 67.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 66.0, 64.0, 65.0, 64.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 85.0, 86.0, 83.0, 87.0, 92.0, 89.0, 153.0, 91.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 140.0, 147.0, 140.0, 143.0, 139.0, 139.0, 137.0, 160.0, 144.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 181.0, 181.0, 179.0, 182.0, 178.0, 181.0, 184.0, 177.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 72.0, 71.0, 72.0, 74.0, 70.0, 68.0, 70.0, 69.0], "value": 70.9, "unit": "score"}], "name": "kraken", "value": 1623.3999999999999, "alertThreshold": 2.0}]} 15:09:14 INFO - Return code: 0 15:09:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:09:14 ERROR - # TBPL SUCCESS # 15:09:14 INFO - Running post-action listener: _resource_record_post_action 15:09:14 INFO - ##### 15:09:14 INFO - ##### Finished run-tests step (success) 15:09:14 INFO - ##### 15:09:14 INFO - Running post-run listener: _resource_record_post_run 15:09:15 INFO - Total resource usage - Wall time: 1197s; CPU: 26.0%; Read bytes: 0; Write bytes: 521961472; Read time: 0; Write time: 430864 15:09:15 INFO - install - Wall time: 16s; CPU: 26.0%; Read bytes: 0; Write bytes: 234442752; Read time: 0; Write time: 224576 15:09:15 INFO - run-tests - Wall time: 1181s; CPU: 26.0%; Read bytes: 0; Write bytes: 287215616; Read time: 0; Write time: 206256 15:09:15 INFO - Running post-run listener: _upload_blobber_files 15:09:15 INFO - Blob upload gear active. 15:09:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:09:15 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 15:09:15 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'] 15:09:15 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 15:09:15 INFO - (blobuploader) - INFO - Open directory for files ... 15:09:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:09:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:09:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:16 INFO - (blobuploader) - INFO - Done attempting. 15:09:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:09:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:09:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:17 INFO - (blobuploader) - INFO - Done attempting. 15:09:17 INFO - (blobuploader) - INFO - Iteration through files over. 15:09:17 INFO - Return code: 0 15:09:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:09:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:09:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9d909d159b17bbcb21715d1d0d5ac9e188cca62d265f6e1438e50b0fe332a9189bdcf7c2c03eb48fa78db9b0a2594a1505a441c24174210f7f0320fc5464ce52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a1b8ddc7fa4075e3ea308cfc97835a12afe263a37747fecd0521ad5dccc6577295e17a063c13dd16cc4b4789852182254fe36f2b95163061ac1e4c56d4dc0c17"} 15:09:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:09:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:09:17 INFO - Contents: 15:09:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9d909d159b17bbcb21715d1d0d5ac9e188cca62d265f6e1438e50b0fe332a9189bdcf7c2c03eb48fa78db9b0a2594a1505a441c24174210f7f0320fc5464ce52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a1b8ddc7fa4075e3ea308cfc97835a12afe263a37747fecd0521ad5dccc6577295e17a063c13dd16cc4b4789852182254fe36f2b95163061ac1e4c56d4dc0c17"} 15:09:17 INFO - Running post-run listener: copy_logs_to_upload_dir 15:09:17 INFO - Copying logs to upload dir... 15:09:17 INFO - mkdir: /builds/slave/test/build/upload/logs 15:09:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.207255 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-05-11 15:09:17.236067) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 15:09:17.239844) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479 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/9d909d159b17bbcb21715d1d0d5ac9e188cca62d265f6e1438e50b0fe332a9189bdcf7c2c03eb48fa78db9b0a2594a1505a441c24174210f7f0320fc5464ce52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a1b8ddc7fa4075e3ea308cfc97835a12afe263a37747fecd0521ad5dccc6577295e17a063c13dd16cc4b4789852182254fe36f2b95163061ac1e4c56d4dc0c17"} build_url:https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005432 build_url: 'https://queue.taskcluster.net/v1/task/cpQygpUvR-eOTqWlPzpvUA/artifacts/public/build/firefox-49.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/9d909d159b17bbcb21715d1d0d5ac9e188cca62d265f6e1438e50b0fe332a9189bdcf7c2c03eb48fa78db9b0a2594a1505a441c24174210f7f0320fc5464ce52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a1b8ddc7fa4075e3ea308cfc97835a12afe263a37747fecd0521ad5dccc6577295e17a063c13dd16cc4b4789852182254fe36f2b95163061ac1e4c56d4dc0c17"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 15:09:17.253484) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:09:17.253788) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1463002481.893789-941748479 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002119 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:09:17.334083) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 15:09:17.334404) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 15:09:17.334726) ========= ========= Total master_lag: 0.15 =========