builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-065 starttime: 1462942591.94 results: success (0) buildid: 20160510194316 builduid: acc7ff442c5a4c99ad2347ebff9a1829 revision: b8f01297e8fca2f828b996a8ddefc2009e4f35cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:31.940900) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:31.942247) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:31.942561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:31.964490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:31.964853) ========= 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=523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 21:56:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 71.0M=0s 2016-05-10 21:56:32 (71.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119742 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:32.097380) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:32.097673) ========= 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=523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018051 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:32.155328) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 21:56:32.155667) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b8f01297e8fca2f828b996a8ddefc2009e4f35cf --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 b8f01297e8fca2f828b996a8ddefc2009e4f35cf --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=523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 21:56:32,398 truncating revision to first 12 chars 2016-05-10 21:56:32,398 Setting DEBUG logging. 2016-05-10 21:56:32,398 attempt 1/10 2016-05-10 21:56:32,398 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b8f01297e8fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 21:56:33,439 unpacking tar archive at: fx-team-b8f01297e8fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.325742 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-10 21:56:33.503911) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:33.504752) ========= script_repo_revision: b8f01297e8fca2f828b996a8ddefc2009e4f35cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:33.505649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:33.505996) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 21:56:33.534064) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-10 21:56:33.534850) ========= /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=523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:56:33 INFO - MultiFileLogger online at 20160510 21:56:33 in /builds/slave/test 21:56:33 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 21:56:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:56:33 INFO - {'append_to_log': False, 21:56:33 INFO - 'base_work_dir': '/builds/slave/test', 21:56:33 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 21:56:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:56:33 INFO - 'branch': 'Fx-Team-Non-PGO', 21:56:33 INFO - 'buildbot_json_path': 'buildprops.json', 21:56:33 INFO - 'config_files': ('talos/linux_config.py',), 21:56:33 INFO - 'default_actions': ('clobber', 21:56:33 INFO - 'read-buildbot-config', 21:56:33 INFO - 'download-and-extract', 21:56:33 INFO - 'populate-webroot', 21:56:33 INFO - 'create-virtualenv', 21:56:33 INFO - 'install', 21:56:33 INFO - 'run-tests'), 21:56:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:56:33 INFO - 'download_minidump_stackwalk': True, 21:56:33 INFO - 'download_symbols': 'ondemand', 21:56:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:56:33 INFO - 'tooltool.py': '/tools/tooltool.py', 21:56:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:56:33 INFO - '/tools/misc-python/virtualenv.py')}, 21:56:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:56:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:56:33 INFO - 'installer_path': 'installer.exe', 21:56:33 INFO - 'log_level': 'info', 21:56:33 INFO - 'log_name': 'talos', 21:56:33 INFO - 'log_to_console': True, 21:56:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:56:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:56:33 INFO - 'opt_config_files': (), 21:56:33 INFO - 'pip_index': False, 21:56:33 INFO - 'sps_profile': False, 21:56:33 INFO - 'sps_profile_interval': 0, 21:56:33 INFO - 'suite': 'dromaeojs', 21:56:33 INFO - 'system_bits': '32', 21:56:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:56:33 INFO - 'title': 'talos-linux64-ix-065', 21:56:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:56:33 INFO - 'use_talos_json': True, 21:56:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:56:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:56:33 INFO - 'work_dir': 'build'} 21:56:33 INFO - ##### 21:56:33 INFO - ##### Running clobber step. 21:56:33 INFO - ##### 21:56:33 INFO - Running pre-action listener: _resource_record_pre_action 21:56:33 INFO - Running main action method: clobber 21:56:33 INFO - rmtree: /builds/slave/test/build 21:56:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:56:34 INFO - Running post-action listener: _resource_record_post_action 21:56:34 INFO - ##### 21:56:34 INFO - ##### Finished clobber step (success) 21:56:34 INFO - ##### 21:56:34 INFO - ##### 21:56:34 INFO - ##### Running read-buildbot-config step. 21:56:34 INFO - ##### 21:56:34 INFO - Running pre-action listener: _resource_record_pre_action 21:56:34 INFO - Running main action method: read_buildbot_config 21:56:34 INFO - Using buildbot properties: 21:56:34 INFO - { 21:56:34 INFO - "project": "", 21:56:34 INFO - "product": "firefox", 21:56:34 INFO - "script_repo_revision": "production", 21:56:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 21:56:34 INFO - "repository": "", 21:56:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 21:56:34 INFO - "buildid": "20160510194316", 21:56:34 INFO - "pgo_build": "False", 21:56:34 INFO - "basedir": "/builds/slave/test", 21:56:34 INFO - "buildnumber": 2960, 21:56:34 INFO - "slavename": "talos-linux64-ix-065", 21:56:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:56:34 INFO - "platform": "ubuntu64_hw", 21:56:34 INFO - "scheduler": "tests-fx-team-linux64-talos", 21:56:34 INFO - "branch": "fx-team", 21:56:34 INFO - "repo_path": "integration/fx-team", 21:56:34 INFO - "revision": "b8f01297e8fca2f828b996a8ddefc2009e4f35cf", 21:56:34 INFO - "stage_platform": "linux64", 21:56:34 INFO - "builduid": "acc7ff442c5a4c99ad2347ebff9a1829", 21:56:34 INFO - "slavebuilddir": "test" 21:56:34 INFO - } 21:56:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 21:56:34 INFO - Running post-action listener: _resource_record_post_action 21:56:34 INFO - ##### 21:56:34 INFO - ##### Finished read-buildbot-config step (success) 21:56:34 INFO - ##### 21:56:34 INFO - ##### 21:56:34 INFO - ##### Running download-and-extract step. 21:56:34 INFO - ##### 21:56:34 INFO - Running pre-action listener: _resource_record_pre_action 21:56:34 INFO - Running main action method: download_and_extract 21:56:34 INFO - mkdir: /builds/slave/test/build/tests 21:56: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')]} 21:56:34 INFO - https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:56:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:56:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:56:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:39 INFO - Downloaded 1361 bytes. 21:56:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 21:56:39 INFO - Using the following test package requirements: 21:56:39 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:56:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:56:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:56:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'jsshell-linux-x86_64.zip'], 21:56:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:56:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 21:56:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:56:39 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:56:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:56:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 21:56:39 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:56:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:56: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')]} 21:56:39 INFO - https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:56:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:56:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:56:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:40 INFO - Downloaded 22329023 bytes. 21:56:40 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'] 21:56:40 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 21:56:41 INFO - Return code: 0 21:56:41 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 21:56:41 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')]} 21:56:41 INFO - https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:56:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:56:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 21:56:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:47 INFO - Downloaded 22329023 bytes. 21:56:47 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'] 21:56:47 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 21:56:48 INFO - Return code: 0 21:56:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:56:48 INFO - https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:56:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:56:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 21:56:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:49 INFO - Downloaded 10840152 bytes. 21:56:49 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'] 21:56:49 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 21:56:49 INFO - Return code: 0 21:56:49 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')]} 21:56:49 INFO - https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:56:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:56:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:56:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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 21:56:51 INFO - Downloaded 54676723 bytes. 21:56:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:56:51 INFO - mkdir: /builds/slave/test/properties 21:56:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:56:51 INFO - Writing to file /builds/slave/test/properties/build_url 21:56:51 INFO - Contents: 21:56:51 INFO - build_url:https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 21:56:52 INFO - Running post-action listener: _resource_record_post_action 21:56:52 INFO - Running post-action listener: set_extra_try_arguments 21:56:52 INFO - ##### 21:56:52 INFO - ##### Finished download-and-extract step (success) 21:56:52 INFO - ##### 21:56:52 INFO - ##### 21:56:52 INFO - ##### Running populate-webroot step. 21:56:52 INFO - ##### 21:56:52 INFO - Running pre-action listener: _resource_record_pre_action 21:56:52 INFO - Running main action method: populate_webroot 21:56:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:56:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:56:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:56:52 INFO - '--tppagecycles', 21:56:52 INFO - '7'], 21:56:52 INFO - 'tests': ['tsvgm']}}, 21:56:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:56:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:56:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:56:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:56:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:56:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:56:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['damp', 'tps']}, 21:56:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'tests': ['damp', 'tps']}, 21:56:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['dromaeo_dom']}, 21:56:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:56:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['a11yr', 21:56:52 INFO - 'ts_paint', 21:56:52 INFO - 'tpaint', 21:56:52 INFO - 'sessionrestore', 21:56:52 INFO - 'sessionrestore_no_auto_restore', 21:56:52 INFO - 'tabpaint']}, 21:56:52 INFO - 'other-e10s': {'tests': ['a11yr', 21:56:52 INFO - 'ts_paint', 21:56:52 INFO - 'tpaint', 21:56:52 INFO - 'sessionrestore', 21:56:52 INFO - 'sessionrestore_no_auto_restore', 21:56:52 INFO - 'tabpaint']}, 21:56:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:56:52 INFO - 'ts_paint', 21:56:52 INFO - 'tpaint', 21:56:52 INFO - 'sessionrestore', 21:56:52 INFO - 'sessionrestore_no_auto_restore', 21:56:52 INFO - 'tabpaint']}, 21:56:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:56:52 INFO - 'ts_paint', 21:56:52 INFO - 'tpaint', 21:56:52 INFO - 'sessionrestore', 21:56:52 INFO - 'sessionrestore_no_auto_restore', 21:56:52 INFO - 'tabpaint']}, 21:56:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['a11yr', 21:56:52 INFO - 'ts_paint', 21:56:52 INFO - 'tpaint', 21:56:52 INFO - 'sessionrestore', 21:56:52 INFO - 'sessionrestore_no_auto_restore', 21:56:52 INFO - 'tabpaint']}, 21:56:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['a11yr', 21:56:52 INFO - 'ts_paint', 21:56:52 INFO - 'tpaint', 21:56:52 INFO - 'sessionrestore', 21:56:52 INFO - 'sessionrestore_no_auto_restore', 21:56:52 INFO - 'tabpaint']}, 21:56:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['tsvgx', 21:56:52 INFO - 'tsvgr_opacity', 21:56:52 INFO - 'tart', 21:56:52 INFO - 'tscrollx', 21:56:52 INFO - 'cart']}, 21:56:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:56:52 INFO - 'tsvgr_opacity', 21:56:52 INFO - 'tart', 21:56:52 INFO - 'tscrollx', 21:56:52 INFO - 'cart']}, 21:56:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'talos_options': ['--disable-e10s'], 21:56:52 INFO - 'tests': ['tp5o']}, 21:56:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'tests': ['tp5o']}, 21:56:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'talos_options': ['--disable-e10s', 21:56:52 INFO - '--xperf_path', 21:56:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:56:52 INFO - 'tests': ['tp5n']}, 21:56:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:56:52 INFO - 'talos_options': ['--xperf_path', 21:56:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:56:52 INFO - 'tests': ['tp5n']}}, 21:56:52 INFO - 'talos.zip': {'path': '', 21:56:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:56:52 INFO - Running post-action listener: _resource_record_post_action 21:56:52 INFO - ##### 21:56:52 INFO - ##### Finished populate-webroot step (success) 21:56:52 INFO - ##### 21:56:52 INFO - ##### 21:56:52 INFO - ##### Running create-virtualenv step. 21:56:52 INFO - ##### 21:56:52 INFO - Running pre-action listener: _resource_record_pre_action 21:56:52 INFO - Running main action method: create_virtualenv 21:56:52 INFO - Creating virtualenv /builds/slave/test/build/venv 21:56:52 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 21:56:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:56:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:56:52 INFO - Using real prefix '/usr' 21:56:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:56:53 INFO - Installing distribute.............................................................................................................................................................................................done. 21:56:54 INFO - Installing pip.................done. 21:56:54 INFO - Return code: 0 21:56:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:54 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:56:54 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 21:56:54 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 21:56:54 INFO - Using env: {'DISPLAY': ':0', 21:56:54 INFO - 'HOME': '/home/cltbld', 21:56:54 INFO - 'LANG': 'en_US.UTF-8', 21:56:54 INFO - 'LANGUAGE': 'en_US:en', 21:56:54 INFO - 'LOGNAME': 'cltbld', 21:56:54 INFO - 'MAIL': '/var/mail/cltbld', 21:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:54 INFO - 'MOZ_NO_REMOTE': '1', 21:56:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:54 INFO - 'NO_EM_RESTART': '1', 21:56:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:54 INFO - 'PWD': '/builds/slave/test', 21:56:54 INFO - 'SHELL': '/bin/bash', 21:56:54 INFO - 'SHLVL': '1', 21:56:54 INFO - 'TERM': 'linux', 21:56:54 INFO - 'TMOUT': '86400', 21:56:54 INFO - 'USER': 'cltbld', 21:56:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:54 INFO - '_': '/tools/buildbot/bin/python'} 21:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:55 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 21:56:55 INFO - Cleaning up... 21:56:55 INFO - Return code: 0 21:56:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:56:55 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')]} 21:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:55 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')]} 21:56:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:55 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:56:55 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 21:56:55 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 21:56:55 INFO - Using env: {'DISPLAY': ':0', 21:56:55 INFO - 'HOME': '/home/cltbld', 21:56:55 INFO - 'LANG': 'en_US.UTF-8', 21:56:55 INFO - 'LANGUAGE': 'en_US:en', 21:56:55 INFO - 'LOGNAME': 'cltbld', 21:56:55 INFO - 'MAIL': '/var/mail/cltbld', 21:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:55 INFO - 'MOZ_NO_REMOTE': '1', 21:56:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:55 INFO - 'NO_EM_RESTART': '1', 21:56:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:55 INFO - 'PWD': '/builds/slave/test', 21:56:55 INFO - 'SHELL': '/bin/bash', 21:56:55 INFO - 'SHLVL': '1', 21:56:55 INFO - 'TERM': 'linux', 21:56:55 INFO - 'TMOUT': '86400', 21:56:55 INFO - 'USER': 'cltbld', 21:56:55 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:56:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:55 INFO - '_': '/tools/buildbot/bin/python'} 21:56:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:55 INFO - Downloading/unpacking psutil>=0.7.1 21:56:55 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 21:56:55 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 21:56:55 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 21:56:55 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 21:56:55 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 21:56:55 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 21:56:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:56:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:57 INFO - Installing collected packages: psutil 21:56:57 INFO - Running setup.py install for psutil 21:56:57 INFO - building 'psutil._psutil_linux' extension 21:56:57 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 21:56:57 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 21:56:57 INFO - building 'psutil._psutil_posix' extension 21:56:57 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 21:56:57 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 21:56:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:57 INFO - Successfully installed psutil 21:56:57 INFO - Cleaning up... 21:56:57 INFO - Return code: 0 21:56:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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', '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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:56: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 21:56: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 mozsystemmonitor==0.0.0 21:56:57 INFO - Using env: {'DISPLAY': ':0', 21:56:57 INFO - 'HOME': '/home/cltbld', 21:56:57 INFO - 'LANG': 'en_US.UTF-8', 21:56:57 INFO - 'LANGUAGE': 'en_US:en', 21:56:57 INFO - 'LOGNAME': 'cltbld', 21:56:57 INFO - 'MAIL': '/var/mail/cltbld', 21:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:57 INFO - 'MOZ_NO_REMOTE': '1', 21:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:57 INFO - 'NO_EM_RESTART': '1', 21:56:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:57 INFO - 'PWD': '/builds/slave/test', 21:56:57 INFO - 'SHELL': '/bin/bash', 21:56:57 INFO - 'SHLVL': '1', 21:56:57 INFO - 'TERM': 'linux', 21:56:57 INFO - 'TMOUT': '86400', 21:56:57 INFO - 'USER': 'cltbld', 21:56:57 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:57 INFO - '_': '/tools/buildbot/bin/python'} 21:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:56:58 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 21:56:58 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 21:56:58 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 21:56:58 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 21:56:58 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 21:56:58 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 21:57:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:57:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:57:00 INFO - Installing collected packages: mozsystemmonitor 21:57:00 INFO - Running setup.py install for mozsystemmonitor 21:57:00 INFO - Successfully installed mozsystemmonitor 21:57:00 INFO - Cleaning up... 21:57:00 INFO - Return code: 0 21:57:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:57:00 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')]} 21:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:00 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')]} 21:57:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:00 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:57:00 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 21:57:00 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 21:57:00 INFO - Using env: {'DISPLAY': ':0', 21:57:00 INFO - 'HOME': '/home/cltbld', 21:57:00 INFO - 'LANG': 'en_US.UTF-8', 21:57:00 INFO - 'LANGUAGE': 'en_US:en', 21:57:00 INFO - 'LOGNAME': 'cltbld', 21:57:00 INFO - 'MAIL': '/var/mail/cltbld', 21:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:00 INFO - 'MOZ_NO_REMOTE': '1', 21:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:00 INFO - 'NO_EM_RESTART': '1', 21:57:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:00 INFO - 'PWD': '/builds/slave/test', 21:57:00 INFO - 'SHELL': '/bin/bash', 21:57:00 INFO - 'SHLVL': '1', 21:57:00 INFO - 'TERM': 'linux', 21:57:00 INFO - 'TMOUT': '86400', 21:57:00 INFO - 'USER': 'cltbld', 21:57:00 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:00 INFO - '_': '/tools/buildbot/bin/python'} 21:57:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:00 INFO - Downloading/unpacking blobuploader==1.2.4 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:02 INFO - Downloading blobuploader-1.2.4.tar.gz 21:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:57:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:57:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:02 INFO - Downloading docopt-0.6.1.tar.gz 21:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:57:03 INFO - Installing collected packages: blobuploader, requests, docopt 21:57:03 INFO - Running setup.py install for blobuploader 21:57:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:57:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:57:03 INFO - Running setup.py install for requests 21:57:03 INFO - Running setup.py install for docopt 21:57:03 INFO - Successfully installed blobuploader requests docopt 21:57:03 INFO - Cleaning up... 21:57:03 INFO - Return code: 0 21:57:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:57:03 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')]} 21:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:03 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')]} 21:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:03 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:57:03 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 21:57:03 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 21:57:03 INFO - Using env: {'DISPLAY': ':0', 21:57:03 INFO - 'HOME': '/home/cltbld', 21:57:03 INFO - 'LANG': 'en_US.UTF-8', 21:57:03 INFO - 'LANGUAGE': 'en_US:en', 21:57:03 INFO - 'LOGNAME': 'cltbld', 21:57:03 INFO - 'MAIL': '/var/mail/cltbld', 21:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:03 INFO - 'MOZ_NO_REMOTE': '1', 21:57:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:03 INFO - 'NO_EM_RESTART': '1', 21:57:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:03 INFO - 'PWD': '/builds/slave/test', 21:57:03 INFO - 'SHELL': '/bin/bash', 21:57:03 INFO - 'SHLVL': '1', 21:57:03 INFO - 'TERM': 'linux', 21:57:03 INFO - 'TMOUT': '86400', 21:57:03 INFO - 'USER': 'cltbld', 21:57:03 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:03 INFO - '_': '/tools/buildbot/bin/python'} 21:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:57:03 INFO - Running setup.py (path:/tmp/pip-gV5DuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:57:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:57:03 INFO - Running setup.py (path:/tmp/pip-XdRB3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:57:04 INFO - Running setup.py (path:/tmp/pip-Dg05Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:57:04 INFO - Running setup.py (path:/tmp/pip-7VCUOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:57:04 INFO - Running setup.py (path:/tmp/pip-bFZj_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:57:04 INFO - Running setup.py (path:/tmp/pip-0yvByM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:57:04 INFO - Running setup.py (path:/tmp/pip-7t9Fct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:57:04 INFO - Running setup.py (path:/tmp/pip-qaBSUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:57:04 INFO - Running setup.py (path:/tmp/pip-11YkYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:57:04 INFO - Running setup.py (path:/tmp/pip-qHR19r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:57:04 INFO - Running setup.py (path:/tmp/pip-os0nX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:57:04 INFO - Running setup.py (path:/tmp/pip-DvhIf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:57:04 INFO - Running setup.py (path:/tmp/pip-Yafll7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:57:04 INFO - Running setup.py (path:/tmp/pip-iXZlcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:04 INFO - Running setup.py (path:/tmp/pip-AFcAfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:57:04 INFO - Running setup.py (path:/tmp/pip-2IxYR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:57:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:57:05 INFO - Running setup.py (path:/tmp/pip-L3pZMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:57:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:57:05 INFO - Running setup.py install for manifestparser 21:57:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Running setup.py install for mozcrash 21:57:05 INFO - Running setup.py install for mozdebug 21:57:05 INFO - Running setup.py install for mozdevice 21:57:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Running setup.py install for mozfile 21:57:05 INFO - Running setup.py install for mozhttpd 21:57:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Running setup.py install for mozinfo 21:57:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Running setup.py install for mozInstall 21:57:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:57:05 INFO - Running setup.py install for mozleak 21:57:06 INFO - Running setup.py install for mozlog 21:57:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Running setup.py install for moznetwork 21:57:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Running setup.py install for mozprocess 21:57:06 INFO - Running setup.py install for mozprofile 21:57:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Running setup.py install for mozrunner 21:57:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Running setup.py install for mozscreenshot 21:57:06 INFO - Running setup.py install for moztest 21:57:06 INFO - Running setup.py install for mozversion 21:57:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:57:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:57:06 INFO - Cleaning up... 21:57:06 INFO - Return code: 0 21:57:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:57:06 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')]} 21:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:06 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')]} 21:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:06 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:57:06 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 21:57:06 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 21:57:06 INFO - Using env: {'DISPLAY': ':0', 21:57:06 INFO - 'HOME': '/home/cltbld', 21:57:06 INFO - 'LANG': 'en_US.UTF-8', 21:57:06 INFO - 'LANGUAGE': 'en_US:en', 21:57:06 INFO - 'LOGNAME': 'cltbld', 21:57:06 INFO - 'MAIL': '/var/mail/cltbld', 21:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:06 INFO - 'MOZ_NO_REMOTE': '1', 21:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:06 INFO - 'NO_EM_RESTART': '1', 21:57:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:06 INFO - 'PWD': '/builds/slave/test', 21:57:06 INFO - 'SHELL': '/bin/bash', 21:57:06 INFO - 'SHLVL': '1', 21:57:06 INFO - 'TERM': 'linux', 21:57:06 INFO - 'TMOUT': '86400', 21:57:06 INFO - 'USER': 'cltbld', 21:57:06 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:06 INFO - '_': '/tools/buildbot/bin/python'} 21:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:57:07 INFO - Running setup.py (path:/tmp/pip-mGFQoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:57:07 INFO - Running setup.py (path:/tmp/pip-7KBnZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:57:07 INFO - Running setup.py (path:/tmp/pip-g_afcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:57:07 INFO - Running setup.py (path:/tmp/pip-QpdXzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:57:07 INFO - Running setup.py (path:/tmp/pip-lD6t8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:57:07 INFO - Running setup.py (path:/tmp/pip-a9dXmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:57:07 INFO - Running setup.py (path:/tmp/pip-U8pfS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:57:07 INFO - Running setup.py (path:/tmp/pip-b1oEwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:57:07 INFO - Running setup.py (path:/tmp/pip-B07z3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:57:07 INFO - Running setup.py (path:/tmp/pip-R7A83O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:57:07 INFO - Running setup.py (path:/tmp/pip-FFb66l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:57:07 INFO - Running setup.py (path:/tmp/pip-b5BfEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:57:07 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)) 21:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:57:07 INFO - Running setup.py (path:/tmp/pip-qCKuPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:57:08 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)) 21:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:57:08 INFO - Running setup.py (path:/tmp/pip-qEjKeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:57:08 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)) 21:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:08 INFO - Running setup.py (path:/tmp/pip-5a8c9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:57:08 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)) 21:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:57:08 INFO - Running setup.py (path:/tmp/pip-lgUgEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:57:08 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)) 21:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:57:08 INFO - Running setup.py (path:/tmp/pip-2Tg2vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:57:08 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)) 21:57:08 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)) 21:57:08 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)) 21:57:08 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)) 21:57:08 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)) 21:57:08 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)) 21:57:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:10 INFO - Downloading blessings-1.6.tar.gz 21:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:57:10 INFO - Installing collected packages: blessings 21:57:10 INFO - Running setup.py install for blessings 21:57:10 INFO - Successfully installed blessings 21:57:10 INFO - Cleaning up... 21:57:10 INFO - Return code: 0 21:57:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:57:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:57:10 INFO - Reading from file tmpfile_stdout 21:57:10 INFO - Current package versions: 21:57:10 INFO - argparse == 1.2.1 21:57:10 INFO - blessings == 1.6 21:57:10 INFO - blobuploader == 1.2.4 21:57:10 INFO - docopt == 0.6.1 21:57:10 INFO - manifestparser == 1.1 21:57:10 INFO - mozInstall == 1.12 21:57:10 INFO - mozcrash == 0.17 21:57:10 INFO - mozdebug == 0.1 21:57:10 INFO - mozdevice == 0.48 21:57:10 INFO - mozfile == 1.2 21:57:10 INFO - mozhttpd == 0.7 21:57:10 INFO - mozinfo == 0.9 21:57:10 INFO - mozleak == 0.1 21:57:10 INFO - mozlog == 3.1 21:57:10 INFO - moznetwork == 0.27 21:57:10 INFO - mozprocess == 0.22 21:57:10 INFO - mozprofile == 0.28 21:57:10 INFO - mozrunner == 6.11 21:57:10 INFO - mozscreenshot == 0.1 21:57:10 INFO - mozsystemmonitor == 0.0 21:57:10 INFO - moztest == 0.7 21:57:10 INFO - mozversion == 1.4 21:57:10 INFO - psutil == 3.1.1 21:57:10 INFO - requests == 1.2.3 21:57:10 INFO - wsgiref == 0.1.2 21:57:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:57:10 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')]} 21:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:10 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')]} 21:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:10 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:57:10 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 21:57:10 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 21:57:10 INFO - Using env: {'DISPLAY': ':0', 21:57:10 INFO - 'HOME': '/home/cltbld', 21:57:10 INFO - 'LANG': 'en_US.UTF-8', 21:57:10 INFO - 'LANGUAGE': 'en_US:en', 21:57:10 INFO - 'LOGNAME': 'cltbld', 21:57:10 INFO - 'MAIL': '/var/mail/cltbld', 21:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:10 INFO - 'MOZ_NO_REMOTE': '1', 21:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:10 INFO - 'NO_EM_RESTART': '1', 21:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:10 INFO - 'PWD': '/builds/slave/test', 21:57:10 INFO - 'SHELL': '/bin/bash', 21:57:10 INFO - 'SHLVL': '1', 21:57:10 INFO - 'TERM': 'linux', 21:57:10 INFO - 'TMOUT': '86400', 21:57:10 INFO - 'USER': 'cltbld', 21:57:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:10 INFO - '_': '/tools/buildbot/bin/python'} 21:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 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)) 21:57:10 INFO - Cleaning up... 21:57:10 INFO - Return code: 0 21:57:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:57:10 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')]} 21:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:10 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')]} 21:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:10 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 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:57:10 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 21:57:10 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 21:57:10 INFO - Using env: {'DISPLAY': ':0', 21:57:10 INFO - 'HOME': '/home/cltbld', 21:57:10 INFO - 'LANG': 'en_US.UTF-8', 21:57:10 INFO - 'LANGUAGE': 'en_US:en', 21:57:10 INFO - 'LOGNAME': 'cltbld', 21:57:10 INFO - 'MAIL': '/var/mail/cltbld', 21:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:10 INFO - 'MOZ_NO_REMOTE': '1', 21:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:10 INFO - 'NO_EM_RESTART': '1', 21:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:10 INFO - 'PWD': '/builds/slave/test', 21:57:10 INFO - 'SHELL': '/bin/bash', 21:57:10 INFO - 'SHLVL': '1', 21:57:10 INFO - 'TERM': 'linux', 21:57:10 INFO - 'TMOUT': '86400', 21:57:10 INFO - 'USER': 'cltbld', 21:57:10 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:10 INFO - '_': '/tools/buildbot/bin/python'} 21:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:11 INFO - Downloading/unpacking jsonschema 21:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:57:12 INFO - Downloading/unpacking functools32 (from jsonschema) 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:13 INFO - Downloading functools32-3.2.3-2.tar.gz 21:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:57:13 INFO - warning: no files found matching '*.txt' 21:57:13 INFO - no previously-included directories found matching 'build' 21:57:13 INFO - no previously-included directories found matching 'dist' 21:57:13 INFO - no previously-included directories found matching '.git*' 21:57:13 INFO - Installing collected packages: jsonschema, functools32 21:57:13 INFO - Running setup.py install for functools32 21:57:13 INFO - warning: no files found matching '*.txt' 21:57:13 INFO - no previously-included directories found matching 'build' 21:57:13 INFO - no previously-included directories found matching 'dist' 21:57:13 INFO - no previously-included directories found matching '.git*' 21:57:13 INFO - Successfully installed jsonschema functools32 21:57:13 INFO - Cleaning up... 21:57:13 INFO - Return code: 0 21:57:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:57:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1837988f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1caf8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d7a770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, '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': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', '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 21:57:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:57:13 INFO - Using env: {'DISPLAY': ':0', 21:57:13 INFO - 'HOME': '/home/cltbld', 21:57:13 INFO - 'LANG': 'en_US.UTF-8', 21:57:13 INFO - 'LANGUAGE': 'en_US:en', 21:57:13 INFO - 'LOGNAME': 'cltbld', 21:57:13 INFO - 'MAIL': '/var/mail/cltbld', 21:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:13 INFO - 'MOZ_NO_REMOTE': '1', 21:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:13 INFO - 'NO_EM_RESTART': '1', 21:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:13 INFO - 'PWD': '/builds/slave/test', 21:57:13 INFO - 'SHELL': '/bin/bash', 21:57:13 INFO - 'SHLVL': '1', 21:57:13 INFO - 'TERM': 'linux', 21:57:13 INFO - 'TMOUT': '86400', 21:57:13 INFO - 'USER': 'cltbld', 21:57:13 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:13 INFO - '_': '/tools/buildbot/bin/python'} 21:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:57:13 INFO - Downloading/unpacking flake8 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57: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 21:57:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:57:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:57:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:57:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:57:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:57:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:57:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:57:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:57:16 INFO - no previously-included directories found matching 'docs/_build' 21:57:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:57:16 INFO - Running setup.py install for pep8 21:57:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:57:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:57:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:57:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:57:16 INFO - no previously-included directories found matching 'docs/_build' 21:57:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:57:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:57:16 INFO - Cleaning up... 21:57:16 INFO - Return code: 0 21:57:16 INFO - Running post-action listener: _resource_record_post_action 21:57:16 INFO - Running post-action listener: _start_resource_monitoring 21:57:16 INFO - Starting resource monitoring. 21:57:16 INFO - ##### 21:57:16 INFO - ##### Finished create-virtualenv step (success) 21:57:16 INFO - ##### 21:57:16 INFO - ##### 21:57:16 INFO - ##### Running install step. 21:57:16 INFO - ##### 21:57:16 INFO - Running pre-action listener: _resource_record_pre_action 21:57:16 INFO - Running main action method: install 21:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:57:16 INFO - Reading from file tmpfile_stdout 21:57:16 INFO - Detecting whether we're running mozinstall >=1.0... 21:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:57:16 INFO - Reading from file tmpfile_stdout 21:57:16 INFO - Output received: 21:57:16 INFO - Usage: mozinstall [options] installer 21:57:16 INFO - Options: 21:57:16 INFO - -h, --help show this help message and exit 21:57:16 INFO - -d DEST, --destination=DEST 21:57:16 INFO - Directory to install application into. [default: 21:57:16 INFO - "/builds/slave/test"] 21:57:16 INFO - --app=APP Application being installed. [default: firefox] 21:57:16 INFO - mkdir: /builds/slave/test/build/application 21:57:16 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'] 21:57:16 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 21:57:32 INFO - Reading from file tmpfile_stdout 21:57:32 INFO - Output received: 21:57:32 INFO - /builds/slave/test/build/application/firefox/firefox 21:57:32 INFO - Running post-action listener: _resource_record_post_action 21:57:32 INFO - ##### 21:57:32 INFO - ##### Finished install step (success) 21:57:32 INFO - ##### 21:57:32 INFO - ##### 21:57:32 INFO - ##### Running run-tests step. 21:57:32 INFO - ##### 21:57:32 INFO - Running pre-action listener: _resource_record_pre_action 21:57:32 INFO - Running main action method: run_tests 21:57:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:57:32 INFO - Python 2.7.3 21:57:32 INFO - Return code: 0 21:57:32 INFO - grabbing minidump binary from tooltool 21:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 21:57:32 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 0x1e099c0>, '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 0x1e0a8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0ad50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:57:32 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 21:57:32 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 21:57:32 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 21:57:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:57:32 INFO - Return code: 0 21:57:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:57:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:57:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:57:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:57:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:57:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:57:33 INFO - Return code: 0 21:57:33 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/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 21:57:33 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/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 21:57:33 INFO - Using env: {'DISPLAY': ':0', 21:57:33 INFO - 'HOME': '/home/cltbld', 21:57:33 INFO - 'LANG': 'en_US.UTF-8', 21:57:33 INFO - 'LANGUAGE': 'en_US:en', 21:57:33 INFO - 'LOGNAME': 'cltbld', 21:57:33 INFO - 'MAIL': '/var/mail/cltbld', 21:57:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:57:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:33 INFO - 'MOZ_NO_REMOTE': '1', 21:57:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:57:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:57:33 INFO - 'NO_EM_RESTART': '1', 21:57:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:33 INFO - 'PWD': '/builds/slave/test', 21:57:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:57:33 INFO - 'SHELL': '/bin/bash', 21:57:33 INFO - 'SHLVL': '1', 21:57:33 INFO - 'TERM': 'linux', 21:57:33 INFO - 'TMOUT': '86400', 21:57:33 INFO - 'USER': 'cltbld', 21:57:33 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489', 21:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:33 INFO - '_': '/tools/buildbot/bin/python'} 21:57:33 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/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 21:57:33 INFO - mozversion application_buildid: 20160510194316 21:57:33 INFO - mozversion application_changeset: b8f01297e8fca2f828b996a8ddefc2009e4f35cf 21:57:33 INFO - mozversion application_display_name: Nightly 21:57:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:57:33 INFO - mozversion application_name: Firefox 21:57:33 INFO - mozversion application_remotingname: firefox 21:57:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:57:33 INFO - mozversion application_vendor: Mozilla 21:57:33 INFO - mozversion application_version: 49.0a1 21:57:33 INFO - mozversion platform_buildid: 20160510194316 21:57:33 INFO - mozversion platform_changeset: b8f01297e8fca2f828b996a8ddefc2009e4f35cf 21:57:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:57:33 INFO - mozversion platform_version: 49.0a1 21:57:33 INFO - using testdate: 1462942653 21:57:33 INFO - actual date: 1462942653 21:57:33 INFO - starting webserver on 'localhost:55223' 21:57:33 INFO - SUITE-START | Running 2 tests 21:57:33 INFO - TEST-START | dromaeo_css 21:57:33 INFO - operating with platform_type : linux_ 21:57:33 INFO - Initialising browser for dromaeo_css test... 21:57:33 INFO - TEST-INFO | started process 7780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQBI21y/profile http://localhost:55223/getInfo.html) 21:57:38 INFO - PROCESS | 7780 | __metrics Screen width/height:1600/1200 21:57:38 INFO - PROCESS | 7780 | colorDepth:24 21:57:38 INFO - PROCESS | 7780 | Browser inner width/height: 1024/697 21:57:38 INFO - PROCESS | 7780 | __metrics 21:57:39 ERROR - PROCESS | 7780 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:57:40 INFO - TEST-INFO | 7780: exit 0 21:57:40 INFO - Browser initialized. 21:57:40 INFO - Running cycle 1/1 for dromaeo_css test... 21:57:40 INFO - TEST-INFO | started process 7861 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQBI21y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:57:52 INFO - PROCESS | 7861 | RSS: Main: 153731072 21:57:52 INFO - PROCESS | 7861 | 21:58:43 INFO - PROCESS | 7861 | 1462942723413 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] 22:00:55 INFO - PROCESS | 7861 | Cycle 1(1): loaded http://localhost:55223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55223/tests/dromaeo/cssquery-ext.html) 22:00:56 INFO - PROCESS | 7861 | RSS: Main: 231817216 22:00:56 INFO - PROCESS | 7861 | 22:03:59 INFO - PROCESS | 7861 | Cycle 1(1): loaded http://localhost:55223/tests/dromaeo/cssquery-ext.html (next: http://localhost:55223/tests/dromaeo/cssquery-jquery.html) 22:03:59 INFO - PROCESS | 7861 | RSS: Main: 238391296 22:03:59 INFO - PROCESS | 7861 | 22:07:02 INFO - PROCESS | 7861 | Cycle 1(1): loaded http://localhost:55223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55223/tests/dromaeo/cssquery-mootools.html) 22:07:02 INFO - PROCESS | 7861 | RSS: Main: 232738816 22:07:02 INFO - PROCESS | 7861 | 22:10:04 INFO - PROCESS | 7861 | Cycle 1(1): loaded http://localhost:55223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55223/tests/dromaeo/cssquery-prototype.html) 22:10:05 INFO - PROCESS | 7861 | RSS: Main: 232148992 22:10:05 INFO - PROCESS | 7861 | 22:13:08 INFO - PROCESS | 7861 | Cycle 1(1): loaded http://localhost:55223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55223/tests/dromaeo/cssquery-yui.html) 22:13:08 INFO - PROCESS | 7861 | RSS: Main: 214286336 22:13:08 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | Cycle 1(1): loaded http://localhost:55223/tests/dromaeo/cssquery-yui.html (next: http://localhost:55223/tests/dromaeo/cssquery-dojo.html) 22:16:11 INFO - PROCESS | 7861 | RSS: Main: 269492224 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | __start_tp_report 22:16:11 INFO - PROCESS | 7861 | _x_x_mozilla_page_load 22:16:11 INFO - PROCESS | 7861 | _x_x_mozilla_page_load_details 22:16:11 INFO - PROCESS | 7861 | |i|pagename|runs| 22:16:11 INFO - PROCESS | 7861 | |0;dojo.html;2649.960250596241;2672.305200647831;2695.353115252339;2726.2230264374643;2733.958990615141;4668.323093151491;4683.274092515661;4690.812367505304;4786.952130478699;4803.423589169301;4649.37233473481;4683.531646835315;4714.269288260317;4714.882127946803;4718.716876987383;4251.914961700764;4269.316909294514;4346.348047792841;4353.107612939356;4366.366876802862;4480.798364073608;4492.640588752892;4543;4573.7027093239;4585.610223131038;4290.678199135046;4330.653547716207;4336.111097225062;4482.036362182114;4513.984353520481;4347.608715215614;4374.759388233646;4414.426124603813;4453.755043472608;4493.977530112328;4418.116376724668;4434.312681534368;4440.178566965122;4475.977620111911;4477.686561940665;4516;4566.223741963874;4612.976935115303;4767.618590512785;4772.76136193189;5082.390113186404;5099.643024988252;5111.182210846284;5113.8721531962;5132.717700526469;5086.745662716887;5101.20931255656;5103.2855400244;5104.489551044866;5143.151380022292;4633.281841314635;4764.14245435818;4803.255495398219;4822.42130944289;4825.855224343275;5310.8937821244135;5322.600804939645;5344.812931547455;5346.358436987586;5350.277712508761;5091.516305950929;5128.974355128201;5131.050755610224;5134.922976155361;5140.7943682253035;4994.450610432849;5119.59043276537;5148.4079330876675;5151.2530683050745;5161.380634323905;5144.819931302386;5145.794168233304;5154.613403994716;5161.845144645667;5194.948050519522;5119.692818430906;5136;5138.97430512845;5149.613778966593;5190.688558686491;4313.1158112586845;4347.56524347569;4355.586219309161;4356.6732495063;4425.645948324126;3867.7485963412287;3881.301365754135;3888.3778595424596;3898.8245528951265;3902.765834049966;4334.32817913224;4346.456692913386;4350.71720338177;4376.978115109468;4379.1241751649795;5185.611079169077;5192.532672059533;5194.35070616173;5195.740212989411;5240.633155679142;4269.210196113729;4376.934345984812;4380.036391993756;4384.868453946387;4437.134758722018;4563.726176429424;4567.566081222345;4611.146937816515;4672.789724462407;4682.74244916526;4798.760061996886;4817.662763606584;4820.879478013007;4824.0834241494;4868.41579010521;5111.744412779421;5127.4872512748425;5142.794288228579;5143.71709555978;5157;4698.506656800987;4699.788509517149;4749.786259618395;4763.904721905612;4808.711477311373;5000.2999580058095;5001.949980500149;5002.474740152232;5017.37282839645;5050.267711181931;5050.444451110301;5058.34241548611;5068.113080211022;5070.112730272262;5098.847034588968;4855.684380515255;4871.805127794849;4898.828541001048;4899.755012249445;4918.606511479002;4959.082569724613;4962.280469331999;4964.7765850537535;4966.478519755518;5008.724520151427;4861.683990540745;4870.488598697086;4870.732109733857;4912.238603016538;4916.139675556692;4783.832565860317;4903.019396120719;4946.505349465025;4956.900861982668;4980.526849949249;4857.295692124693;4884.545740239134;4891.95108048915;4892.828750993698;4935.4570997190995 22:16:11 INFO - PROCESS | 7861 | |1;ext.html;573.2948473377745;592.7628948420631;605.6487237402307;606.3814908793029;607.1166452811159;41742.79128604357;43375;43555.56444435551;43621.345679814825;47721.28418073723;41097.58902410975;41406;41451.37822932658;41500.37749433753;41559.792201039025;7935.52386856789;8073.152319006508;8075.434719569633;8076.071251806029;8082.353411727077;7947.086085100201;8026.6789328427085;8032.19678032195;8034.116247212802;8034.5982700865015;8017.318527925104;8044.6782128714785;8051.396145289127;8051.758447246592;8055.073666528337;7965.322947549465;8000.559969201691;8021.398395120389;8025.077116131662;8025.357971362277;7857.921420785776;7940.602969851484;7941.166177551389;7946.562939038351;7955.880661790078;38931.805340973115;39204.019899502455;39267.21465570701;39317.41023884644;39408;3197.3445443684222;3325.985574399807;3326.185084654275;3707.703383729295;3913.0608653923136;29408.264793380124;29947.25131871699;30086.398272034654;30167.396652067055;30655;3116.7350775184136;3184.442722523549;3237.174520497258;3238.3685181389637;3245.902622921316;243716;244403;244459;245100.77449612817;245728;48003.75998119987;49559.256611150144;54038;56281.71859140678;56296;59730;59862.40137598568;59926.70036649789;59978.700106499185;60062.399376005684;12733.172343797623;13973.79039314411;14103.71792564143;14140.363683634261;14187.78718319226;9666.661666841626;9904;10186.898131018743;10721.83917241242;10729.03438690506;16601.833981660027;16698.749518756984;16734.330626775038;16738.91630541864;16739.497815065566;6687.732490700415;6805.863882722318;6806.727730890808;6971.8605627887155;7018.912068629371;880.1990188928045;972.1445128287204;973.9269554783419;982.4841957971979;1033.9069483746498;28013.859930700622;28709.42581148407;28859.422811543653;28866.892601384574;29039.41921161565;1040.7294103533136;1044.2011860926398;1060.4962642744788;1066.1417558865187;1134.4384529657873;14960.70078598422;14971.475998340005;15229.390824367123;15407.46073887431;15492.767608485881;328729;331396;331789;334534;334719;49965.75017124891;50036;50103.24845127326;50115.74942125266;50209.24686129711;1173.9413029348498;1284.7751643462357;1300.089937044073;1310.4692599497027;1315.4738104758173;28640.28399289951;29781.255468613457;29787.10638680843;29974.550381744728;30192.84903575468;42144.578554214066;42285;42733.78633106814;42749.786251068545;42801.357979629705;253226;254010;254401;255562;256017;31999.880004199742;33065.66934330626;41399.58600413957;42229.57770422257;42696.35955460733;42875;43033.13933721245;43380.34929476123;43767.78116109399;43820.34269486024;36256.81871590625;36928.630713693594;37137.62862371342;37359.25281494409;37381.6261837389;36390.63609363873;36739.816300918326;36807.26385472222;37046;37320.44019339766;34055;34686.65313346935;36563;36981.075473113386;37209.25581488409;31773.682263177998;33244.66755332416;33325.50011749777;33591.496127558596;33803;25461.36346591276;25732.9717025573;25739.871300643375;25947.22158335253;26018 22:16:11 INFO - PROCESS | 7861 | |2;jquery.html;380.3211267886822;390.5040598439981;392.65865069985057;395.69729157194735;409.05100167611135;12783.169094008883;12985;16157.75763363549;17488.912555437237;17530.91234543827;15866.84133158668;16796.580085497837;16911.40810071648;16992.915035424838;16998.06510641914;4940.209566469374;4978.925316120261;4987.975060124712;4999.700017998932;5013.548780609744;3366.9494957575657;5049.621278404116;5096.898062038776;5131.461196574362;5197.454267301936;5469.781208751645;5539.9169012464845;5545.722713864311;5555.027870122733;5561.805336813212;5485.753141108659;5531.446855314456;5532.087205611069;5581.134924086773;5589.133684278943;5261.657992230493;5371.4897084776885;5380.166074258496;5385.865353366197;5410.810621628224;19395.448364130978;19625.116869740752;20460.5907881843;20505.077271522816;20508.17967281307;1997.6803711406105;2026.0983862181336;2028.5131568423471;2043.6014977079476;2046.2531176120758;8600;9112.772180695469;9468;9501.049895010512;9508.334416590842;1729.325563030419;1779.9142523060923;1803.7204233343853;1827.250827160871;1840.374272747259;71622.64188679024;71869.6406517964;72756;73004;73375;10178.083972442515;10182.694519164437;10201.591936322464;10207.234457415723;10349.586016559253;14408.567742967727;14535.127892326494;14545.709085818224;14554.490592829412;14574.562763117123;7092.078029856086;7282.453815963824;7359.484836061424;7533.1336896256535;7551.73568925085;6999.685014174374;7034.331738484938;7039.577625342496;7126.750563730348;7200.963995179991;4134.131832315187;4263.488381394252;4295.591918767712;4381.91608970944;4539.5460453955;1583.952481425559;1760.4102625620305;1904.7999960004277;1904.9047547622563;1939.8933058681625;887.3744010472601;927.846905260638;931.4504442379028;973.8782652168479;1006;16027.519174424786;16055.759163612554;16077.35690572364;16568.917155414147;17032.914835425992;895.7223260789176;896.5786080542135;901.3059943843205;903.8734577159203;917.2524392619994;9255.722328330161;9359.15767580921;9461.716148515556;9518.714438566854;9619.422834629944;371200;371802;371816;382417;408406;78268.21731782724;78431;78648.21351786409;78809.60595196988;79529;843.6541018182515;907.2016625369764;910.2035718746097;917.6604656277086;922.9400088994194;13011.479540818262;14581.416743330148;14586.197759122926;15238.3904643813;15841.128737919526;33627;34152.829235853664;34163.829180853936;34196.48705269473;35470.64529354673;45440.09119817519;47862.76068619634;48261;48496.27255591098;53465;6747.089142965833;6992.580445173306;7021.754238601625;7133.251008644225;7447.92552074494;10840.783184336427;11757.118216133653;12083.818742719042;12223.93888030554;12233.632991010283;10759.08547773448;10825.296355736851;10857.34855908648;10864.348139111678;10915.672529824118;10530.526126324181;10631.202659800701;10698;10785.514651840844;10984;7233.493655444279;10798.568057277935;10991.395473249047;11013.504392302206;11110.61112861046;7365.852682946418;7376.709075911801;7385.593792341473;7533.9246607535415;7738.41961852852;9011.468050431311;10813.62152324665;10992.17558683086;11044.889551104707;11073.778524429628 22:16:11 INFO - PROCESS | 7861 | |3;mootools.html;1318.611009752123;1500.1149321900077;1532.1879403915925;1722.319683724928;1877.6057028024113;6573.112629794976;7659.6936122555035;7893.447458677902;7897.881531777028;8110.351171906248;7859.253370929767;8020.558869262189;8041.236082572161;8049.798755031127;8060.032796064481;1879.1543805287674;2152.8923553822324;2195.4182141732454;2230.531588366445;2235.7205349331334;2248.179414513707;2297.264875239924;2299.41364951937;2302.930912072632;2303.170858490942;2117.0579092303933;2117.385958072165;2162.145952348821;2164.6969424280614;2165.7942495462908;1812.601227729904;1842.0697547738307;1858.2474097990334;2035.4504283843353;2074.0459388681224;2146.624340740364;2178.553396553703;2184.5740080684272;2184.912603495858;2186.8578542394694;7887.8028049298655;7946.205379462065;7947.642356093932;7958.602069896482;7959.562224077674;4614.5385461453925;4764.904701905977;4767.785449654774;4794.640401969831;4797.808087676489;2719.2114286856786;2775.4865349910333;2829.773618110567;2910;2914.970850291491;871.2159056848824;884.1953822021993;884.4030279561334;915.5102020419043;921.7280902133887;10853.34879907208;10857.294275872122;10879.456027198608;10897.782044359068;10898.073663738593;4327.610515053598;4376.518582955872;4486.798094085709;4491.708038977456;4520.61574766142;9259.166674999282;9293.442393456415;9297.209737172281;9303.488308143116;9317.487538185465;4893.510648935078;4894.535019173173;4912.066707325596;4913.901721965541;4933.50664933511;3723.646253605903;4326.394304797331;4449.354843547732;4468.486124095705;4509;3754.2116155607628;3780.111673756665;3849.8845034649007;3875.4768106305287;3933.5869733678123;1225.3199474291853;1228.3858070964518;1234.796258617318;1239.2936026465009;1250.2123662092824;814.5519964019766;916.702071826659;922.326701507903;934.2199263614822;938.9108034736689;5211.478852114758;5465.125579907196;5521.785207254397;5529.253550770594;5539.556835453234;419.85305143199633;423.5743078206383;431.43912913213325;436.8187202311069;440.3394907638542;3679.79761113136;3692.4276737105793;3695.2978934002454;3729.5151630287887;3738;8738.300935925237;9111.544422778834;9151.084891510796;9157.45055296671;9180.17378435944;9004.414713043761;9050.140236677506;9082.772930676785;9100.908990910006;9134;6879.828004299832;7152.392046675986;7239.420846332281;7341.963290183515;7428.4800063995;5170.121079416433;5425.701586412785;5463.262459567907;5533.059379905345;5550.750216240198;8677.526904832093;8934.687285944961;9099.681511147079;9133.497657628894;9137.451752894847;9131.269498439977;9244.306676999386;9254.167124958785;9261;9299.814003720023;6149.754009839557;6675.232348279816;6675.299093595103;6836.38472537474;6883.827904302432;7571.507851989603;7616.3906887449975;7661.616919154131;7670.578118203329;7685.193054729173;6903.998920156449;7003.299670032956;7048.647567621496;7155.105611798525;7160.782666946736;6911.7926462206215;6931.133608298963;7000.089988301692;7095.077639906779;7112.03987461686;6275.592086514362;6282.937170628418;6291.779787707408;6330.272018717907;6366.77716279928;4717.740524271198;4720.070281926397;4731.45588257341;4762.809487620595;4802.327674125555;4690.437147542317;4699.929501057419;4793.736344501086;4851.320815085962;4898.8775280618265 22:16:11 INFO - PROCESS | 7861 | |4;prototype.html;298.6938388152144;305.36788847086524;320.64728798321846;327.54961927349893;328.738652771047;4734.7159170449795;4765.831546646276;4766.713997160173;4787.2101103318;4792.999999999995;4670.90658186836;4707.411573553306;4732.550407711271;4735.455422626399;4741.217699079648;3542.592601850788;3673.3755261605456;3739.364308067635;3773.490578771858;3803.0302272920367;3738.6448287412654;3748.756330838487;3754.3242216401036;3764.698824094066;3778.6410291022307;3532.7880327180324;3713.6286371362917;3719.5536535615765;3729.347364211266;3745.288395204916;3637.7999210043436;3700.4819325294347;3710.4619830124752;3718.5165928429133;3729.4219395993664;3283.9014829555153;3288.3423315337027;3292.1440425489304;3294.7858389204625;3295.1597342677464;4468.262736648449;4497.078098989699;4519.548045195487;4523.705959112647;4525.705829121097;4027.3153563894207;4249.1714115747445;4251.256030194703;4259.190753756808;4299.892502687458;4919.4834542373255;4934.827281045146;4938.160512712811;4952.182889823175;4963.602911767086;3583.552055993001;3586.264815712773;3588.5693716753894;3619.167591453954;3635.1275693833554;5039.571636410909;5172.870678233075;5198.220266959948;5229.326615483047;5235.895282094375;3638.8180590970346;3648.1062139775927;3648.3980143276062;3649.3431182386894;3671.3575124353133;3688.870889518854;3689.594144644087;3691.9815400922826;3705.7961812100593;3709.703223742094;4187.225363307737;4227.534971153171;4229.471316085489;4239.236937351307;4242.366688824763;4734.668573199911;4921.867970366836;4931.161702510582;4956.132676781549;4957.628177886673;1271.7329360834144;1275.1583954589926;1279.0918447902093;1279.8144269081117;1295.1905059337914;820.0528389709895;822.3996482567759;852.9445586036887;866.2550206822128;867.2584939876315;4262.42457268271;4266.87199384019;4274.7435153890865;4276.957230427718;4293.3989241506215;5018.372703412074;5032;5034.546890779774;5054.7978080876355;5077.019962214739;4209.26337890874;4214.7247481136865;4277.871663850089;4281.522874608255;4295.4201182839915;2699.8650067496546;2703.864806759654;2714;2716.1172618898936;2725;4716.504766999485;4777.518969119584;4794.760261986957;4810.13417584838;4810.350602668595;4889.9486610378935;5141.742912854343;5147.356580427447;5164.48355164488;5186.377634683787;4732.739699316502;4733.6213102951;4733.644976626698;4744.833930812474;4754.78603462845;4978.800847966041;4996.450390457119;5008.173651347487;5029.371328583927;5048.697078175322;4658.138244424792;4859.246816743411;4868.683535570177;4964.971367465915;4970.478099799469;4660.906781864412;4696.074609410125;4710.387649605666;4718.669693121448;4735.621150308036;3619.5837478690287;3622.565292164957;3627.836747346323;3644.854205831737;3657.65252301031;4829.491616712731;5030.823921162888;5033.899322013466;5060.266261392004;5065.949340506695;4814.614830813456;4828.95171048285;4835.879103022453;4850.636202284913;4867.58625516835;4822.662413631012;4827;4841.588464980516;4865.440474345495;4879.536444037811;4661.533846615312;4770.475247722818;4795;4818.397700287464;4824.226070009829;4869.9026019480125;4878.341423907869;4887;4887.951120488749;4914.729689867092;4760.690555113906;4780.808767649394;4819.7108173509705;4835.920554161744;4856.247281671346 22:16:11 INFO - PROCESS | 7861 | |5;yui.html;652.5856081388317;684.9863002739942;695.2109355881075;698.1691786773739;724.3661795928564;11739.706507337321;12322.630321090359;12347.876521234784;12348.197367171128;12354.629361119158;11454.255473394223;11465.426728663575;11511.712207194825;11517.193796434252;11536.596219132332;692.882210024297;854.3976496569934;870.1081391573624;884.2881480408269;887.0818702642766;878.9692360767374;879.8944126704804;881.4623079921242;884.5975081337975;886.1404437695425;930.3441074042818;932.0120672087575;932.2402242172642;932.7155217658611;933.2953620016877;728.6101935464527;898.820235952812;925.796324808541;926.4117285523673;929.7675581104724;684.9726010959556;685.7874059041663;685.8456847209388;685.9657017149123;686.1286166568431;11445.141614378872;11471.311721296666;11478.65564033072;11492.827607585892;11506.482208300647;12097.758044839142;12121.93939030308;12457.439415226247;12491.376443670175;12821.23072615646;10103.696889093339;10141.340812847142;10253.948730256376;10434.426106564137;10435.843462348072;4030.6372426481757;4246.392410109245;4268.850590229358;4342.782860856976;4396.274614688573;45309.09381812345;45820.54179458196;45825.312620310724;45897.770511147566;45977.08045839064;10362.585496580203;10473.947630261953;10676.89323106759;11069.889301107047;11090.833637495443;18832.623347532972;18841.434756957315;18951.526211844815;18966.336178233694;18968.43094707161;6907.89638155418;7004.579725216503;7018.719251229996;7053.047838541834;7124.358807707331;3685.8758078786227;3722.3485889969147;3725.1990821973404;3726.925461490755;3730.8880733578035;2755.6968733439307;2792.622995895528;2827.7313655202724;2828.971710282871;2831.546952487592;520.8828013696964;523.0271694647952;524.3288590604033;530.167636810212;531.147508249262;510.57622173595826;525.9237410575522;576.7404667899463;585.3268740947859;592.3691268798716;7339.266073392618;7519.736809211651;7565.130010048805;7591.924080759121;7624.733134340271;468.24846121974423;491.1968930798099;529.7986765029341;533.2135100726475;568.1421054208092;3312.0727341528814;3423.383790917609;3493.615702272748;3582.5880023797076;3598.5861625913353;47696.5230347699;49068.50931490639;49379.753101234266;49411;49531.50468495269;43350.56649433466;44619.776901115285;45794;45899.541004589526;46697.766511167225;41926.79036604858;42087.36868946968;42512;42650.36024459574;42787.7860610695;6966.094407726963;7057.788266352018;7160.606166660884;7165.104361954755;7280.672369743268;28485.145445635833;28796.136115916557;28943;29200;29885.252868678457;33992.66007339895;34553.65446345605;37425.81287093656;37609.623903761705;37745.81127094457;9210;9471.573779180091;9569;9610.087041731025;9772.657956971472;10087.344322619007;10161.745956350855;10201.84697229557;10344.27590068688;10381.896181038395;9011.504367259864;9113.407628504126;9152.359334846496;9422.764430889283;9804.068613481708;8628.52543110135;8730.17063378978;8906.86639700418;8987.101289870961;9006.59470323824;8801.119888011148;8809.42738721981;9096.27229821626;9112.908870911206;9495.905040949778;7739.845203095794;7772.7668169954995;7833.412494062854;8145.348372130334;8167;7993.880091798744;8015.639296231567;8042.83914321722;8084.797880053045;8177.4684645497855 22:16:11 INFO - PROCESS | 7861 | __end_tp_report 22:16:11 INFO - PROCESS | 7861 | __start_cc_report 22:16:11 INFO - PROCESS | 7861 | _x_x_mozilla_cycle_collect,1038 22:16:11 INFO - PROCESS | 7861 | __end_cc_report 22:16:11 INFO - PROCESS | 7861 | __startTimestamp1462943771684__endTimestamp 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | ------- Summary: start ------- 22:16:11 INFO - PROCESS | 7861 | Number of tests: 6 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | [#0] dojo.html Cycles:165 Average:4723.74 Median:4822.42 stddev:491.14 (10.2%) stddev-sans-first:464.92 22:16:11 INFO - PROCESS | 7861 | Values: 2650.0 2672.3 2695.4 2726.2 2734.0 4668.3 4683.3 4690.8 4787.0 4803.4 4649.4 4683.5 4714.3 4714.9 4718.7 4251.9 4269.3 4346.3 4353.1 4366.4 4480.8 4492.6 4543.0 4573.7 4585.6 4290.7 4330.7 4336.1 4482.0 4514.0 4347.6 4374.8 4414.4 4453.8 4494.0 4418.1 4434.3 4440.2 4476.0 4477.7 4516.0 4566.2 4613.0 4767.6 4772.8 5082.4 5099.6 5111.2 5113.9 5132.7 5086.7 5101.2 5103.3 5104.5 5143.2 4633.3 4764.1 4803.3 4822.4 4825.9 5310.9 5322.6 5344.8 5346.4 5350.3 5091.5 5129.0 5131.1 5134.9 5140.8 4994.5 5119.6 5148.4 5151.3 5161.4 5144.8 5145.8 5154.6 5161.8 5194.9 5119.7 5136.0 5139.0 5149.6 5190.7 4313.1 4347.6 4355.6 4356.7 4425.6 3867.7 3881.3 3888.4 3898.8 3902.8 4334.3 4346.5 4350.7 4377.0 4379.1 5185.6 5192.5 5194.4 5195.7 5240.6 4269.2 4376.9 4380.0 4384.9 4437.1 4563.7 4567.6 4611.1 4672.8 4682.7 4798.8 4817.7 4820.9 4824.1 4868.4 5111.7 5127.5 5142.8 5143.7 5157.0 4698.5 4699.8 4749.8 4763.9 4808.7 5000.3 5001.9 5002.5 5017.4 5050.3 5050.4 5058.3 5068.1 5070.1 5098.8 4855.7 4871.8 4898.8 4899.8 4918.6 4959.1 4962.3 4964.8 4966.5 5008.7 4861.7 4870.5 4870.7 4912.2 4916.1 4783.8 4903.0 4946.5 4956.9 4980.5 4857.3 4884.5 4892.0 4892.8 4935.5 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | [#1] ext.html Cycles:180 Average:44828.96 Median:37173.44 stddev:73160.31 (196.8%) stddev-sans-first:73290.09 22:16:11 INFO - PROCESS | 7861 | Values: 573.3 592.8 605.6 606.4 607.1 41742.8 43375.0 43555.6 43621.3 47721.3 41097.6 41406.0 41451.4 41500.4 41559.8 7935.5 8073.2 8075.4 8076.1 8082.4 7947.1 8026.7 8032.2 8034.1 8034.6 8017.3 8044.7 8051.4 8051.8 8055.1 7965.3 8000.6 8021.4 8025.1 8025.4 7857.9 7940.6 7941.2 7946.6 7955.9 38931.8 39204.0 39267.2 39317.4 39408.0 3197.3 3326.0 3326.2 3707.7 3913.1 29408.3 29947.3 30086.4 30167.4 30655.0 3116.7 3184.4 3237.2 3238.4 3245.9 243716.0 244403.0 244459.0 245100.8 245728.0 48003.8 49559.3 54038.0 56281.7 56296.0 59730.0 59862.4 59926.7 59978.7 60062.4 12733.2 13973.8 14103.7 14140.4 14187.8 9666.7 9904.0 10186.9 10721.8 10729.0 16601.8 16698.7 16734.3 16738.9 16739.5 6687.7 6805.9 6806.7 6971.9 7018.9 880.2 972.1 973.9 982.5 1033.9 28013.9 28709.4 28859.4 28866.9 29039.4 1040.7 1044.2 1060.5 1066.1 1134.4 14960.7 14971.5 15229.4 15407.5 15492.8 328729.0 331396.0 331789.0 334534.0 334719.0 49965.8 50036.0 50103.2 50115.7 50209.2 1173.9 1284.8 1300.1 1310.5 1315.5 28640.3 29781.3 29787.1 29974.6 30192.8 42144.6 42285.0 42733.8 42749.8 42801.4 253226.0 254010.0 254401.0 255562.0 256017.0 31999.9 33065.7 41399.6 42229.6 42696.4 42875.0 43033.1 43380.3 43767.8 43820.3 36256.8 36928.6 37137.6 37359.3 37381.6 36390.6 36739.8 36807.3 37046.0 37320.4 34055.0 34686.7 36563.0 36981.1 37209.3 31773.7 33244.7 33325.5 33591.5 33803.0 25461.4 25733.0 25739.9 25947.2 26018.0 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | [#2] jquery.html Cycles:180 Average:24015.58 Median:4198.81 stddev:63110.31 (1503.1%) stddev-sans-first:63262.40 22:16:11 INFO - PROCESS | 7861 | Values: 380.3 390.5 392.7 395.7 409.1 12783.2 12985.0 16157.8 17488.9 17530.9 15866.8 16796.6 16911.4 16992.9 16998.1 4940.2 4978.9 4988.0 4999.7 5013.5 3366.9 5049.6 5096.9 5131.5 5197.5 5469.8 5539.9 5545.7 5555.0 5561.8 5485.8 5531.4 5532.1 5581.1 5589.1 5261.7 5371.5 5380.2 5385.9 5410.8 19395.4 19625.1 20460.6 20505.1 20508.2 1997.7 2026.1 2028.5 2043.6 2046.3 8600.0 9112.8 9468.0 9501.0 9508.3 1729.3 1779.9 1803.7 1827.3 1840.4 71622.6 71869.6 72756.0 73004.0 73375.0 10178.1 10182.7 10201.6 10207.2 10349.6 14408.6 14535.1 14545.7 14554.5 14574.6 7092.1 7282.5 7359.5 7533.1 7551.7 6999.7 7034.3 7039.6 7126.8 7201.0 4134.1 4263.5 4295.6 4381.9 4539.5 1584.0 1760.4 1904.8 1904.9 1939.9 887.4 927.8 931.5 973.9 1006.0 16027.5 16055.8 16077.4 16568.9 17032.9 895.7 896.6 901.3 903.9 917.3 9255.7 9359.2 9461.7 9518.7 9619.4 371200.0 371802.0 371816.0 382417.0 408406.0 78268.2 78431.0 78648.2 78809.6 79529.0 843.7 907.2 910.2 917.7 922.9 13011.5 14581.4 14586.2 15238.4 15841.1 33627.0 34152.8 34163.8 34196.5 35470.6 45440.1 47862.8 48261.0 48496.3 53465.0 6747.1 6992.6 7021.8 7133.3 7447.9 10840.8 11757.1 12083.8 12223.9 12233.6 10759.1 10825.3 10857.3 10864.3 10915.7 10530.5 10631.2 10698.0 10785.5 10984.0 7233.5 10798.6 10991.4 11013.5 11110.6 7365.9 7376.7 7385.6 7533.9 7738.4 9011.5 10813.6 10992.2 11044.9 11073.8 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | [#3] mootools.html Cycles:180 Average:5191.82 Median:5190.80 stddev:2890.33 (55.7%) stddev-sans-first:2883.78 22:16:11 INFO - PROCESS | 7861 | Values: 1318.6 1500.1 1532.2 1722.3 1877.6 6573.1 7659.7 7893.4 7897.9 8110.4 7859.3 8020.6 8041.2 8049.8 8060.0 1879.2 2152.9 2195.4 2230.5 2235.7 2248.2 2297.3 2299.4 2302.9 2303.2 2117.1 2117.4 2162.1 2164.7 2165.8 1812.6 1842.1 1858.2 2035.5 2074.0 2146.6 2178.6 2184.6 2184.9 2186.9 7887.8 7946.2 7947.6 7958.6 7959.6 4614.5 4764.9 4767.8 4794.6 4797.8 2719.2 2775.5 2829.8 2910.0 2915.0 871.2 884.2 884.4 915.5 921.7 10853.3 10857.3 10879.5 10897.8 10898.1 4327.6 4376.5 4486.8 4491.7 4520.6 9259.2 9293.4 9297.2 9303.5 9317.5 4893.5 4894.5 4912.1 4913.9 4933.5 3723.6 4326.4 4449.4 4468.5 4509.0 3754.2 3780.1 3849.9 3875.5 3933.6 1225.3 1228.4 1234.8 1239.3 1250.2 814.6 916.7 922.3 934.2 938.9 5211.5 5465.1 5521.8 5529.3 5539.6 419.9 423.6 431.4 436.8 440.3 3679.8 3692.4 3695.3 3729.5 3738.0 8738.3 9111.5 9151.1 9157.5 9180.2 9004.4 9050.1 9082.8 9100.9 9134.0 6879.8 7152.4 7239.4 7342.0 7428.5 5170.1 5425.7 5463.3 5533.1 5550.8 8677.5 8934.7 9099.7 9133.5 9137.5 9131.3 9244.3 9254.2 9261.0 9299.8 6149.8 6675.2 6675.3 6836.4 6883.8 7571.5 7616.4 7661.6 7670.6 7685.2 6904.0 7003.3 7048.6 7155.1 7160.8 6911.8 6931.1 7000.1 7095.1 7112.0 6275.6 6282.9 6291.8 6330.3 6366.8 4717.7 4720.1 4731.5 4762.8 4802.3 4690.4 4699.9 4793.7 4851.3 4898.9 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | [#4] prototype.html Cycles:180 Average:4091.12 Median:4708.90 stddev:1165.09 (24.7%) stddev-sans-first:1133.05 22:16:11 INFO - PROCESS | 7861 | Values: 298.7 305.4 320.6 327.5 328.7 4734.7 4765.8 4766.7 4787.2 4793.0 4670.9 4707.4 4732.6 4735.5 4741.2 3542.6 3673.4 3739.4 3773.5 3803.0 3738.6 3748.8 3754.3 3764.7 3778.6 3532.8 3713.6 3719.6 3729.3 3745.3 3637.8 3700.5 3710.5 3718.5 3729.4 3283.9 3288.3 3292.1 3294.8 3295.2 4468.3 4497.1 4519.5 4523.7 4525.7 4027.3 4249.2 4251.3 4259.2 4299.9 4919.5 4934.8 4938.2 4952.2 4963.6 3583.6 3586.3 3588.6 3619.2 3635.1 5039.6 5172.9 5198.2 5229.3 5235.9 3638.8 3648.1 3648.4 3649.3 3671.4 3688.9 3689.6 3692.0 3705.8 3709.7 4187.2 4227.5 4229.5 4239.2 4242.4 4734.7 4921.9 4931.2 4956.1 4957.6 1271.7 1275.2 1279.1 1279.8 1295.2 820.1 822.4 852.9 866.3 867.3 4262.4 4266.9 4274.7 4277.0 4293.4 5018.4 5032.0 5034.5 5054.8 5077.0 4209.3 4214.7 4277.9 4281.5 4295.4 2699.9 2703.9 2714.0 2716.1 2725.0 4716.5 4777.5 4794.8 4810.1 4810.4 4889.9 5141.7 5147.4 5164.5 5186.4 4732.7 4733.6 4733.6 4744.8 4754.8 4978.8 4996.5 5008.2 5029.4 5048.7 4658.1 4859.2 4868.7 4965.0 4970.5 4660.9 4696.1 4710.4 4718.7 4735.6 3619.6 3622.6 3627.8 3644.9 3657.7 4829.5 5030.8 5033.9 5060.3 5065.9 4814.6 4829.0 4835.9 4850.6 4867.6 4822.7 4827.0 4841.6 4865.4 4879.5 4661.5 4770.5 4795.0 4818.4 4824.2 4869.9 4878.3 4887.0 4888.0 4914.7 4760.7 4780.8 4819.7 4835.9 4856.2 22:16:11 INFO - PROCESS | 7861 | 22:16:11 INFO - PROCESS | 7861 | [#5] yui.html Cycles:180 Average:12242.20 Median:49471.25 stddev:14026.55 (28.4%) stddev-sans-first:14038.89 22:16:11 INFO - PROCESS | 7861 | Values: 652.6 685.0 695.2 698.2 724.4 11739.7 12322.6 12347.9 12348.2 12354.6 11454.3 11465.4 11511.7 11517.2 11536.6 692.9 854.4 870.1 884.3 887.1 879.0 879.9 881.5 884.6 886.1 930.3 932.0 932.2 932.7 933.3 728.6 898.8 925.8 926.4 929.8 685.0 685.8 685.8 686.0 686.1 11445.1 11471.3 11478.7 11492.8 11506.5 12097.8 12121.9 12457.4 12491.4 12821.2 10103.7 10141.3 10253.9 10434.4 10435.8 4030.6 4246.4 4268.9 4342.8 4396.3 45309.1 45820.5 45825.3 45897.8 45977.1 10362.6 10473.9 10676.9 11069.9 11090.8 18832.6 18841.4 18951.5 18966.3 18968.4 6907.9 7004.6 7018.7 7053.0 7124.4 3685.9 3722.3 3725.2 3726.9 3730.9 2755.7 2792.6 2827.7 2829.0 2831.5 520.9 523.0 524.3 530.2 531.1 510.6 525.9 576.7 585.3 592.4 7339.3 7519.7 7565.1 7591.9 7624.7 468.2 491.2 529.8 533.2 568.1 3312.1 3423.4 3493.6 3582.6 3598.6 47696.5 49068.5 49379.8 49411.0 49531.5 43350.6 44619.8 45794.0 45899.5 46697.8 41926.8 42087.4 42512.0 42650.4 42787.8 6966.1 7057.8 7160.6 7165.1 7280.7 28485.1 28796.1 28943.0 29200.0 29885.3 33992.7 34553.7 37425.8 37609.6 37745.8 9210.0 9471.6 9569.0 9610.1 9772.7 10087.3 10161.7 10201.8 10344.3 10381.9 9011.5 9113.4 9152.4 9422.8 9804.1 8628.5 8730.2 8906.9 8987.1 9006.6 8801.1 8809.4 9096.3 9112.9 9495.9 7739.8 7772.8 7833.4 8145.3 8167.0 7993.9 8015.6 8042.8 8084.8 8177.5 22:16:11 INFO - PROCESS | 7861 | -------- Summary: end -------- 22:16:11 INFO - PROCESS | 7861 | 22:16:12 INFO - TEST-INFO | 7861: exit 0 22:16:12 INFO - TEST-OK | dromaeo_css | took 1118671ms 22:16:12 INFO - TEST-START | kraken 22:16:12 INFO - operating with platform_type : linux_ 22:16:12 INFO - Initialising browser for kraken test... 22:16:12 INFO - TEST-INFO | started process 8040 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc8b0he/profile http://localhost:55223/getInfo.html) 22:16:16 INFO - PROCESS | 8040 | __metrics Screen width/height:1600/1200 22:16:16 INFO - PROCESS | 8040 | colorDepth:24 22:16:16 INFO - PROCESS | 8040 | Browser inner width/height: 1024/697 22:16:16 INFO - PROCESS | 8040 | __metrics 22:16:17 ERROR - PROCESS | 8040 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:16:18 INFO - TEST-INFO | 8040: exit 0 22:16:18 INFO - Browser initialized. 22:16:18 INFO - Running cycle 1/1 for kraken test... 22:16:18 INFO - TEST-INFO | started process 8118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpc8b0he/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:16:30 INFO - PROCESS | 8118 | RSS: Main: 154681344 22:16:30 INFO - PROCESS | 8118 | 22:16:33 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55223/tests/kraken/driver.html?testName=audio-beat-detection) 22:16:34 INFO - PROCESS | 8118 | RSS: Main: 209539072 22:16:34 INFO - PROCESS | 8118 | 22:16:37 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55223/tests/kraken/driver.html?testName=audio-dft) 22:16:37 INFO - PROCESS | 8118 | RSS: Main: 197734400 22:16:37 INFO - PROCESS | 8118 | 22:16:41 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55223/tests/kraken/driver.html?testName=audio-fft) 22:16:41 INFO - PROCESS | 8118 | RSS: Main: 266293248 22:16:41 INFO - PROCESS | 8118 | 22:16:43 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55223/tests/kraken/driver.html?testName=audio-oscillator) 22:16:44 INFO - PROCESS | 8118 | RSS: Main: 175284224 22:16:44 INFO - PROCESS | 8118 | 22:16:46 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:16:47 INFO - PROCESS | 8118 | RSS: Main: 179527680 22:16:47 INFO - PROCESS | 8118 | 22:16:50 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55223/tests/kraken/driver.html?testName=imaging-darkroom) 22:16:51 INFO - PROCESS | 8118 | RSS: Main: 216092672 22:16:51 INFO - PROCESS | 8118 | 22:16:55 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55223/tests/kraken/driver.html?testName=imaging-desaturate) 22:16:55 INFO - PROCESS | 8118 | RSS: Main: 214343680 22:16:55 INFO - PROCESS | 8118 | 22:16:58 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55223/tests/kraken/driver.html?testName=json-parse-financial) 22:16:59 INFO - PROCESS | 8118 | RSS: Main: 214634496 22:16:59 INFO - PROCESS | 8118 | 22:17:01 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:17:01 INFO - PROCESS | 8118 | RSS: Main: 203632640 22:17:01 INFO - PROCESS | 8118 | 22:17:03 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:17:04 INFO - PROCESS | 8118 | RSS: Main: 207441920 22:17:04 INFO - PROCESS | 8118 | 22:17:06 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:17:07 INFO - PROCESS | 8118 | RSS: Main: 215781376 22:17:07 INFO - PROCESS | 8118 | 22:17:10 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:17:10 INFO - PROCESS | 8118 | RSS: Main: 224182272 22:17:10 INFO - PROCESS | 8118 | 22:17:13 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:17:14 INFO - PROCESS | 8118 | RSS: Main: 219340800 22:17:14 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | Cycle 1(1): loaded http://localhost:55223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55223/tests/kraken/driver.html?testName=ai-astar) 22:17:16 INFO - PROCESS | 8118 | RSS: Main: 264564736 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | __start_tp_report 22:17:16 INFO - PROCESS | 8118 | _x_x_mozilla_page_load 22:17:16 INFO - PROCESS | 8118 | _x_x_mozilla_page_load_details 22:17:16 INFO - PROCESS | 8118 | |i|pagename|runs| 22:17:16 INFO - PROCESS | 8118 | |0;ai-astar;114;109;113;114;115;114;116;114;112;116 22:17:16 INFO - PROCESS | 8118 | |1;audio-beat-detection;157;138;157;210;130;157;156;132;204;138 22:17:16 INFO - PROCESS | 8118 | |2;audio-dft;163;160;162;161;160;163;162;163;162;162 22:17:16 INFO - PROCESS | 8118 | |3;audio-fft;75;77;76;156;77;75;76;76;128;76 22:17:16 INFO - PROCESS | 8118 | |4;audio-oscillator;94;94;153;93;94;94;125;93;94;123 22:17:16 INFO - PROCESS | 8118 | |5;imaging-gaussian-blur;118;116;117;116;118;117;116;118;117;116 22:17:16 INFO - PROCESS | 8118 | |6;imaging-darkroom;147;146;147;148;147;147;147;148;147;146 22:17:16 INFO - PROCESS | 8118 | |7;imaging-desaturate;94;94;94;95;94;94;96;94;94;94 22:17:16 INFO - PROCESS | 8118 | |8;json-parse-financial;75;67;69;67;68;68;67;120;65;65 22:17:16 INFO - PROCESS | 8118 | |9;json-stringify-tinderbox;64;64;64;65;64;64;63;64;64;64 22:17:16 INFO - PROCESS | 8118 | |10;stanford-crypto-aes;82;87;87;84;83;83;91;85;83;83 22:17:16 INFO - PROCESS | 8118 | |11;stanford-crypto-ccm;133;134;131;134;139;141;129;132;195;132 22:17:16 INFO - PROCESS | 8118 | |12;stanford-crypto-pbkdf2;175;179;179;181;181;178;174;178;186;181 22:17:16 INFO - PROCESS | 8118 | |13;stanford-crypto-sha256-iterative;69;71;72;69;70;69;69;66;66;67 22:17:16 INFO - PROCESS | 8118 | __end_tp_report 22:17:16 INFO - PROCESS | 8118 | __start_cc_report 22:17:16 INFO - PROCESS | 8118 | _x_x_mozilla_cycle_collect,2124 22:17:16 INFO - PROCESS | 8118 | __end_cc_report 22:17:16 INFO - PROCESS | 8118 | __startTimestamp1462943836670__endTimestamp 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | ------- Summary: start ------- 22:17:16 INFO - PROCESS | 8118 | Number of tests: 14 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:2.06 (1.8%) stddev-sans-first:2.18 22:17:16 INFO - PROCESS | 8118 | Values: 114.0 109.0 113.0 114.0 115.0 114.0 116.0 114.0 112.0 116.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#1] audio-beat-detection Cycles:10 Average:157.90 Median:157.00 stddev:28.06 (17.9%) stddev-sans-first:29.76 22:17:16 INFO - PROCESS | 8118 | Values: 157.0 138.0 157.0 210.0 130.0 157.0 156.0 132.0 204.0 138.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.14 (0.7%) stddev-sans-first:1.12 22:17:16 INFO - PROCESS | 8118 | Values: 163.0 160.0 162.0 161.0 160.0 163.0 162.0 163.0 162.0 162.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#3] audio-fft Cycles:10 Average:89.20 Median:76.00 stddev:28.61 (37.6%) stddev-sans-first:29.88 22:17:16 INFO - PROCESS | 8118 | Values: 75.0 77.0 76.0 156.0 77.0 75.0 76.0 76.0 128.0 76.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#4] audio-oscillator Cycles:10 Average:105.70 Median:94.00 stddev:20.86 (22.2%) stddev-sans-first:21.69 22:17:16 INFO - PROCESS | 8118 | Values: 94.0 94.0 153.0 93.0 94.0 94.0 125.0 93.0 94.0 123.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.83 22:17:16 INFO - PROCESS | 8118 | Values: 118.0 116.0 117.0 116.0 118.0 117.0 116.0 118.0 117.0 116.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 22:17:16 INFO - PROCESS | 8118 | Values: 147.0 146.0 147.0 148.0 147.0 147.0 147.0 148.0 147.0 146.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 22:17:16 INFO - PROCESS | 8118 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 96.0 94.0 94.0 94.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#8] json-parse-financial Cycles:10 Average:73.10 Median:67.50 stddev:16.72 (24.8%) stddev-sans-first:17.72 22:17:16 INFO - PROCESS | 8118 | Values: 75.0 67.0 69.0 67.0 68.0 68.0 67.0 120.0 65.0 65.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 22:17:16 INFO - PROCESS | 8118 | Values: 64.0 64.0 64.0 65.0 64.0 64.0 63.0 64.0 64.0 64.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:84.50 stddev:2.78 (3.3%) stddev-sans-first:2.76 22:17:16 INFO - PROCESS | 8118 | Values: 82.0 87.0 87.0 84.0 83.0 83.0 91.0 85.0 83.0 83.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:134.00 stddev:19.66 (14.7%) stddev-sans-first:20.69 22:17:16 INFO - PROCESS | 8118 | Values: 133.0 134.0 131.0 134.0 139.0 141.0 129.0 132.0 195.0 132.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:180.00 stddev:3.39 (1.9%) stddev-sans-first:3.24 22:17:16 INFO - PROCESS | 8118 | Values: 175.0 179.0 179.0 181.0 181.0 178.0 174.0 178.0 186.0 181.0 22:17:16 INFO - PROCESS | 8118 | 22:17:16 INFO - PROCESS | 8118 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.99 (2.9%) stddev-sans-first:2.11 22:17:16 INFO - PROCESS | 8118 | Values: 69.0 71.0 72.0 69.0 70.0 69.0 69.0 66.0 66.0 67.0 22:17:16 INFO - PROCESS | 8118 | -------- Summary: end -------- 22:17:16 INFO - PROCESS | 8118 | 22:17:17 INFO - TEST-INFO | 8118: exit 0 22:17:17 INFO - TEST-OK | kraken | took 65284ms 22:17:17 INFO - SUITE-END | took 1183s 22:17:17 INFO - Completed test suite (00:19:44) 22:17:17 INFO - javascript benchmark 22:17:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2649.960250596241, 2672.305200647831, 2695.353115252339, 2726.2230264374643, 2733.958990615141, 4668.323093151491, 4683.274092515661, 4690.812367505304, 4786.952130478699, 4803.423589169301, 4649.37233473481, 4683.531646835315, 4714.269288260317, 4714.882127946803, 4718.716876987383, 4251.914961700764, 4269.316909294514, 4346.348047792841, 4353.107612939356, 4366.366876802862, 4480.798364073608, 4492.640588752892, 4543.0, 4573.7027093239, 4585.610223131038, 4290.678199135046, 4330.653547716207, 4336.111097225062, 4482.036362182114, 4513.984353520481, 4347.608715215614, 4374.759388233646, 4414.426124603813, 4453.755043472608, 4493.977530112328, 4418.116376724668, 4434.312681534368, 4440.178566965122, 4475.977620111911, 4477.686561940665, 4516.0, 4566.223741963874, 4612.976935115303, 4767.618590512785, 4772.76136193189, 5082.390113186404, 5099.643024988252, 5111.182210846284, 5113.8721531962, 5132.717700526469, 5086.745662716887, 5101.20931255656, 5103.2855400244, 5104.489551044866, 5143.151380022292, 4633.281841314635, 4764.14245435818, 4803.255495398219, 4822.42130944289, 4825.855224343275, 5310.8937821244135, 5322.600804939645, 5344.812931547455, 5346.358436987586, 5350.277712508761, 5091.516305950929, 5128.974355128201, 5131.050755610224, 5134.922976155361, 5140.7943682253035, 4994.450610432849, 5119.59043276537, 5148.4079330876675, 5151.2530683050745, 5161.380634323905, 5144.819931302386, 5145.794168233304, 5154.613403994716, 5161.845144645667, 5194.948050519522, 5119.692818430906, 5136.0, 5138.97430512845, 5149.613778966593, 5190.688558686491, 4313.1158112586845, 4347.56524347569, 4355.586219309161, 4356.6732495063, 4425.645948324126, 3867.7485963412287, 3881.301365754135, 3888.3778595424596, 3898.8245528951265, 3902.765834049966, 4334.32817913224, 4346.456692913386, 4350.71720338177, 4376.978115109468, 4379.1241751649795, 5185.611079169077, 5192.532672059533, 5194.35070616173, 5195.740212989411, 5240.633155679142, 4269.210196113729, 4376.934345984812, 4380.036391993756, 4384.868453946387, 4437.134758722018, 4563.726176429424, 4567.566081222345, 4611.146937816515, 4672.789724462407, 4682.74244916526, 4798.760061996886, 4817.662763606584, 4820.879478013007, 4824.0834241494, 4868.41579010521, 5111.744412779421, 5127.4872512748425, 5142.794288228579, 5143.71709555978, 5157.0, 4698.506656800987, 4699.788509517149, 4749.786259618395, 4763.904721905612, 4808.711477311373, 5000.2999580058095, 5001.949980500149, 5002.474740152232, 5017.37282839645, 5050.267711181931, 5050.444451110301, 5058.34241548611, 5068.113080211022, 5070.112730272262, 5098.847034588968, 4855.684380515255, 4871.805127794849, 4898.828541001048, 4899.755012249445, 4918.606511479002, 4959.082569724613, 4962.280469331999, 4964.7765850537535, 4966.478519755518, 5008.724520151427, 4861.683990540745, 4870.488598697086, 4870.732109733857, 4912.238603016538, 4916.139675556692, 4783.832565860317, 4903.019396120719, 4946.505349465025, 4956.900861982668, 4980.526849949249, 4857.295692124693, 4884.545740239134, 4891.95108048915, 4892.828750993698, 4935.4570997190995], "value": 4692.952345650155, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.2948473377745, 592.7628948420631, 605.6487237402307, 606.3814908793029, 607.1166452811159, 41742.79128604357, 43375.0, 43555.56444435551, 43621.345679814825, 47721.28418073723, 41097.58902410975, 41406.0, 41451.37822932658, 41500.37749433753, 41559.792201039025, 7935.52386856789, 8073.152319006508, 8075.434719569633, 8076.071251806029, 8082.353411727077, 7947.086085100201, 8026.6789328427085, 8032.19678032195, 8034.116247212802, 8034.5982700865015, 8017.318527925104, 8044.6782128714785, 8051.396145289127, 8051.758447246592, 8055.073666528337, 7965.322947549465, 8000.559969201691, 8021.398395120389, 8025.077116131662, 8025.357971362277, 7857.921420785776, 7940.602969851484, 7941.166177551389, 7946.562939038351, 7955.880661790078, 38931.805340973115, 39204.019899502455, 39267.21465570701, 39317.41023884644, 39408.0, 3197.3445443684222, 3325.985574399807, 3326.185084654275, 3707.703383729295, 3913.0608653923136, 29408.264793380124, 29947.25131871699, 30086.398272034654, 30167.396652067055, 30655.0, 3116.7350775184136, 3184.442722523549, 3237.174520497258, 3238.3685181389637, 3245.902622921316, 243716.0, 244403.0, 244459.0, 245100.77449612817, 245728.0, 48003.75998119987, 49559.256611150144, 54038.0, 56281.71859140678, 56296.0, 59730.0, 59862.40137598568, 59926.70036649789, 59978.700106499185, 60062.399376005684, 12733.172343797623, 13973.79039314411, 14103.71792564143, 14140.363683634261, 14187.78718319226, 9666.661666841626, 9904.0, 10186.898131018743, 10721.83917241242, 10729.03438690506, 16601.833981660027, 16698.749518756984, 16734.330626775038, 16738.91630541864, 16739.497815065566, 6687.732490700415, 6805.863882722318, 6806.727730890808, 6971.8605627887155, 7018.912068629371, 880.1990188928045, 972.1445128287204, 973.9269554783419, 982.4841957971979, 1033.9069483746498, 28013.859930700622, 28709.42581148407, 28859.422811543653, 28866.892601384574, 29039.41921161565, 1040.7294103533136, 1044.2011860926398, 1060.4962642744788, 1066.1417558865187, 1134.4384529657873, 14960.70078598422, 14971.475998340005, 15229.390824367123, 15407.46073887431, 15492.767608485881, 328729.0, 331396.0, 331789.0, 334534.0, 334719.0, 49965.75017124891, 50036.0, 50103.24845127326, 50115.74942125266, 50209.24686129711, 1173.9413029348498, 1284.7751643462357, 1300.089937044073, 1310.4692599497027, 1315.4738104758173, 28640.28399289951, 29781.255468613457, 29787.10638680843, 29974.550381744728, 30192.84903575468, 42144.578554214066, 42285.0, 42733.78633106814, 42749.786251068545, 42801.357979629705, 253226.0, 254010.0, 254401.0, 255562.0, 256017.0, 31999.880004199742, 33065.66934330626, 41399.58600413957, 42229.57770422257, 42696.35955460733, 42875.0, 43033.13933721245, 43380.34929476123, 43767.78116109399, 43820.34269486024, 36256.81871590625, 36928.630713693594, 37137.62862371342, 37359.25281494409, 37381.6261837389, 36390.63609363873, 36739.816300918326, 36807.26385472222, 37046.0, 37320.44019339766, 34055.0, 34686.65313346935, 36563.0, 36981.075473113386, 37209.25581488409, 31773.682263177998, 33244.66755332416, 33325.50011749777, 33591.496127558596, 33803.0, 25461.36346591276, 25732.9717025573, 25739.871300643375, 25947.22158335253, 26018.0], "value": 17905.617719645896, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.3211267886822, 390.5040598439981, 392.65865069985057, 395.69729157194735, 409.05100167611135, 12783.169094008883, 12985.0, 16157.75763363549, 17488.912555437237, 17530.91234543827, 15866.84133158668, 16796.580085497837, 16911.40810071648, 16992.915035424838, 16998.06510641914, 4940.209566469374, 4978.925316120261, 4987.975060124712, 4999.700017998932, 5013.548780609744, 3366.9494957575657, 5049.621278404116, 5096.898062038776, 5131.461196574362, 5197.454267301936, 5469.781208751645, 5539.9169012464845, 5545.722713864311, 5555.027870122733, 5561.805336813212, 5485.753141108659, 5531.446855314456, 5532.087205611069, 5581.134924086773, 5589.133684278943, 5261.657992230493, 5371.4897084776885, 5380.166074258496, 5385.865353366197, 5410.810621628224, 19395.448364130978, 19625.116869740752, 20460.5907881843, 20505.077271522816, 20508.17967281307, 1997.6803711406105, 2026.0983862181336, 2028.5131568423471, 2043.6014977079476, 2046.2531176120758, 8600.0, 9112.772180695469, 9468.0, 9501.049895010512, 9508.334416590842, 1729.325563030419, 1779.9142523060923, 1803.7204233343853, 1827.250827160871, 1840.374272747259, 71622.64188679024, 71869.6406517964, 72756.0, 73004.0, 73375.0, 10178.083972442515, 10182.694519164437, 10201.591936322464, 10207.234457415723, 10349.586016559253, 14408.567742967727, 14535.127892326494, 14545.709085818224, 14554.490592829412, 14574.562763117123, 7092.078029856086, 7282.453815963824, 7359.484836061424, 7533.1336896256535, 7551.73568925085, 6999.685014174374, 7034.331738484938, 7039.577625342496, 7126.750563730348, 7200.963995179991, 4134.131832315187, 4263.488381394252, 4295.591918767712, 4381.91608970944, 4539.5460453955, 1583.952481425559, 1760.4102625620305, 1904.7999960004277, 1904.9047547622563, 1939.8933058681625, 887.3744010472601, 927.846905260638, 931.4504442379028, 973.8782652168479, 1006.0, 16027.519174424786, 16055.759163612554, 16077.35690572364, 16568.917155414147, 17032.914835425992, 895.7223260789176, 896.5786080542135, 901.3059943843205, 903.8734577159203, 917.2524392619994, 9255.722328330161, 9359.15767580921, 9461.716148515556, 9518.714438566854, 9619.422834629944, 371200.0, 371802.0, 371816.0, 382417.0, 408406.0, 78268.21731782724, 78431.0, 78648.21351786409, 78809.60595196988, 79529.0, 843.6541018182515, 907.2016625369764, 910.2035718746097, 917.6604656277086, 922.9400088994194, 13011.479540818262, 14581.416743330148, 14586.197759122926, 15238.3904643813, 15841.128737919526, 33627.0, 34152.829235853664, 34163.829180853936, 34196.48705269473, 35470.64529354673, 45440.09119817519, 47862.76068619634, 48261.0, 48496.27255591098, 53465.0, 6747.089142965833, 6992.580445173306, 7021.754238601625, 7133.251008644225, 7447.92552074494, 10840.783184336427, 11757.118216133653, 12083.818742719042, 12223.93888030554, 12233.632991010283, 10759.08547773448, 10825.296355736851, 10857.34855908648, 10864.348139111678, 10915.672529824118, 10530.526126324181, 10631.202659800701, 10698.0, 10785.514651840844, 10984.0, 7233.493655444279, 10798.568057277935, 10991.395473249047, 11013.504392302206, 11110.61112861046, 7365.852682946418, 7376.709075911801, 7385.593792341473, 7533.9246607535415, 7738.41961852852, 9011.468050431311, 10813.62152324665, 10992.17558683086, 11044.889551104707, 11073.778524429628], "value": 8207.88929126224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.611009752123, 1500.1149321900077, 1532.1879403915925, 1722.319683724928, 1877.6057028024113, 6573.112629794976, 7659.6936122555035, 7893.447458677902, 7897.881531777028, 8110.351171906248, 7859.253370929767, 8020.558869262189, 8041.236082572161, 8049.798755031127, 8060.032796064481, 1879.1543805287674, 2152.8923553822324, 2195.4182141732454, 2230.531588366445, 2235.7205349331334, 2248.179414513707, 2297.264875239924, 2299.41364951937, 2302.930912072632, 2303.170858490942, 2117.0579092303933, 2117.385958072165, 2162.145952348821, 2164.6969424280614, 2165.7942495462908, 1812.601227729904, 1842.0697547738307, 1858.2474097990334, 2035.4504283843353, 2074.0459388681224, 2146.624340740364, 2178.553396553703, 2184.5740080684272, 2184.912603495858, 2186.8578542394694, 7887.8028049298655, 7946.205379462065, 7947.642356093932, 7958.602069896482, 7959.562224077674, 4614.5385461453925, 4764.904701905977, 4767.785449654774, 4794.640401969831, 4797.808087676489, 2719.2114286856786, 2775.4865349910333, 2829.773618110567, 2910.0, 2914.970850291491, 871.2159056848824, 884.1953822021993, 884.4030279561334, 915.5102020419043, 921.7280902133887, 10853.34879907208, 10857.294275872122, 10879.456027198608, 10897.782044359068, 10898.073663738593, 4327.610515053598, 4376.518582955872, 4486.798094085709, 4491.708038977456, 4520.61574766142, 9259.166674999282, 9293.442393456415, 9297.209737172281, 9303.488308143116, 9317.487538185465, 4893.510648935078, 4894.535019173173, 4912.066707325596, 4913.901721965541, 4933.50664933511, 3723.646253605903, 4326.394304797331, 4449.354843547732, 4468.486124095705, 4509.0, 3754.2116155607628, 3780.111673756665, 3849.8845034649007, 3875.4768106305287, 3933.5869733678123, 1225.3199474291853, 1228.3858070964518, 1234.796258617318, 1239.2936026465009, 1250.2123662092824, 814.5519964019766, 916.702071826659, 922.326701507903, 934.2199263614822, 938.9108034736689, 5211.478852114758, 5465.125579907196, 5521.785207254397, 5529.253550770594, 5539.556835453234, 419.85305143199633, 423.5743078206383, 431.43912913213325, 436.8187202311069, 440.3394907638542, 3679.79761113136, 3692.4276737105793, 3695.2978934002454, 3729.5151630287887, 3738.0, 8738.300935925237, 9111.544422778834, 9151.084891510796, 9157.45055296671, 9180.17378435944, 9004.414713043761, 9050.140236677506, 9082.772930676785, 9100.908990910006, 9134.0, 6879.828004299832, 7152.392046675986, 7239.420846332281, 7341.963290183515, 7428.4800063995, 5170.121079416433, 5425.701586412785, 5463.262459567907, 5533.059379905345, 5550.750216240198, 8677.526904832093, 8934.687285944961, 9099.681511147079, 9133.497657628894, 9137.451752894847, 9131.269498439977, 9244.306676999386, 9254.167124958785, 9261.0, 9299.814003720023, 6149.754009839557, 6675.232348279816, 6675.299093595103, 6836.38472537474, 6883.827904302432, 7571.507851989603, 7616.3906887449975, 7661.616919154131, 7670.578118203329, 7685.193054729173, 6903.998920156449, 7003.299670032956, 7048.647567621496, 7155.105611798525, 7160.782666946736, 6911.7926462206215, 6931.133608298963, 7000.089988301692, 7095.077639906779, 7112.03987461686, 6275.592086514362, 6282.937170628418, 6291.779787707408, 6330.272018717907, 6366.77716279928, 4717.740524271198, 4720.070281926397, 4731.45588257341, 4762.809487620595, 4802.327674125555, 4690.437147542317, 4699.929501057419, 4793.736344501086, 4851.320815085962, 4898.8775280618265], "value": 4122.301421146864, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.6938388152144, 305.36788847086524, 320.64728798321846, 327.54961927349893, 328.738652771047, 4734.7159170449795, 4765.831546646276, 4766.713997160173, 4787.2101103318, 4792.999999999995, 4670.90658186836, 4707.411573553306, 4732.550407711271, 4735.455422626399, 4741.217699079648, 3542.592601850788, 3673.3755261605456, 3739.364308067635, 3773.490578771858, 3803.0302272920367, 3738.6448287412654, 3748.756330838487, 3754.3242216401036, 3764.698824094066, 3778.6410291022307, 3532.7880327180324, 3713.6286371362917, 3719.5536535615765, 3729.347364211266, 3745.288395204916, 3637.7999210043436, 3700.4819325294347, 3710.4619830124752, 3718.5165928429133, 3729.4219395993664, 3283.9014829555153, 3288.3423315337027, 3292.1440425489304, 3294.7858389204625, 3295.1597342677464, 4468.262736648449, 4497.078098989699, 4519.548045195487, 4523.705959112647, 4525.705829121097, 4027.3153563894207, 4249.1714115747445, 4251.256030194703, 4259.190753756808, 4299.892502687458, 4919.4834542373255, 4934.827281045146, 4938.160512712811, 4952.182889823175, 4963.602911767086, 3583.552055993001, 3586.264815712773, 3588.5693716753894, 3619.167591453954, 3635.1275693833554, 5039.571636410909, 5172.870678233075, 5198.220266959948, 5229.326615483047, 5235.895282094375, 3638.8180590970346, 3648.1062139775927, 3648.3980143276062, 3649.3431182386894, 3671.3575124353133, 3688.870889518854, 3689.594144644087, 3691.9815400922826, 3705.7961812100593, 3709.703223742094, 4187.225363307737, 4227.534971153171, 4229.471316085489, 4239.236937351307, 4242.366688824763, 4734.668573199911, 4921.867970366836, 4931.161702510582, 4956.132676781549, 4957.628177886673, 1271.7329360834144, 1275.1583954589926, 1279.0918447902093, 1279.8144269081117, 1295.1905059337914, 820.0528389709895, 822.3996482567759, 852.9445586036887, 866.2550206822128, 867.2584939876315, 4262.42457268271, 4266.87199384019, 4274.7435153890865, 4276.957230427718, 4293.3989241506215, 5018.372703412074, 5032.0, 5034.546890779774, 5054.7978080876355, 5077.019962214739, 4209.26337890874, 4214.7247481136865, 4277.871663850089, 4281.522874608255, 4295.4201182839915, 2699.8650067496546, 2703.864806759654, 2714.0, 2716.1172618898936, 2725.0, 4716.504766999485, 4777.518969119584, 4794.760261986957, 4810.13417584838, 4810.350602668595, 4889.9486610378935, 5141.742912854343, 5147.356580427447, 5164.48355164488, 5186.377634683787, 4732.739699316502, 4733.6213102951, 4733.644976626698, 4744.833930812474, 4754.78603462845, 4978.800847966041, 4996.450390457119, 5008.173651347487, 5029.371328583927, 5048.697078175322, 4658.138244424792, 4859.246816743411, 4868.683535570177, 4964.971367465915, 4970.478099799469, 4660.906781864412, 4696.074609410125, 4710.387649605666, 4718.669693121448, 4735.621150308036, 3619.5837478690287, 3622.565292164957, 3627.836747346323, 3644.854205831737, 3657.65252301031, 4829.491616712731, 5030.823921162888, 5033.899322013466, 5060.266261392004, 5065.949340506695, 4814.614830813456, 4828.95171048285, 4835.879103022453, 4850.636202284913, 4867.58625516835, 4822.662413631012, 4827.0, 4841.588464980516, 4865.440474345495, 4879.536444037811, 4661.533846615312, 4770.475247722818, 4795.0, 4818.397700287464, 4824.226070009829, 4869.9026019480125, 4878.341423907869, 4887.0, 4887.951120488749, 4914.729689867092, 4760.690555113906, 4780.808767649394, 4819.7108173509705, 4835.920554161744, 4856.247281671346], "value": 3727.944589611988, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.5856081388317, 684.9863002739942, 695.2109355881075, 698.1691786773739, 724.3661795928564, 11739.706507337321, 12322.630321090359, 12347.876521234784, 12348.197367171128, 12354.629361119158, 11454.255473394223, 11465.426728663575, 11511.712207194825, 11517.193796434252, 11536.596219132332, 692.882210024297, 854.3976496569934, 870.1081391573624, 884.2881480408269, 887.0818702642766, 878.9692360767374, 879.8944126704804, 881.4623079921242, 884.5975081337975, 886.1404437695425, 930.3441074042818, 932.0120672087575, 932.2402242172642, 932.7155217658611, 933.2953620016877, 728.6101935464527, 898.820235952812, 925.796324808541, 926.4117285523673, 929.7675581104724, 684.9726010959556, 685.7874059041663, 685.8456847209388, 685.9657017149123, 686.1286166568431, 11445.141614378872, 11471.311721296666, 11478.65564033072, 11492.827607585892, 11506.482208300647, 12097.758044839142, 12121.93939030308, 12457.439415226247, 12491.376443670175, 12821.23072615646, 10103.696889093339, 10141.340812847142, 10253.948730256376, 10434.426106564137, 10435.843462348072, 4030.6372426481757, 4246.392410109245, 4268.850590229358, 4342.782860856976, 4396.274614688573, 45309.09381812345, 45820.54179458196, 45825.312620310724, 45897.770511147566, 45977.08045839064, 10362.585496580203, 10473.947630261953, 10676.89323106759, 11069.889301107047, 11090.833637495443, 18832.623347532972, 18841.434756957315, 18951.526211844815, 18966.336178233694, 18968.43094707161, 6907.89638155418, 7004.579725216503, 7018.719251229996, 7053.047838541834, 7124.358807707331, 3685.8758078786227, 3722.3485889969147, 3725.1990821973404, 3726.925461490755, 3730.8880733578035, 2755.6968733439307, 2792.622995895528, 2827.7313655202724, 2828.971710282871, 2831.546952487592, 520.8828013696964, 523.0271694647952, 524.3288590604033, 530.167636810212, 531.147508249262, 510.57622173595826, 525.9237410575522, 576.7404667899463, 585.3268740947859, 592.3691268798716, 7339.266073392618, 7519.736809211651, 7565.130010048805, 7591.924080759121, 7624.733134340271, 468.24846121974423, 491.1968930798099, 529.7986765029341, 533.2135100726475, 568.1421054208092, 3312.0727341528814, 3423.383790917609, 3493.615702272748, 3582.5880023797076, 3598.5861625913353, 47696.5230347699, 49068.50931490639, 49379.753101234266, 49411.0, 49531.50468495269, 43350.56649433466, 44619.776901115285, 45794.0, 45899.541004589526, 46697.766511167225, 41926.79036604858, 42087.36868946968, 42512.0, 42650.36024459574, 42787.7860610695, 6966.094407726963, 7057.788266352018, 7160.606166660884, 7165.104361954755, 7280.672369743268, 28485.145445635833, 28796.136115916557, 28943.0, 29200.0, 29885.252868678457, 33992.66007339895, 34553.65446345605, 37425.81287093656, 37609.623903761705, 37745.81127094457, 9210.0, 9471.573779180091, 9569.0, 9610.087041731025, 9772.657956971472, 10087.344322619007, 10161.745956350855, 10201.84697229557, 10344.27590068688, 10381.896181038395, 9011.504367259864, 9113.407628504126, 9152.359334846496, 9422.764430889283, 9804.068613481708, 8628.52543110135, 8730.17063378978, 8906.86639700418, 8987.101289870961, 9006.59470323824, 8801.119888011148, 8809.42738721981, 9096.27229821626, 9112.908870911206, 9495.905040949778, 7739.845203095794, 7772.7668169954995, 7833.412494062854, 8145.348372130334, 8167.0, 7993.880091798744, 8015.639296231567, 8042.83914321722, 8084.797880053045, 8177.4684645497855], "value": 5752.9447042950405, "unit": "score"}], "name": "dromaeo_css", "value": 6273.881891822607, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 113.0, 114.0, 115.0, 114.0, 116.0, 114.0, 112.0, 116.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 138.0, 157.0, 210.0, 130.0, 157.0, 156.0, 132.0, 204.0, 138.0], "value": 157.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 162.0, 161.0, 160.0, 163.0, 162.0, 163.0, 162.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 156.0, 77.0, 75.0, 76.0, 76.0, 128.0, 76.0], "value": 89.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 153.0, 93.0, 94.0, 94.0, 125.0, 93.0, 94.0, 123.0], "value": 105.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 116.0, 118.0, 117.0, 116.0, 118.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 67.0, 69.0, 67.0, 68.0, 68.0, 67.0, 120.0, 65.0, 65.0], "value": 73.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0], "value": 64.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 87.0, 84.0, 83.0, 83.0, 91.0, 85.0, 83.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 131.0, 134.0, 139.0, 141.0, 129.0, 132.0, 195.0, 132.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 179.0, 181.0, 181.0, 178.0, 174.0, 178.0, 186.0, 181.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 72.0, 69.0, 70.0, 69.0, 69.0, 66.0, 66.0, 67.0], "value": 68.8, "unit": "score"}], "name": "kraken", "value": 1596.3999999999999, "alertThreshold": 2.0}]} 22:17:17 INFO - Return code: 0 22:17:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:17:17 ERROR - # TBPL SUCCESS # 22:17:17 INFO - Running post-action listener: _resource_record_post_action 22:17:17 INFO - ##### 22:17:17 INFO - ##### Finished run-tests step (success) 22:17:17 INFO - ##### 22:17:17 INFO - Running post-run listener: _resource_record_post_run 22:17:19 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 529739776; Read time: 12; Write time: 440616 22:17:19 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234680320; Read time: 0; Write time: 250100 22:17:19 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294879232; Read time: 12; Write time: 190492 22:17:19 INFO - Running post-run listener: _upload_blobber_files 22:17:19 INFO - Blob upload gear active. 22:17:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:17:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:17:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:17:19 INFO - (blobuploader) - INFO - Open directory for files ... 22:17:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:17:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:17:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:20 INFO - (blobuploader) - INFO - Done attempting. 22:17:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:17:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:17:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:17:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:17:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:17:20 INFO - (blobuploader) - INFO - Done attempting. 22:17:20 INFO - (blobuploader) - INFO - Iteration through files over. 22:17:20 INFO - Return code: 0 22:17:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:17:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:17:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4d724e3b712975028a6d9133616faacf8557ccbcc5c08de99c7ef6b43b9bf0f0485c3b06304213b9aef849aa80b73a4e9a9965ff96e171c4b6c993dc6baa133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac96c4992b04eec8b3f16b4f6ebceeecfdc3d17aa66a320ac41ae47bef20196b2c9a100fed6bdb2ad94cdd2364476648db5358450f19ced860f936a665b28d10"} 22:17:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:17:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:17:20 INFO - Contents: 22:17:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4d724e3b712975028a6d9133616faacf8557ccbcc5c08de99c7ef6b43b9bf0f0485c3b06304213b9aef849aa80b73a4e9a9965ff96e171c4b6c993dc6baa133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac96c4992b04eec8b3f16b4f6ebceeecfdc3d17aa66a320ac41ae47bef20196b2c9a100fed6bdb2ad94cdd2364476648db5358450f19ced860f936a665b28d10"} 22:17:20 INFO - Running post-run listener: copy_logs_to_upload_dir 22:17:20 INFO - Copying logs to upload dir... 22:17:20 INFO - mkdir: /builds/slave/test/build/upload/logs 22:17:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.240877 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-05-10 22:17:20.781189) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 22:17:20.785085) ========= 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=523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489 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/4d724e3b712975028a6d9133616faacf8557ccbcc5c08de99c7ef6b43b9bf0f0485c3b06304213b9aef849aa80b73a4e9a9965ff96e171c4b6c993dc6baa133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac96c4992b04eec8b3f16b4f6ebceeecfdc3d17aa66a320ac41ae47bef20196b2c9a100fed6bdb2ad94cdd2364476648db5358450f19ced860f936a665b28d10"} build_url:https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/JH-lGuG8RLKyxh_BbfrGnw/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/4d724e3b712975028a6d9133616faacf8557ccbcc5c08de99c7ef6b43b9bf0f0485c3b06304213b9aef849aa80b73a4e9a9965ff96e171c4b6c993dc6baa133b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ac96c4992b04eec8b3f16b4f6ebceeecfdc3d17aa66a320ac41ae47bef20196b2c9a100fed6bdb2ad94cdd2364476648db5358450f19ced860f936a665b28d10"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 22:17:20.809512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:17:20.809842) ========= 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=523ca41ca1e0623de333ad52000001bf-1462942570.342700-1667379489 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 22:17:20.892627) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 22:17:20.892950) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 22:17:20.893233) ========= ========= Total master_lag: 0.17 =========