builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1461584800.99 results: success (0) buildid: 20160422035709 builduid: 3af33a9a7de54ba3986a82f34ab86caa revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:40.992176) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:40.992550) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:40.992860) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.479595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.479897) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 04:46:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-04-25 04:46:41 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085226 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.580220) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.580530) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016568 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.633738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:41.634054) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 80598be5d80e695727ff74e712faa623b02f3c70 --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 80598be5d80e695727ff74e712faa623b02f3c70 --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=9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 04:46:42,095 truncating revision to first 12 chars 2016-04-25 04:46:42,095 Setting DEBUG logging. 2016-04-25 04:46:42,095 attempt 1/10 2016-04-25 04:46:42,095 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/80598be5d80e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 04:46:42,325 unpacking tar archive at: fx-team-80598be5d80e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.507169 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.156702) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.157001) ========= script_repo_revision: 80598be5d80e695727ff74e712faa623b02f3c70 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.157382) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.157638) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 04:46:42.167517) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-04-25 04:46:42.167795) ========= /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=9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:46:42 INFO - MultiFileLogger online at 20160425 04:46:42 in /builds/slave/test 04:46:42 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 04:46:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:42 INFO - {'append_to_log': False, 04:46:42 INFO - 'base_work_dir': '/builds/slave/test', 04:46:42 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 04:46:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:42 INFO - 'branch': 'Fx-Team-Non-PGO', 04:46:42 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:42 INFO - 'config_files': ('talos/linux_config.py',), 04:46:42 INFO - 'default_actions': ('clobber', 04:46:42 INFO - 'read-buildbot-config', 04:46:42 INFO - 'download-and-extract', 04:46:42 INFO - 'populate-webroot', 04:46:42 INFO - 'create-virtualenv', 04:46:42 INFO - 'install', 04:46:42 INFO - 'run-tests'), 04:46:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:42 INFO - 'download_minidump_stackwalk': True, 04:46:42 INFO - 'download_symbols': 'ondemand', 04:46:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:42 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:42 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:42 INFO - 'installer_path': 'installer.exe', 04:46:42 INFO - 'log_level': 'info', 04:46:42 INFO - 'log_name': 'talos', 04:46:42 INFO - 'log_to_console': True, 04:46:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:46:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:46:42 INFO - 'opt_config_files': (), 04:46:42 INFO - 'pip_index': False, 04:46:42 INFO - 'sps_profile': False, 04:46:42 INFO - 'sps_profile_interval': 0, 04:46:42 INFO - 'suite': 'dromaeojs', 04:46:42 INFO - 'system_bits': '32', 04:46:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:42 INFO - 'title': 'talos-linux64-ix-040', 04:46:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:42 INFO - 'use_talos_json': True, 04:46:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:42 INFO - 'work_dir': 'build'} 04:46:42 INFO - ##### 04:46:42 INFO - ##### Running clobber step. 04:46:42 INFO - ##### 04:46:42 INFO - Running pre-action listener: _resource_record_pre_action 04:46:42 INFO - Running main action method: clobber 04:46:42 INFO - rmtree: /builds/slave/test/build 04:46:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:43 INFO - Running post-action listener: _resource_record_post_action 04:46:43 INFO - ##### 04:46:43 INFO - ##### Running read-buildbot-config step. 04:46:43 INFO - ##### 04:46:43 INFO - Running pre-action listener: _resource_record_pre_action 04:46:43 INFO - Running main action method: read_buildbot_config 04:46:43 INFO - Using buildbot properties: 04:46:43 INFO - { 04:46:43 INFO - "project": "", 04:46:43 INFO - "product": "firefox", 04:46:43 INFO - "script_repo_revision": "production", 04:46:43 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 04:46:43 INFO - "repository": "", 04:46:43 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 04:46:43 INFO - "buildid": "20160422035709", 04:46:43 INFO - "pgo_build": "False", 04:46:43 INFO - "basedir": "/builds/slave/test", 04:46:43 INFO - "buildnumber": 2876, 04:46:43 INFO - "slavename": "talos-linux64-ix-040", 04:46:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:46:43 INFO - "platform": "ubuntu64_hw", 04:46:43 INFO - "scheduler": "tests-fx-team-linux64-talos", 04:46:43 INFO - "branch": "fx-team", 04:46:43 INFO - "repo_path": "integration/fx-team", 04:46:43 INFO - "revision": "80598be5d80e695727ff74e712faa623b02f3c70", 04:46:43 INFO - "stage_platform": "linux64", 04:46:43 INFO - "builduid": "3af33a9a7de54ba3986a82f34ab86caa", 04:46:43 INFO - "slavebuilddir": "test" 04:46:43 INFO - } 04:46:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 04:46:43 INFO - Running post-action listener: _resource_record_post_action 04:46:43 INFO - ##### 04:46:43 INFO - ##### Running download-and-extract step. 04:46:43 INFO - ##### 04:46:43 INFO - Running pre-action listener: _resource_record_pre_action 04:46:43 INFO - Running main action method: download_and_extract 04:46:43 INFO - mkdir: /builds/slave/test/build/tests 04:46:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:46:43 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:46:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:46:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:46:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:46:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:46:49 INFO - Downloaded 1361 bytes. 04:46:49 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 04:46:49 INFO - Using the following test package requirements: 04:46:49 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 04:46:49 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:46:49 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:46:49 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'jsshell-linux-x86_64.zip'], 04:46:49 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:46:49 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 04:46:49 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:46:49 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:46:49 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:46:49 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 04:46:49 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:46:49 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:46: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')]} 04:46:49 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:46:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:46:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:46:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:46:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:46:57 INFO - Downloaded 22281373 bytes. 04:46:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:46:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:46:58 INFO - Return code: 0 04:46:58 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:46:58 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:46:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:46:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:46:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 04:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:47:00 INFO - Downloaded 22281373 bytes. 04:47:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:47:01 INFO - Return code: 0 04:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:01 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:47:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 04:47:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 04:47:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 04:47:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:47:02 INFO - Downloaded 10845952 bytes. 04:47:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:47:03 INFO - Return code: 0 04:47: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')]} 04:47:03 INFO - https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:47:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:47:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:47:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:47:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:47:04 INFO - Downloaded 54650321 bytes. 04:47:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:47:04 INFO - mkdir: /builds/slave/test/properties 04:47:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:04 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:04 INFO - Contents: 04:47:04 INFO - build_url:https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 04:47:05 INFO - Running post-action listener: _resource_record_post_action 04:47:05 INFO - Running post-action listener: set_extra_try_arguments 04:47:05 INFO - ##### 04:47:05 INFO - ##### Running populate-webroot step. 04:47:05 INFO - ##### 04:47:05 INFO - Running pre-action listener: _resource_record_pre_action 04:47:05 INFO - Running main action method: populate_webroot 04:47:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:05 INFO - '--tppagecycles', 04:47:05 INFO - '7'], 04:47:05 INFO - 'tests': ['tsvgm']}}, 04:47:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['damp', 'tps']}, 04:47:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'tests': ['damp', 'tps']}, 04:47:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['dromaeo_dom']}, 04:47:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['a11yr', 04:47:05 INFO - 'ts_paint', 04:47:05 INFO - 'tpaint', 04:47:05 INFO - 'sessionrestore', 04:47:05 INFO - 'sessionrestore_no_auto_restore', 04:47:05 INFO - 'tabpaint']}, 04:47:05 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:05 INFO - 'ts_paint', 04:47:05 INFO - 'tpaint', 04:47:05 INFO - 'sessionrestore', 04:47:05 INFO - 'sessionrestore_no_auto_restore', 04:47:05 INFO - 'tabpaint']}, 04:47:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:05 INFO - 'ts_paint', 04:47:05 INFO - 'tpaint', 04:47:05 INFO - 'sessionrestore', 04:47:05 INFO - 'sessionrestore_no_auto_restore', 04:47:05 INFO - 'tabpaint']}, 04:47:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:05 INFO - 'ts_paint', 04:47:05 INFO - 'tpaint', 04:47:05 INFO - 'sessionrestore', 04:47:05 INFO - 'sessionrestore_no_auto_restore', 04:47:05 INFO - 'tabpaint']}, 04:47:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['a11yr', 04:47:05 INFO - 'ts_paint', 04:47:05 INFO - 'tpaint', 04:47:05 INFO - 'sessionrestore', 04:47:05 INFO - 'sessionrestore_no_auto_restore', 04:47:05 INFO - 'tabpaint']}, 04:47:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['a11yr', 04:47:05 INFO - 'ts_paint', 04:47:05 INFO - 'tpaint', 04:47:05 INFO - 'sessionrestore', 04:47:05 INFO - 'sessionrestore_no_auto_restore', 04:47:05 INFO - 'tabpaint']}, 04:47:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['tsvgx', 04:47:05 INFO - 'tsvgr_opacity', 04:47:05 INFO - 'tart', 04:47:05 INFO - 'tscrollx', 04:47:05 INFO - 'cart']}, 04:47:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:05 INFO - 'tsvgr_opacity', 04:47:05 INFO - 'tart', 04:47:05 INFO - 'tscrollx', 04:47:05 INFO - 'cart']}, 04:47:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'talos_options': ['--disable-e10s'], 04:47:05 INFO - 'tests': ['tp5o']}, 04:47:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'tests': ['tp5o']}, 04:47:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'talos_options': ['--disable-e10s', 04:47:05 INFO - '--xperf_path', 04:47:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:05 INFO - 'tests': ['tp5n']}, 04:47:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:47:05 INFO - 'talos_options': ['--xperf_path', 04:47:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:05 INFO - 'tests': ['tp5n']}}, 04:47:05 INFO - 'talos.zip': {'path': '', 04:47:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:05 INFO - Running post-action listener: _resource_record_post_action 04:47:05 INFO - ##### 04:47:05 INFO - ##### Running create-virtualenv step. 04:47:05 INFO - ##### 04:47:05 INFO - Running pre-action listener: _resource_record_pre_action 04:47:05 INFO - Running main action method: create_virtualenv 04:47:05 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:47:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:05 INFO - Using real prefix '/usr' 04:47:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:05 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:07 INFO - Installing pip.................done. 04:47:07 INFO - Return code: 0 04:47:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:07 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:07 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 04:47:07 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 04:47:07 INFO - Using env: {'DISPLAY': ':0', 04:47:07 INFO - 'HOME': '/home/cltbld', 04:47:07 INFO - 'LANG': 'en_US.UTF-8', 04:47:07 INFO - 'LANGUAGE': 'en_US:en', 04:47:07 INFO - 'LOGNAME': 'cltbld', 04:47:07 INFO - 'MAIL': '/var/mail/cltbld', 04:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:07 INFO - 'MOZ_NO_REMOTE': '1', 04:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:07 INFO - 'NO_EM_RESTART': '1', 04:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:07 INFO - 'PWD': '/builds/slave/test', 04:47:07 INFO - 'SHELL': '/bin/bash', 04:47:07 INFO - 'SHLVL': '1', 04:47:07 INFO - 'TERM': 'linux', 04:47:07 INFO - 'TMOUT': '86400', 04:47:07 INFO - 'USER': 'cltbld', 04:47:07 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:07 INFO - '_': '/tools/buildbot/bin/python'} 04:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:07 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 04:47:07 INFO - Cleaning up... 04:47:07 INFO - Return code: 0 04:47:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:07 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:07 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 04:47:07 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 04:47:07 INFO - Using env: {'DISPLAY': ':0', 04:47:07 INFO - 'HOME': '/home/cltbld', 04:47:07 INFO - 'LANG': 'en_US.UTF-8', 04:47:07 INFO - 'LANGUAGE': 'en_US:en', 04:47:07 INFO - 'LOGNAME': 'cltbld', 04:47:07 INFO - 'MAIL': '/var/mail/cltbld', 04:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:07 INFO - 'MOZ_NO_REMOTE': '1', 04:47:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:07 INFO - 'NO_EM_RESTART': '1', 04:47:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:07 INFO - 'PWD': '/builds/slave/test', 04:47:07 INFO - 'SHELL': '/bin/bash', 04:47:07 INFO - 'SHLVL': '1', 04:47:07 INFO - 'TERM': 'linux', 04:47:07 INFO - 'TMOUT': '86400', 04:47:07 INFO - 'USER': 'cltbld', 04:47:07 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:07 INFO - '_': '/tools/buildbot/bin/python'} 04:47:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:08 INFO - Downloading/unpacking psutil>=0.7.1 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:10 INFO - Installing collected packages: psutil 04:47:10 INFO - Running setup.py install for psutil 04:47:10 INFO - building 'psutil._psutil_linux' extension 04:47:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:47:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:47:10 INFO - building 'psutil._psutil_posix' extension 04:47:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:47:10 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:47:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:10 INFO - Successfully installed psutil 04:47:10 INFO - Cleaning up... 04:47:10 INFO - Return code: 0 04:47:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', '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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:47: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 mozsystemmonitor==0.0.0 04:47:10 INFO - Using env: {'DISPLAY': ':0', 04:47:10 INFO - 'HOME': '/home/cltbld', 04:47:10 INFO - 'LANG': 'en_US.UTF-8', 04:47:10 INFO - 'LANGUAGE': 'en_US:en', 04:47:10 INFO - 'LOGNAME': 'cltbld', 04:47:10 INFO - 'MAIL': '/var/mail/cltbld', 04:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:10 INFO - 'MOZ_NO_REMOTE': '1', 04:47:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:10 INFO - 'NO_EM_RESTART': '1', 04:47:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:10 INFO - 'PWD': '/builds/slave/test', 04:47:10 INFO - 'SHELL': '/bin/bash', 04:47:10 INFO - 'SHLVL': '1', 04:47:10 INFO - 'TERM': 'linux', 04:47:10 INFO - 'TMOUT': '86400', 04:47:10 INFO - 'USER': 'cltbld', 04:47:10 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:10 INFO - '_': '/tools/buildbot/bin/python'} 04:47:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:47:10 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 04:47:10 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 04:47:10 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 04:47:10 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 04:47:10 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 04:47:10 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 04:47:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:47:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:47:12 INFO - Installing collected packages: mozsystemmonitor 04:47:12 INFO - Running setup.py install for mozsystemmonitor 04:47:12 INFO - Successfully installed mozsystemmonitor 04:47:12 INFO - Cleaning up... 04:47:13 INFO - Return code: 0 04:47:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', '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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47: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 blobuploader==1.2.4 04:47:13 INFO - Using env: {'DISPLAY': ':0', 04:47:13 INFO - 'HOME': '/home/cltbld', 04:47:13 INFO - 'LANG': 'en_US.UTF-8', 04:47:13 INFO - 'LANGUAGE': 'en_US:en', 04:47:13 INFO - 'LOGNAME': 'cltbld', 04:47:13 INFO - 'MAIL': '/var/mail/cltbld', 04:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:13 INFO - 'MOZ_NO_REMOTE': '1', 04:47:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:13 INFO - 'NO_EM_RESTART': '1', 04:47:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:13 INFO - 'PWD': '/builds/slave/test', 04:47:13 INFO - 'SHELL': '/bin/bash', 04:47:13 INFO - 'SHLVL': '1', 04:47:13 INFO - 'TERM': 'linux', 04:47:13 INFO - 'TMOUT': '86400', 04:47:13 INFO - 'USER': 'cltbld', 04:47:13 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:13 INFO - '_': '/tools/buildbot/bin/python'} 04:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:13 INFO - Downloading/unpacking blobuploader==1.2.4 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:15 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:47:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:15 INFO - Downloading docopt-0.6.1.tar.gz 04:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:47:15 INFO - Installing collected packages: blobuploader, requests, docopt 04:47:15 INFO - Running setup.py install for blobuploader 04:47:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:47:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:47:15 INFO - Running setup.py install for requests 04:47:16 INFO - Running setup.py install for docopt 04:47:16 INFO - Successfully installed blobuploader requests docopt 04:47:16 INFO - Cleaning up... 04:47:16 INFO - Return code: 0 04:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:16 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:16 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 04:47:16 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 04:47:16 INFO - Using env: {'DISPLAY': ':0', 04:47:16 INFO - 'HOME': '/home/cltbld', 04:47:16 INFO - 'LANG': 'en_US.UTF-8', 04:47:16 INFO - 'LANGUAGE': 'en_US:en', 04:47:16 INFO - 'LOGNAME': 'cltbld', 04:47:16 INFO - 'MAIL': '/var/mail/cltbld', 04:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:16 INFO - 'MOZ_NO_REMOTE': '1', 04:47:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:16 INFO - 'NO_EM_RESTART': '1', 04:47:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:16 INFO - 'PWD': '/builds/slave/test', 04:47:16 INFO - 'SHELL': '/bin/bash', 04:47:16 INFO - 'SHLVL': '1', 04:47:16 INFO - 'TERM': 'linux', 04:47:16 INFO - 'TMOUT': '86400', 04:47:16 INFO - 'USER': 'cltbld', 04:47:16 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:16 INFO - '_': '/tools/buildbot/bin/python'} 04:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:16 INFO - Running setup.py (path:/tmp/pip-i9LnQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:16 INFO - Running setup.py (path:/tmp/pip-yjiG3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:16 INFO - Running setup.py (path:/tmp/pip-fkfGZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:16 INFO - Running setup.py (path:/tmp/pip-EidzmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:16 INFO - Running setup.py (path:/tmp/pip-ZxvC6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:17 INFO - Running setup.py (path:/tmp/pip-iysCk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:17 INFO - Running setup.py (path:/tmp/pip-7D67su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:17 INFO - Running setup.py (path:/tmp/pip-xZemA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:17 INFO - Running setup.py (path:/tmp/pip-1vZohQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:17 INFO - Running setup.py (path:/tmp/pip-M0Wv6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:17 INFO - Running setup.py (path:/tmp/pip-39Aq9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:17 INFO - Running setup.py (path:/tmp/pip-P3Hvy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:17 INFO - Running setup.py (path:/tmp/pip-vmAG25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:17 INFO - Running setup.py (path:/tmp/pip-Q40OrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:17 INFO - Running setup.py (path:/tmp/pip-bMhDF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:17 INFO - Running setup.py (path:/tmp/pip-GuioOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:17 INFO - Running setup.py (path:/tmp/pip-BnCZvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:47:17 INFO - Running setup.py install for manifestparser 04:47:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:47:17 INFO - Running setup.py install for mozcrash 04:47:18 INFO - Running setup.py install for mozdebug 04:47:18 INFO - Running setup.py install for mozdevice 04:47:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozfile 04:47:18 INFO - Running setup.py install for mozhttpd 04:47:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozinfo 04:47:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozInstall 04:47:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for mozleak 04:47:18 INFO - Running setup.py install for mozlog 04:47:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:47:18 INFO - Running setup.py install for moznetwork 04:47:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozprocess 04:47:19 INFO - Running setup.py install for mozprofile 04:47:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozrunner 04:47:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Running setup.py install for mozscreenshot 04:47:19 INFO - Running setup.py install for moztest 04:47:19 INFO - Running setup.py install for mozversion 04:47:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:47:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:47:19 INFO - Cleaning up... 04:47:19 INFO - Return code: 0 04:47:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:19 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:19 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 04:47:19 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 04:47:19 INFO - Using env: {'DISPLAY': ':0', 04:47:19 INFO - 'HOME': '/home/cltbld', 04:47:19 INFO - 'LANG': 'en_US.UTF-8', 04:47:19 INFO - 'LANGUAGE': 'en_US:en', 04:47:19 INFO - 'LOGNAME': 'cltbld', 04:47:19 INFO - 'MAIL': '/var/mail/cltbld', 04:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:19 INFO - 'MOZ_NO_REMOTE': '1', 04:47:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:19 INFO - 'NO_EM_RESTART': '1', 04:47:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:19 INFO - 'PWD': '/builds/slave/test', 04:47:19 INFO - 'SHELL': '/bin/bash', 04:47:19 INFO - 'SHLVL': '1', 04:47:19 INFO - 'TERM': 'linux', 04:47:19 INFO - 'TMOUT': '86400', 04:47:19 INFO - 'USER': 'cltbld', 04:47:19 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:19 INFO - '_': '/tools/buildbot/bin/python'} 04:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:47:19 INFO - Running setup.py (path:/tmp/pip-pP9TRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:47:19 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)) 04:47:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:47:19 INFO - Running setup.py (path:/tmp/pip-qxboTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:47:20 INFO - Running setup.py (path:/tmp/pip-U2ylWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:47:20 INFO - Running setup.py (path:/tmp/pip-vtX7Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:47:20 INFO - Running setup.py (path:/tmp/pip-C2ZGvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:47:20 INFO - Running setup.py (path:/tmp/pip-N77qBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:47:20 INFO - Running setup.py (path:/tmp/pip-a5cepv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:47:20 INFO - Running setup.py (path:/tmp/pip-uyHhu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:47:20 INFO - Running setup.py (path:/tmp/pip-O3cSKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:47:20 INFO - Running setup.py (path:/tmp/pip-vA885P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:47:20 INFO - Running setup.py (path:/tmp/pip-E0XvyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:47:20 INFO - Running setup.py (path:/tmp/pip-t4iXRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:47:20 INFO - Running setup.py (path:/tmp/pip-jHUmTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:47:20 INFO - Running setup.py (path:/tmp/pip-BIQ7bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:20 INFO - Running setup.py (path:/tmp/pip-QLd3Dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:47:20 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)) 04:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:47:20 INFO - Running setup.py (path:/tmp/pip-gwT0Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:47:21 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)) 04:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:47:21 INFO - Running setup.py (path:/tmp/pip-0njQup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:47:21 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)) 04:47:21 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)) 04:47:21 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)) 04:47:21 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)) 04:47:21 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)) 04:47:21 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)) 04:47:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:47:21 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 04:47:21 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 04:47:21 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 04:47:21 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 04:47:21 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 04:47:21 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 04:47:22 INFO - Downloading blessings-1.6.tar.gz 04:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:47:23 INFO - Installing collected packages: blessings 04:47:23 INFO - Running setup.py install for blessings 04:47:23 INFO - Successfully installed blessings 04:47:23 INFO - Cleaning up... 04:47:23 INFO - Return code: 0 04:47:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:23 INFO - Reading from file tmpfile_stdout 04:47:23 INFO - Current package versions: 04:47:23 INFO - argparse == 1.2.1 04:47:23 INFO - blessings == 1.6 04:47:23 INFO - blobuploader == 1.2.4 04:47:23 INFO - docopt == 0.6.1 04:47:23 INFO - manifestparser == 1.1 04:47:23 INFO - mozInstall == 1.12 04:47:23 INFO - mozcrash == 0.17 04:47:23 INFO - mozdebug == 0.1 04:47:23 INFO - mozdevice == 0.48 04:47:23 INFO - mozfile == 1.2 04:47:23 INFO - mozhttpd == 0.7 04:47:23 INFO - mozinfo == 0.9 04:47:23 INFO - mozleak == 0.1 04:47:23 INFO - mozlog == 3.1 04:47:23 INFO - moznetwork == 0.27 04:47:23 INFO - mozprocess == 0.22 04:47:23 INFO - mozprofile == 0.28 04:47:23 INFO - mozrunner == 6.11 04:47:23 INFO - mozscreenshot == 0.1 04:47:23 INFO - mozsystemmonitor == 0.0 04:47:23 INFO - moztest == 0.7 04:47:23 INFO - mozversion == 1.4 04:47:23 INFO - psutil == 3.1.1 04:47:23 INFO - requests == 1.2.3 04:47:23 INFO - wsgiref == 0.1.2 04:47:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:23 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:23 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 04:47:23 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 04:47:23 INFO - Using env: {'DISPLAY': ':0', 04:47:23 INFO - 'HOME': '/home/cltbld', 04:47:23 INFO - 'LANG': 'en_US.UTF-8', 04:47:23 INFO - 'LANGUAGE': 'en_US:en', 04:47:23 INFO - 'LOGNAME': 'cltbld', 04:47:23 INFO - 'MAIL': '/var/mail/cltbld', 04:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:23 INFO - 'MOZ_NO_REMOTE': '1', 04:47:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:23 INFO - 'NO_EM_RESTART': '1', 04:47:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:23 INFO - 'PWD': '/builds/slave/test', 04:47:23 INFO - 'SHELL': '/bin/bash', 04:47:23 INFO - 'SHLVL': '1', 04:47:23 INFO - 'TERM': 'linux', 04:47:23 INFO - 'TMOUT': '86400', 04:47:23 INFO - 'USER': 'cltbld', 04:47:23 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:23 INFO - '_': '/tools/buildbot/bin/python'} 04:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 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)) 04:47:23 INFO - Cleaning up... 04:47:23 INFO - Return code: 0 04:47:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:23 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:23 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 04:47:23 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 04:47:23 INFO - Using env: {'DISPLAY': ':0', 04:47:23 INFO - 'HOME': '/home/cltbld', 04:47:23 INFO - 'LANG': 'en_US.UTF-8', 04:47:23 INFO - 'LANGUAGE': 'en_US:en', 04:47:23 INFO - 'LOGNAME': 'cltbld', 04:47:23 INFO - 'MAIL': '/var/mail/cltbld', 04:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:23 INFO - 'MOZ_NO_REMOTE': '1', 04:47:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:23 INFO - 'NO_EM_RESTART': '1', 04:47:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:23 INFO - 'PWD': '/builds/slave/test', 04:47:23 INFO - 'SHELL': '/bin/bash', 04:47:23 INFO - 'SHLVL': '1', 04:47:23 INFO - 'TERM': 'linux', 04:47:23 INFO - 'TMOUT': '86400', 04:47:23 INFO - 'USER': 'cltbld', 04:47:23 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:23 INFO - '_': '/tools/buildbot/bin/python'} 04:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:23 INFO - Downloading/unpacking jsonschema 04:47:23 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 04:47:23 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 04:47:23 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 04:47:23 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 04:47:23 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 04:47:23 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 04:47:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:25 INFO - Downloading/unpacking functools32 (from jsonschema) 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:25 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:26 INFO - warning: no files found matching '*.txt' 04:47:26 INFO - no previously-included directories found matching 'build' 04:47:26 INFO - no previously-included directories found matching 'dist' 04:47:26 INFO - no previously-included directories found matching '.git*' 04:47:26 INFO - Installing collected packages: jsonschema, functools32 04:47:26 INFO - Running setup.py install for functools32 04:47:26 INFO - warning: no files found matching '*.txt' 04:47:26 INFO - no previously-included directories found matching 'build' 04:47:26 INFO - no previously-included directories found matching 'dist' 04:47:26 INFO - no previously-included directories found matching '.git*' 04:47:26 INFO - Successfully installed jsonschema functools32 04:47:26 INFO - Cleaning up... 04:47:26 INFO - Return code: 0 04:47:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:26 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 0x7fb2173a3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dab5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, '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': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', '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 04:47:26 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 04:47:26 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 04:47:26 INFO - Using env: {'DISPLAY': ':0', 04:47:26 INFO - 'HOME': '/home/cltbld', 04:47:26 INFO - 'LANG': 'en_US.UTF-8', 04:47:26 INFO - 'LANGUAGE': 'en_US:en', 04:47:26 INFO - 'LOGNAME': 'cltbld', 04:47:26 INFO - 'MAIL': '/var/mail/cltbld', 04:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:26 INFO - 'MOZ_NO_REMOTE': '1', 04:47:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:26 INFO - 'NO_EM_RESTART': '1', 04:47:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:26 INFO - 'PWD': '/builds/slave/test', 04:47:26 INFO - 'SHELL': '/bin/bash', 04:47:26 INFO - 'SHLVL': '1', 04:47:26 INFO - 'TERM': 'linux', 04:47:26 INFO - 'TMOUT': '86400', 04:47:26 INFO - 'USER': 'cltbld', 04:47:26 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:26 INFO - '_': '/tools/buildbot/bin/python'} 04:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:26 INFO - Downloading/unpacking flake8 04:47:26 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 04:47:26 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 04:47:26 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 04:47:26 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 04:47:26 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 04:47:26 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 04:47:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:47:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:47:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 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 04:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:29 INFO - no previously-included directories found matching 'docs/_build' 04:47:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:47:29 INFO - Running setup.py install for pep8 04:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:47:29 INFO - no previously-included directories found matching 'docs/_build' 04:47:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:47:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:47:29 INFO - Cleaning up... 04:47:29 INFO - Return code: 0 04:47:29 INFO - Running post-action listener: _resource_record_post_action 04:47:29 INFO - Running post-action listener: _start_resource_monitoring 04:47:29 INFO - Starting resource monitoring. 04:47:29 INFO - ##### 04:47:29 INFO - ##### Running install step. 04:47:29 INFO - ##### 04:47:29 INFO - Running pre-action listener: _resource_record_pre_action 04:47:29 INFO - Running main action method: install 04:47:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:47:29 INFO - Reading from file tmpfile_stdout 04:47:29 INFO - Detecting whether we're running mozinstall >=1.0... 04:47:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:47:29 INFO - Reading from file tmpfile_stdout 04:47:29 INFO - Output received: 04:47:29 INFO - Usage: mozinstall [options] installer 04:47:29 INFO - Options: 04:47:29 INFO - -h, --help show this help message and exit 04:47:29 INFO - -d DEST, --destination=DEST 04:47:29 INFO - Directory to install application into. [default: 04:47:29 INFO - "/builds/slave/test"] 04:47:29 INFO - --app=APP Application being installed. [default: firefox] 04:47:29 INFO - mkdir: /builds/slave/test/build/application 04:47:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:47:45 INFO - Reading from file tmpfile_stdout 04:47:45 INFO - Output received: 04:47:45 INFO - /builds/slave/test/build/application/firefox/firefox 04:47:45 INFO - Running post-action listener: _resource_record_post_action 04:47:45 INFO - ##### 04:47:45 INFO - ##### Running run-tests step. 04:47:45 INFO - ##### 04:47:45 INFO - Running pre-action listener: _resource_record_pre_action 04:47:45 INFO - Running main action method: run_tests 04:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:45 INFO - Python 2.7.3 04:47:45 INFO - Return code: 0 04:47:45 INFO - grabbing minidump binary from tooltool 04:47:45 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')]} 04:47:45 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e3a410>, '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 0x1e3b310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e3b7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:45 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 04:47:45 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 04:47:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:45 INFO - Return code: 0 04:47:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:47:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:47:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:47:46 INFO - Return code: 0 04:47:46 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/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 04:47:46 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/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 04:47:46 INFO - Using env: {'DISPLAY': ':0', 04:47:46 INFO - 'HOME': '/home/cltbld', 04:47:46 INFO - 'LANG': 'en_US.UTF-8', 04:47:46 INFO - 'LANGUAGE': 'en_US:en', 04:47:46 INFO - 'LOGNAME': 'cltbld', 04:47:46 INFO - 'MAIL': '/var/mail/cltbld', 04:47:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:46 INFO - 'MOZ_NO_REMOTE': '1', 04:47:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:46 INFO - 'NO_EM_RESTART': '1', 04:47:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:46 INFO - 'PWD': '/builds/slave/test', 04:47:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:46 INFO - 'SHELL': '/bin/bash', 04:47:46 INFO - 'SHLVL': '1', 04:47:46 INFO - 'TERM': 'linux', 04:47:46 INFO - 'TMOUT': '86400', 04:47:46 INFO - 'USER': 'cltbld', 04:47:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891', 04:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:46 INFO - '_': '/tools/buildbot/bin/python'} 04:47:46 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/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 04:47:46 INFO - mozversion application_buildid: 20160422035709 04:47:46 INFO - mozversion application_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:47:46 INFO - mozversion application_display_name: Nightly 04:47:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:46 INFO - mozversion application_name: Firefox 04:47:46 INFO - mozversion application_remotingname: firefox 04:47:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:47:46 INFO - mozversion application_vendor: Mozilla 04:47:46 INFO - mozversion application_version: 48.0a1 04:47:46 INFO - mozversion platform_buildid: 20160422035709 04:47:46 INFO - mozversion platform_changeset: 80598be5d80e695727ff74e712faa623b02f3c70 04:47:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:47:46 INFO - mozversion platform_version: 48.0a1 04:47:46 INFO - using testdate: 1461584866 04:47:46 INFO - actual date: 1461584866 04:47:46 INFO - starting webserver on 'localhost:60946' 04:47:46 INFO - SUITE-START | Running 2 tests 04:47:46 INFO - TEST-START | dromaeo_css 04:47:46 INFO - operating with platform_type : linux_ 04:47:46 INFO - Initialising browser for dromaeo_css test... 04:47:46 INFO - TEST-INFO | started process 11964 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBrIYWS/profile http://localhost:60946/getInfo.html) 04:47:50 INFO - PROCESS | 11964 | __metrics Screen width/height:1600/1200 04:47:50 INFO - PROCESS | 11964 | colorDepth:24 04:47:50 INFO - PROCESS | 11964 | Browser inner width/height: 1024/697 04:47:50 INFO - PROCESS | 11964 | __metrics 04:47:52 ERROR - PROCESS | 11964 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:47:52 INFO - TEST-INFO | 11964: exit 0 04:47:52 INFO - Browser initialized. 04:47:52 INFO - Running cycle 1/1 for dromaeo_css test... 04:47:52 INFO - TEST-INFO | started process 12047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBrIYWS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:04 INFO - PROCESS | 12047 | RSS: Main: 155795456 04:48:04 INFO - PROCESS | 12047 | 04:48:55 INFO - PROCESS | 12047 | 1461584935762 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] 04:51:07 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:60946/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60946/tests/dromaeo/cssquery-ext.html) 04:51:08 INFO - PROCESS | 12047 | RSS: Main: 230592512 04:51:08 INFO - PROCESS | 12047 | 04:54:11 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:60946/tests/dromaeo/cssquery-ext.html (next: http://localhost:60946/tests/dromaeo/cssquery-jquery.html) 04:54:12 INFO - PROCESS | 12047 | RSS: Main: 238977024 04:54:12 INFO - PROCESS | 12047 | 04:57:14 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:60946/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60946/tests/dromaeo/cssquery-mootools.html) 04:57:14 INFO - PROCESS | 12047 | RSS: Main: 241909760 04:57:14 INFO - PROCESS | 12047 | 05:00:16 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:60946/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60946/tests/dromaeo/cssquery-prototype.html) 05:00:17 INFO - PROCESS | 12047 | RSS: Main: 242585600 05:00:17 INFO - PROCESS | 12047 | 05:03:20 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:60946/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60946/tests/dromaeo/cssquery-yui.html) 05:03:21 INFO - PROCESS | 12047 | RSS: Main: 211558400 05:03:21 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | Cycle 1(1): loaded http://localhost:60946/tests/dromaeo/cssquery-yui.html (next: http://localhost:60946/tests/dromaeo/cssquery-dojo.html) 05:06:24 INFO - PROCESS | 12047 | RSS: Main: 293527552 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | __start_tp_report 05:06:24 INFO - PROCESS | 12047 | _x_x_mozilla_page_load 05:06:24 INFO - PROCESS | 12047 | _x_x_mozilla_page_load_details 05:06:24 INFO - PROCESS | 12047 | |i|pagename|runs| 05:06:24 INFO - PROCESS | 12047 | |0;dojo.html;2563.8333508321957;2632.157709532949;2641.4452964877373;2652.854093024883;2671.0784779251157;4579.175748365301;4605.723656580603;4617.861464156076;4639.072185562884;4648.790804413801;4661.347411362403;4697.412823397075;4757.595604373624;4783.952160478394;4788.760561971905;4292.399064131024;4324.6107850293465;4341.1317736452675;4402.4937132229725;4420.690551661385;4308.3106702927535;4342.109867477159;4385.057212699269;4413.205622987861;4418.823247070113;4196.958030419718;4255.021345090616;4355.346697995295;4387.495438024636;4397.692161548693;4223.176480586287;4227.4292970448905;4240.575942405766;4249.213895429355;4253.149370125956;4188.643965262956;4226;4226.619604235603;4233.534311225762;4274.29474136767;4517.728936263835;4585.747783871886;4608.7004344717825;4646.024334889677;4657.4876763555985;5019.29729837823;5026.824061157842;5043.067032598981;5082.466341034212;5090.490950904917;4906.607471402279;5036.093503169428;5042.823501177478;5053.519915608012;5058.418281897592;4625.560571745713;4659.743714095757;4666;4701.106789710013;4709.7174169549935;5146.588272938156;5159.896802063938;5194.27280180775;5198.584113271005;5243.973780131151;4769.379980602499;4935;4966.379202599675;4979.203327467588;4984.451710311862;5040.470750571138;5066.6453348266;5072.289879416885;5085.74571271435;5118.948810511922;5012.473690262543;5036.294918711384;5041.59667226621;5045.520675535819;5085.00842335741;4816.397950256218;4908.803647854046;4942.728149951715;5005.174146265898;5030.220315851049;4222.767747773902;4234.851780187741;4251.44731184944;4268.658507319407;4288.356746488052;3682.650148235913;3690.022144131807;3694.0949467380665;3723.422869455239;3752.7748335099436;4192.140611174702;4304.289792184317;4317.805698743501;4319.8488052918;4389.407429996973;5026.447090820006;5029.5221953914315;5040.621953353513;5041.420236672756;5069.518395752398;4317.481902171759;4323.913521729548;4326.805293761789;4349.739015659071;4373.868783936487;4386.912261754747;4501.279795232747;4504.752238626841;4575.839845605455;4592.265237561974;4665.393498845128;4709.152352576432;4714.481407045222;4718.504557021463;4735.715857048588;4988.650794444354;5010.5240002199735;5012.273220382951;5013.097642424253;5071.290019397214;4642.257238841769;4737.549932756383;4794.137055330074;4837.612990960644;4842.95157048425;4891.26631005352;4902.828401005948;4909.214525675875;4912.656114072123;4966.205407134841;4821.083994041121;4974.278729584118;4998.425181104074;5000.399952005782;5048.192289233852;4876.78054487556;4927.457979622311;4932.457429682804;4970.080535101018;4979.850604481871;4805.423349198119;4840.7337596432535;4845.1278769820365;4853.247746599282;4895.657303988686;4684.273937539687;4744.217204161275;4783.1390349737385;4807.879803004813;4828.975855120702;4833.347498087714;4856.39295088114;4899.70601763881;4918.991606720685;4930.630202734737;4781.163296423181;4864.173090574682;4865.051314993684;4869.050535145754;4907.043126590281 05:06:24 INFO - PROCESS | 12047 | |1;ext.html;588.9793857214999;600.9008513595257;608.1334098909057;625.115461621805;633.5755044120439;27199.04803331884;33943;34881.30237395251;34925;35006;37244;37921.24157516855;37986.62013379866;37990.43014354787;38002.8099859501;7303.342699157075;7504.512206706574;7510;7529.209433009523;7709.3061624453785;7465.365443937272;9617.278704097185;10037.046480584333;10037.247206459508;10051.346662466953;9714.22286217101;9762.267829912786;9800.31397802154;9822.508874556279;9823.066808653202;7517.436192285601;7817.530948143073;9538.570764315622;9701.70894873155;9728.221742260637;7417.740379086706;9496.857547136799;9827.656032038843;9839.90160098397;9857.408555486694;33952.32095358079;34394.48408273878;34526.82736586326;34561.48157777635;34568.30863382718;2859.742623163904;2866.5700144978214;3060.4491191585507;3197.344544368399;3362.613299470543;26838.73161268382;27132.457350852874;27483.17550473489;29289.850807316358;29617;3267.941197045846;3283.5074738789135;3301.5542901708204;3404.455287154068;3418.7265018798435;178721.9999999987;179476;179879;181291;181792;52364.73817630888;53396.73301633467;53499.46500535023;53566.196507052424;53581.732091340076;55598.72200638971;55864.720676396355;55870;55911;55928.48321825528;12965.15726477776;13198.538051168163;13207.933960330136;13211.867881321255;13215.669608259677;8968.820623587491;9244.95377523108;9250.537473126316;9519.714838496715;10031.69904902854;15570.688586228438;15934;16003.599910002344;16039.358425662842;16040.957337773007;5604.103343465026;5673.3475650299915;5707.571932105108;5732.971335143381;5733.111367738007;877.7147427086222;887.7114937645291;934.6027938126269;1028.1825948371063;1084.6908631040117;25678.743212567635;26151.869240654054;26172.214833555023;27767.444651106864;27814.86092569524;993.64228877604;1009.5255230041869;1014.3710899242517;1071.0681706915034;1078.6440474643348;13884.375203115682;14552.490662826962;14782.556523304318;15003.849961500464;15037.323320450607;332059;336216;338906;341029;341083;47647.52352476431;48128.27807582889;48129;48160.18398160226;48190;1203.1938601137258;1207.3600991474357;1209.989658635032;1232.7411243638758;1335.2122247873606;24071.390284091733;26928.596071058557;27584.44831103407;27958.44083118367;28052.43895122127;38861;39549.80225098856;39605.20789584135;39647.80176099101;39650.40524392194;231854;236477;236630;237813;237937;30602.84698576493;31915.840420797747;31955.520667190474;31971.200719982186;31979.200519986254;33272;41293.38059929164;41349.37975930423;41758.791206043774;42081.78959105185;33046;33735.662643374235;33814.49278260877;33824.66175338215;34207.82896085503;33487;33690.66309336973;33875.4918676225;33938.66061339454;34301.313973720884;30109.397812044073;30687;30808.691913080584;30847;30868.382632347675;29209.269768255963;29518.262043449085;32177.195570110933;33366.666333336354;35091.82454087713;23083.88458057699;23761.287161384495;23823.52352952966;24060.39849003789;24066 05:06:24 INFO - PROCESS | 12047 | |2;jquery.html;377.08180580286995;380.29075773682075;387.5794762682489;388.1906225519792;390.2974645637851;10327.793444131114;10931.125509959204;10956.452177391118;10971.341719496826;15991.280392382341;16344.83655163448;16679.83320166798;17614.735778963295;17624.647507049853;17642.558936026606;5499.340079190482;5722.599418040739;7110.395616372589;7133.9286607134045;7143.85712285754;6953.061336719554;7009.334113259232;7043.295670432967;7067.187273463566;7070.363667269945;6926.268432891777;7044.295570442966;7105.182903966059;7129.180144283422;7132.108486439195;6941.757038503653;7074.469414793911;7090.255523170044;7124.679389427488;7128.893066604005;6705.4970877184405;6734.292899245557;6819;6905.9309406906295;6999.23008469068;21858.344249672537;22617.54764904709;22631;22689.319320420414;22794.886025569933;2062.6802845558964;2072.357569153552;2167.0248388225205;2431.732509423962;2449.534588428193;14018;14291.071080379743;14411.495597654137;15114.697706045927;15119.54641360761;1866.9159887804944;1956.5695546979641;2019.4446527204989;2025.4126303372004;2079.396974877284;77416.61291693561;78367.99999999943;81458.77811832886;82415;82721;16160.191990400668;16231.513054608144;16422.53661950717;16561.6687666246;16706;29165.85417072901;29452.410951780843;29500.262493437836;29569.704302957125;29614.482768964448;8240.381971352173;8560;8611.138886111465;8697.21725044749;8731.60707768152;6491.253505846888;6492.935070649233;6839.418649414756;6943.778810950751;7137.357637812518;8080.595970201466;8313.376496762767;8343.874841877378;8353.457025293457;8379.916200838035;3207.631122420905;3270.1438364029395;3348.54643324378;3408.812515311632;3440.8623655053516;410.1857812242696;412.5626835554322;415.5387519852961;793.3137835772131;925.7500474871747;10123.291369604058;10561.577536898592;10691.572337106583;10754.677359679223;10888.891111088946;428.52219774950703;430.322242468114;431.7193824014416;444.1250736049976;451.5687518419901;5722.54219662426;5738.741756620962;5771.85570360736;5775.133729940542;5831.650100993954;145110;145553.27223363816;145590;145652.27173864061;165537;54308.45691543113;71939;81519;82973;83042;442.8715672454952;448.26484565313564;449.8627918484828;451.06854345775866;452.1386758225585;9627.663031793854;9919.107280344804;10036;10129.949350253497;10206.795864082826;31932;34103.147421314665;34246.48630270498;35034.29931401409;35750.46374304336;48578.27132592943;48626.27060594023;48754.512454874995;48908;49081.50918490769;7424.331810137113;7762.223777622193;7770.417218708506;7833.451658383859;7937.563434011185;10697.964167351984;10740;10876.836847447137;11228.943855280671;11424.143189260672;9548.37935534188;9690.079442452956;9754.512274386394;9881.604735810488;9918.801623967625;9511.334206605748;9614.471204083842;9992.900070999198;10072.395656260647;10143.442110683687;9978.301518893608;10138.949305253427;10301.433421161908;10409.895901040893;10588.047075763217;7547.622618868925;7667.504836557041;7793.10379306387;7913.089994650689;7948.443608947318;9592;9737.561809718438;9783.657571984946;10092.798144036931;10213.712734964292 05:06:24 INFO - PROCESS | 12047 | |3;mootools.html;1237.8700236475165;1356.6947436826715;1436.5044059799372;1560.5864445921832;1739.6085880676849;6823.965880170598;7080.787576372704;7484.962575187116;7661.6169191540475;7686.846263074735;7453.962730186341;7477.663505142268;7515.887261691079;7517.962410187942;7527.360174385184;1916.252661462031;2117.1637203304685;2218.1792736687407;2248.303026061918;2252.087904398713;2281.532285881399;2300.275413244831;2301.8158547316257;2304.6082166031815;2306.8961896714686;2223.544173444448;2224.4327696437385;2233.530958498717;2265.478939843837;2274.442761523429;1877.11775465531;2130.957380852382;2134.4877229464973;2151.397608669575;2240.6414973604146;2231.0741042467357;2259.6497542880716;2262.468319944812;2263.954720905589;2266.490039741045;7749.7675069748;7806.414518911104;7812.06255249368;7817.296443320129;7821.413393995416;4518.502964673883;4671.205894997858;4682.8126874924965;4688.601468875116;4691.7888695008805;2797.6642802863785;2843.431313737261;2939.514980028293;2964;2975.64292284925;891.131147131548;896.6771962093641;915.9496227707474;916.1937495004354;924.3621900888365;10792.51433685486;10798.406087665175;10812.729681758019;10820.513076911559;10841.566337346496;4285.314349704033;4300.698951073395;4496.3705081288645;4501.977490112594;4523.140603285366;9177.219936305355;9188.586513606904;9188.816223675489;9206.631734730536;9213.539323033821;4746.1456937750845;4746.4541577719;4754;4758.738269395148;4771.856844294677;3808.8476460941806;3924.4505769192338;3981.5421226558738;4253.149370125925;4327.653787696977;3693.538307711536;3706.1475860552223;3784.9810750946067;3813.256414999104;3817.8854634361014;1243.0801207106676;1259.0431272232986;1266.5187228852976;1271.8600953895063;1273.4842388832537;705.5378726933867;750.9586972716553;763.9885401718979;792.8374683189933;800.371708209053;5053.974730126326;5227.895442091137;5323.653962492425;5366.651167674088;5403.162509810985;834.4284165346756;873.5021038008401;877.5436772878068;879.366855863777;879.5997820991433;3352.5641666583674;3450.8274586270586;3461.7576769625884;3477.4088404971217;3498.160441494023;9506.47714375716;9902.207823374112;9906.603735850485;9944.950275248577;9966.950165249127;9178.357514973888;9620.37467564606;9833.213342932548;9840.212782977345;9861.852072218922;6997.895031574531;7042.119735033121;7129.394001509826;7129.857402851914;7141.82145446368;4973.229149481836;4988.02733466985;5093.821716239914;5124.410692770379;5161.329027226437;8895;8927;8930.464172149692;8944.552772361487;9042;8256.958715206385;8596.269317107866;8606.61270242853;8628.39601227908;9402.811943761224;6127.417895299939;6583.637899915359;6632.83417914556;6749.3588109131515;6811.693473793592;7414.592197429193;7429.925700742923;7479.738209162653;7505.174430812715;7510.399168066432;6553.442957348488;6727.024581435567;6746.6626668665385;6861.1080559526945;6867.278935711678;6620.337966203534;6632.933670663231;6649.301823308483;6698;6824.863502729819;6265.8120256392385;6288;6305.180326557518;6323.810285691437;6324.3991820776955;4284.764337961442;4402.757848318373;4479.910401792011;4525.479569849509;4551.704139230939;4546.113507866066;4547.363369128391;4577.725336479822;4580.221362368338;4586.2661974083985 05:06:24 INFO - PROCESS | 12047 | |4;prototype.html;313.778785955901;314.9795263307885;319.5111479436462;320.8556149732621;323.8801643391944;5124.12889808733;5145.511176438242;5154.974225128878;5162.767675454605;5171.715555644438;5090.211017292317;5105.489451054893;5110.488951104888;5114.386273647158;5130.179171332587;3778.1688028633725;3899.25914076326;3916.373380259159;4023.7988100595;4037.6567991720594;4006.8797936061965;4029.415734718464;4032.254033003889;4039.5556488786356;4040.5555388907196;3722.9441558376648;3975.662068724162;3992.960070399288;3994.2011597680435;3997.700172487075;3727.235916637083;3858.826352814116;3901.141748815256;4001.519817621875;4008.3586626139677;3525.576930768324;3526.4710293456187;3542.468629705539;3543.716502679805;3544.3240353533174;4909.729964851932;4922.089413458521;4923.80304787808;4936.827211047632;4947.356843610308;4506.684532082755;4633.49031606523;4634.142683603544;4639.512851150648;4647.86056418308;5324.334458192726;5344.331958505187;5357.705326207057;5362.892742145174;5364.678119312854;3860.1507668312925;3881.3207688654375;3890.396988466792;3895.7857317847506;3913.3347330953807;5444.6188766786345;5641.323041235037;5663.461971112738;5671.461211184931;5672.234248376499;3987.940180897289;3995.340768773178;4000.739951903117;4003.579624139482;4005.118873847749;4059.594040595917;4062.8106936055055;4082.5713300103657;4096.6313031826685;4115.36211887158;4370.737755734666;4635;4641.118187544355;4642.094791515689;4652.906941861143;5412.188171774265;5439.265699130646;5451.746098397391;5454.263674403984;5463.672179669153;1412.216219997911;1430.8783753381078;1436.1598464897943;1436.2818590704649;1437.173625165534;895.583553647544;954.8207963165485;973.9610415583297;983.975400614976;999.3554157568328;4671.11248862715;4790.568848803624;4795.20879054959;4806.831760888352;4810.542998415145;5538.944610553924;5634.295713035871;5636.802711905064;5645.322561292671;5653.462921022497;4573.63410927125;4770.141374552544;4775.21209000518;4806.903861922742;4808.975955120202;2989.355854280137;3023.4557779599445;3032.9233122241285;3036.6659667436566;3046.2232130806947;5310.150375939831;5316.4582271140935;5333.173358129496;5336.199570064521;5349.4650534946195;5520.337559492807;5826.096954971986;5828.242328497353;5829.09649004405;5832.300123985064;5307.3896501902;5316.554384982874;5326.135852451611;5333.466653334635;5336.919946200733;5677.489025987681;5686.374498805211;5686.459786320293;5690.3539853968105;5695.7436915338085;5278.868028299324;5525.4474552544425;5560.638558493685;5569.827767170726;5572.86067848307;5258.868528286824;5271.525562699222;5279.683219006872;5288.286081378965;5296.708681022581;3850.133719912997;3873.6901047916663;3907.29668659644;3911.882643520699;3916.6866650667307;5537.6954267515675;5635.887282254414;5636.239107720569;5638.182463542846;5652.349979752216;5425.996190704732;5439.918401224064;5450.209719590716;5458.4541545845095;5462.470508257694;5215.765290561858;5407.567394608343;5425.972870135624;5427.213054107211;5434.239206511171;5300.867478313073;5340.893182136258;5344.652597581145;5353.705546194997;5361.758720857649;5184.533391994739;5227.529522343008;5434.347878254634;5498.367687715964;5503.587230957614;5301.284326615858;5312.468753124656;5330.520253177077;5340.465953404629;5346.465353464622 05:06:24 INFO - PROCESS | 12047 | |5;yui.html;606.0696383950084;706.6219572528698;734.5923012727937;756.7237958145275;770.626246270559;10913.399763013042;11235.101191904649;11302.208845380816;11318.434078296083;11941.283522988626;13875.16748995061;13964.860351396483;13986.370613322399;13991.790123148161;13994.720105597882;630.6153246519619;751.8533885892253;774.5004472115496;785.9253370929781;786.3591173193829;780.7813812132612;791.2562191539963;792.0455850699893;792.9563873986929;793.5000949401868;808.8584497712906;809.1180613131685;814.2915663372873;814.8044469327378;814.8248126652768;649.4933951517824;791.9287264146254;813.7640084375524;817.6238930092135;817.9100298967109;594.0999385930319;594.9434803693686;595.5860676829606;596.1802521532892;596.83885350955;10614.416207108607;10728.141748660088;10738.892611073945;10748.086412654933;10752.086072683831;10895.073918716918;11260.774784504347;11351.09191264705;11352.943235283854;11415.258008229439;9989.800203995952;10080.747981300452;10152.593896244141;10258.640947566799;10350.378977261389;3125.984370078158;3202.647708752035;3203.727683146912;3228.4834426491648;3248.951265731016;41552.79223603863;47060.5293947063;47251.29123063122;47345.289820652375;47406;9178.173964343108;9465.716028519155;9822.705318840446;9836.262280329003;10232.232582556338;16983.830161698224;17140.91429542869;17146.22841972114;17147;17149.142542872807;6757.425618822357;6896.93103068973;6949.861002780017;7100.360967512948;7151.32062454066;3768.5477742671055;3800.638939300817;3870.0518372998795;3884.533855937249;3899.6100389960775;2885.783566232541;2936.4861149299213;2939.5590661400533;2941.07356182806;2944.6024786653957;520.903632827928;522.6289334572496;529.3224672419309;533.386605403781;533.4931814775979;472.8557789874121;608.9604175728564;615.8891399548124;616.0851136062943;628.8050704281687;7065.1521817382245;7202.1357437106835;7314.45141614381;7331.596019791448;7629.237076292437;484.672845829064;527.8495628745789;558.2324304081887;566.8441178675897;567.4524084258628;3480.0951752544015;3723.832427540767;3725.869594564177;3732.290864735746;3829.751066180689;53328.46671533235;54119.72940135274;54231.45768542343;54317.1852422214;54331.45668543343;44544;45317.77341113339;45386.31920521195;45734;45832;39436.60563394329;41096.79451602723;42094.78952605279;42123.368149477785;42463.57536424658;6993.4055605274125;7247.420206383582;7250.528715633467;7268.709251629875;7391.630418478947;27748;28068.157955261377;28446.57330140091;28476.715232848233;28678.71321286844;34862;35046;37384.81307593445;37669.434958476195;38568.80715596404;8812.60343284541;8961.283097352327;9576.281778866474;9667.081627245401;9761.9023809761;10255.538500767334;10280;10296.742581435525;10306.587736490457;10399.908009658877;9949.801003980025;10039.096481316716;10110.472381904523;10264.64073757415;10321.225908056776;9510.904890951002;9562.139407453362;9593.184579310837;9676.564554595201;10079.34484258521;10315.381077135396;10656.14750819917;10883.727906802393;10896.945515272373;11008.174386921173;8661.13388661154;8873.866891996755;8916.55417229123;8929.10708929102;9103;8422.157784221774;8614.526201059001;8707.91292087071;8723.345749068973;9139.177474027369 05:06:24 INFO - PROCESS | 12047 | __end_tp_report 05:06:24 INFO - PROCESS | 12047 | __start_cc_report 05:06:24 INFO - PROCESS | 12047 | _x_x_mozilla_cycle_collect,1101 05:06:24 INFO - PROCESS | 12047 | __end_cc_report 05:06:24 INFO - PROCESS | 12047 | __startTimestamp1461585984259__endTimestamp 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | ------- Summary: start ------- 05:06:24 INFO - PROCESS | 12047 | Number of tests: 6 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | [#0] dojo.html Cycles:165 Average:4632.49 Median:4769.38 stddev:489.00 (10.3%) stddev-sans-first:462.79 05:06:24 INFO - PROCESS | 12047 | Values: 2563.8 2632.2 2641.4 2652.9 2671.1 4579.2 4605.7 4617.9 4639.1 4648.8 4661.3 4697.4 4757.6 4784.0 4788.8 4292.4 4324.6 4341.1 4402.5 4420.7 4308.3 4342.1 4385.1 4413.2 4418.8 4197.0 4255.0 4355.3 4387.5 4397.7 4223.2 4227.4 4240.6 4249.2 4253.1 4188.6 4226.0 4226.6 4233.5 4274.3 4517.7 4585.7 4608.7 4646.0 4657.5 5019.3 5026.8 5043.1 5082.5 5090.5 4906.6 5036.1 5042.8 5053.5 5058.4 4625.6 4659.7 4666.0 4701.1 4709.7 5146.6 5159.9 5194.3 5198.6 5244.0 4769.4 4935.0 4966.4 4979.2 4984.5 5040.5 5066.6 5072.3 5085.7 5118.9 5012.5 5036.3 5041.6 5045.5 5085.0 4816.4 4908.8 4942.7 5005.2 5030.2 4222.8 4234.9 4251.4 4268.7 4288.4 3682.7 3690.0 3694.1 3723.4 3752.8 4192.1 4304.3 4317.8 4319.8 4389.4 5026.4 5029.5 5040.6 5041.4 5069.5 4317.5 4323.9 4326.8 4349.7 4373.9 4386.9 4501.3 4504.8 4575.8 4592.3 4665.4 4709.2 4714.5 4718.5 4735.7 4988.7 5010.5 5012.3 5013.1 5071.3 4642.3 4737.5 4794.1 4837.6 4843.0 4891.3 4902.8 4909.2 4912.7 4966.2 4821.1 4974.3 4998.4 5000.4 5048.2 4876.8 4927.5 4932.5 4970.1 4979.9 4805.4 4840.7 4845.1 4853.2 4895.7 4684.3 4744.2 4783.1 4807.9 4829.0 4833.3 4856.4 4899.7 4919.0 4930.6 4781.2 4864.2 4865.1 4869.1 4907.0 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | [#1] ext.html Cycles:180 Average:41040.74 Median:33819.58 stddev:68130.08 (201.5%) stddev-sans-first:68253.51 05:06:24 INFO - PROCESS | 12047 | Values: 589.0 600.9 608.1 625.1 633.6 27199.0 33943.0 34881.3 34925.0 35006.0 37244.0 37921.2 37986.6 37990.4 38002.8 7303.3 7504.5 7510.0 7529.2 7709.3 7465.4 9617.3 10037.0 10037.2 10051.3 9714.2 9762.3 9800.3 9822.5 9823.1 7517.4 7817.5 9538.6 9701.7 9728.2 7417.7 9496.9 9827.7 9839.9 9857.4 33952.3 34394.5 34526.8 34561.5 34568.3 2859.7 2866.6 3060.4 3197.3 3362.6 26838.7 27132.5 27483.2 29289.9 29617.0 3267.9 3283.5 3301.6 3404.5 3418.7 178722.0 179476.0 179879.0 181291.0 181792.0 52364.7 53396.7 53499.5 53566.2 53581.7 55598.7 55864.7 55870.0 55911.0 55928.5 12965.2 13198.5 13207.9 13211.9 13215.7 8968.8 9245.0 9250.5 9519.7 10031.7 15570.7 15934.0 16003.6 16039.4 16041.0 5604.1 5673.3 5707.6 5733.0 5733.1 877.7 887.7 934.6 1028.2 1084.7 25678.7 26151.9 26172.2 27767.4 27814.9 993.6 1009.5 1014.4 1071.1 1078.6 13884.4 14552.5 14782.6 15003.8 15037.3 332059.0 336216.0 338906.0 341029.0 341083.0 47647.5 48128.3 48129.0 48160.2 48190.0 1203.2 1207.4 1210.0 1232.7 1335.2 24071.4 26928.6 27584.4 27958.4 28052.4 38861.0 39549.8 39605.2 39647.8 39650.4 231854.0 236477.0 236630.0 237813.0 237937.0 30602.8 31915.8 31955.5 31971.2 31979.2 33272.0 41293.4 41349.4 41758.8 42081.8 33046.0 33735.7 33814.5 33824.7 34207.8 33487.0 33690.7 33875.5 33938.7 34301.3 30109.4 30687.0 30808.7 30847.0 30868.4 29209.3 29518.3 32177.2 33366.7 35091.8 23083.9 23761.3 23823.5 24060.4 24066.0 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | [#2] jquery.html Cycles:180 Average:18332.73 Median:450.47 stddev:28795.79 (6392.4%) stddev-sans-first:28845.01 05:06:24 INFO - PROCESS | 12047 | Values: 377.1 380.3 387.6 388.2 390.3 10327.8 10931.1 10956.5 10971.3 15991.3 16344.8 16679.8 17614.7 17624.6 17642.6 5499.3 5722.6 7110.4 7133.9 7143.9 6953.1 7009.3 7043.3 7067.2 7070.4 6926.3 7044.3 7105.2 7129.2 7132.1 6941.8 7074.5 7090.3 7124.7 7128.9 6705.5 6734.3 6819.0 6905.9 6999.2 21858.3 22617.5 22631.0 22689.3 22794.9 2062.7 2072.4 2167.0 2431.7 2449.5 14018.0 14291.1 14411.5 15114.7 15119.5 1866.9 1956.6 2019.4 2025.4 2079.4 77416.6 78368.0 81458.8 82415.0 82721.0 16160.2 16231.5 16422.5 16561.7 16706.0 29165.9 29452.4 29500.3 29569.7 29614.5 8240.4 8560.0 8611.1 8697.2 8731.6 6491.3 6492.9 6839.4 6943.8 7137.4 8080.6 8313.4 8343.9 8353.5 8379.9 3207.6 3270.1 3348.5 3408.8 3440.9 410.2 412.6 415.5 793.3 925.8 10123.3 10561.6 10691.6 10754.7 10888.9 428.5 430.3 431.7 444.1 451.6 5722.5 5738.7 5771.9 5775.1 5831.7 145110.0 145553.3 145590.0 145652.3 165537.0 54308.5 71939.0 81519.0 82973.0 83042.0 442.9 448.3 449.9 451.1 452.1 9627.7 9919.1 10036.0 10129.9 10206.8 31932.0 34103.1 34246.5 35034.3 35750.5 48578.3 48626.3 48754.5 48908.0 49081.5 7424.3 7762.2 7770.4 7833.5 7937.6 10698.0 10740.0 10876.8 11228.9 11424.1 9548.4 9690.1 9754.5 9881.6 9918.8 9511.3 9614.5 9992.9 10072.4 10143.4 9978.3 10138.9 10301.4 10409.9 10588.0 7547.6 7667.5 7793.1 7913.1 7948.4 9592.0 9737.6 9783.7 10092.8 10213.7 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | [#3] mootools.html Cycles:180 Average:5121.59 Median:5194.61 stddev:2863.52 (55.1%) stddev-sans-first:2856.68 05:06:24 INFO - PROCESS | 12047 | Values: 1237.9 1356.7 1436.5 1560.6 1739.6 6824.0 7080.8 7485.0 7661.6 7686.8 7454.0 7477.7 7515.9 7518.0 7527.4 1916.3 2117.2 2218.2 2248.3 2252.1 2281.5 2300.3 2301.8 2304.6 2306.9 2223.5 2224.4 2233.5 2265.5 2274.4 1877.1 2131.0 2134.5 2151.4 2240.6 2231.1 2259.6 2262.5 2264.0 2266.5 7749.8 7806.4 7812.1 7817.3 7821.4 4518.5 4671.2 4682.8 4688.6 4691.8 2797.7 2843.4 2939.5 2964.0 2975.6 891.1 896.7 915.9 916.2 924.4 10792.5 10798.4 10812.7 10820.5 10841.6 4285.3 4300.7 4496.4 4502.0 4523.1 9177.2 9188.6 9188.8 9206.6 9213.5 4746.1 4746.5 4754.0 4758.7 4771.9 3808.8 3924.5 3981.5 4253.1 4327.7 3693.5 3706.1 3785.0 3813.3 3817.9 1243.1 1259.0 1266.5 1271.9 1273.5 705.5 751.0 764.0 792.8 800.4 5054.0 5227.9 5323.7 5366.7 5403.2 834.4 873.5 877.5 879.4 879.6 3352.6 3450.8 3461.8 3477.4 3498.2 9506.5 9902.2 9906.6 9945.0 9967.0 9178.4 9620.4 9833.2 9840.2 9861.9 6997.9 7042.1 7129.4 7129.9 7141.8 4973.2 4988.0 5093.8 5124.4 5161.3 8895.0 8927.0 8930.5 8944.6 9042.0 8257.0 8596.3 8606.6 8628.4 9402.8 6127.4 6583.6 6632.8 6749.4 6811.7 7414.6 7429.9 7479.7 7505.2 7510.4 6553.4 6727.0 6746.7 6861.1 6867.3 6620.3 6632.9 6649.3 6698.0 6824.9 6265.8 6288.0 6305.2 6323.8 6324.4 4284.8 4402.8 4479.9 4525.5 4551.7 4546.1 4547.4 4577.7 4580.2 4586.3 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | [#4] prototype.html Cycles:180 Average:4507.05 Median:5137.85 stddev:1309.35 (25.5%) stddev-sans-first:1274.63 05:06:24 INFO - PROCESS | 12047 | Values: 313.8 315.0 319.5 320.9 323.9 5124.1 5145.5 5155.0 5162.8 5171.7 5090.2 5105.5 5110.5 5114.4 5130.2 3778.2 3899.3 3916.4 4023.8 4037.7 4006.9 4029.4 4032.3 4039.6 4040.6 3722.9 3975.7 3993.0 3994.2 3997.7 3727.2 3858.8 3901.1 4001.5 4008.4 3525.6 3526.5 3542.5 3543.7 3544.3 4909.7 4922.1 4923.8 4936.8 4947.4 4506.7 4633.5 4634.1 4639.5 4647.9 5324.3 5344.3 5357.7 5362.9 5364.7 3860.2 3881.3 3890.4 3895.8 3913.3 5444.6 5641.3 5663.5 5671.5 5672.2 3987.9 3995.3 4000.7 4003.6 4005.1 4059.6 4062.8 4082.6 4096.6 4115.4 4370.7 4635.0 4641.1 4642.1 4652.9 5412.2 5439.3 5451.7 5454.3 5463.7 1412.2 1430.9 1436.2 1436.3 1437.2 895.6 954.8 974.0 984.0 999.4 4671.1 4790.6 4795.2 4806.8 4810.5 5538.9 5634.3 5636.8 5645.3 5653.5 4573.6 4770.1 4775.2 4806.9 4809.0 2989.4 3023.5 3032.9 3036.7 3046.2 5310.2 5316.5 5333.2 5336.2 5349.5 5520.3 5826.1 5828.2 5829.1 5832.3 5307.4 5316.6 5326.1 5333.5 5336.9 5677.5 5686.4 5686.5 5690.4 5695.7 5278.9 5525.4 5560.6 5569.8 5572.9 5258.9 5271.5 5279.7 5288.3 5296.7 3850.1 3873.7 3907.3 3911.9 3916.7 5537.7 5635.9 5636.2 5638.2 5652.3 5426.0 5439.9 5450.2 5458.5 5462.5 5215.8 5407.6 5426.0 5427.2 5434.2 5300.9 5340.9 5344.7 5353.7 5361.8 5184.5 5227.5 5434.3 5498.4 5503.6 5301.3 5312.5 5330.5 5340.5 5346.5 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - PROCESS | 12047 | [#5] yui.html Cycles:180 Average:12371.71 Median:23945.34 stddev:14372.66 (60.0%) stddev-sans-first:14385.82 05:06:24 INFO - PROCESS | 12047 | Values: 606.1 706.6 734.6 756.7 770.6 10913.4 11235.1 11302.2 11318.4 11941.3 13875.2 13964.9 13986.4 13991.8 13994.7 630.6 751.9 774.5 785.9 786.4 780.8 791.3 792.0 793.0 793.5 808.9 809.1 814.3 814.8 814.8 649.5 791.9 813.8 817.6 817.9 594.1 594.9 595.6 596.2 596.8 10614.4 10728.1 10738.9 10748.1 10752.1 10895.1 11260.8 11351.1 11352.9 11415.3 9989.8 10080.7 10152.6 10258.6 10350.4 3126.0 3202.6 3203.7 3228.5 3249.0 41552.8 47060.5 47251.3 47345.3 47406.0 9178.2 9465.7 9822.7 9836.3 10232.2 16983.8 17140.9 17146.2 17147.0 17149.1 6757.4 6896.9 6949.9 7100.4 7151.3 3768.5 3800.6 3870.1 3884.5 3899.6 2885.8 2936.5 2939.6 2941.1 2944.6 520.9 522.6 529.3 533.4 533.5 472.9 609.0 615.9 616.1 628.8 7065.2 7202.1 7314.5 7331.6 7629.2 484.7 527.8 558.2 566.8 567.5 3480.1 3723.8 3725.9 3732.3 3829.8 53328.5 54119.7 54231.5 54317.2 54331.5 44544.0 45317.8 45386.3 45734.0 45832.0 39436.6 41096.8 42094.8 42123.4 42463.6 6993.4 7247.4 7250.5 7268.7 7391.6 27748.0 28068.2 28446.6 28476.7 28678.7 34862.0 35046.0 37384.8 37669.4 38568.8 8812.6 8961.3 9576.3 9667.1 9761.9 10255.5 10280.0 10296.7 10306.6 10399.9 9949.8 10039.1 10110.5 10264.6 10321.2 9510.9 9562.1 9593.2 9676.6 10079.3 10315.4 10656.1 10883.7 10896.9 11008.2 8661.1 8873.9 8916.6 8929.1 9103.0 8422.2 8614.5 8707.9 8723.3 9139.2 05:06:24 INFO - PROCESS | 12047 | -------- Summary: end -------- 05:06:24 INFO - PROCESS | 12047 | 05:06:24 INFO - TEST-INFO | 12047: exit 0 05:06:24 INFO - TEST-OK | dromaeo_css | took 1118376ms 05:06:24 INFO - TEST-START | kraken 05:06:24 INFO - operating with platform_type : linux_ 05:06:24 INFO - Initialising browser for kraken test... 05:06:24 INFO - TEST-INFO | started process 12221 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiWiATF/profile http://localhost:60946/getInfo.html) 05:06:29 INFO - PROCESS | 12221 | __metrics Screen width/height:1600/1200 05:06:29 INFO - PROCESS | 12221 | colorDepth:24 05:06:29 INFO - PROCESS | 12221 | Browser inner width/height: 1024/697 05:06:29 INFO - PROCESS | 12221 | __metrics 05:06:30 ERROR - PROCESS | 12221 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:06:31 INFO - TEST-INFO | 12221: exit 0 05:06:31 INFO - Browser initialized. 05:06:31 INFO - Running cycle 1/1 for kraken test... 05:06:31 INFO - TEST-INFO | started process 12300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiWiATF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:42 INFO - PROCESS | 12300 | RSS: Main: 155373568 05:06:42 INFO - PROCESS | 12300 | 05:06:45 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60946/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:46 INFO - PROCESS | 12300 | RSS: Main: 210825216 05:06:46 INFO - PROCESS | 12300 | 05:06:49 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60946/tests/kraken/driver.html?testName=audio-dft) 05:06:49 INFO - PROCESS | 12300 | RSS: Main: 196440064 05:06:49 INFO - PROCESS | 12300 | 05:06:53 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60946/tests/kraken/driver.html?testName=audio-fft) 05:06:53 INFO - PROCESS | 12300 | RSS: Main: 266625024 05:06:53 INFO - PROCESS | 12300 | 05:06:55 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60946/tests/kraken/driver.html?testName=audio-oscillator) 05:06:56 INFO - PROCESS | 12300 | RSS: Main: 174886912 05:06:56 INFO - PROCESS | 12300 | 05:06:58 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60946/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:06:59 INFO - PROCESS | 12300 | RSS: Main: 179355648 05:06:59 INFO - PROCESS | 12300 | 05:07:02 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60946/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:03 INFO - PROCESS | 12300 | RSS: Main: 215392256 05:07:03 INFO - PROCESS | 12300 | 05:07:06 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60946/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:07 INFO - PROCESS | 12300 | RSS: Main: 215990272 05:07:07 INFO - PROCESS | 12300 | 05:07:09 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60946/tests/kraken/driver.html?testName=json-parse-financial) 05:07:10 INFO - PROCESS | 12300 | RSS: Main: 216055808 05:07:10 INFO - PROCESS | 12300 | 05:07:12 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60946/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:12 INFO - PROCESS | 12300 | RSS: Main: 201502720 05:07:12 INFO - PROCESS | 12300 | 05:07:14 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:07:15 INFO - PROCESS | 12300 | RSS: Main: 204779520 05:07:15 INFO - PROCESS | 12300 | 05:07:17 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:07:18 INFO - PROCESS | 12300 | RSS: Main: 219570176 05:07:18 INFO - PROCESS | 12300 | 05:07:20 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:07:21 INFO - PROCESS | 12300 | RSS: Main: 226172928 05:07:21 INFO - PROCESS | 12300 | 05:07:24 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:07:25 INFO - PROCESS | 12300 | RSS: Main: 219824128 05:07:25 INFO - PROCESS | 12300 | 05:07:26 INFO - PROCESS | 12300 | Cycle 1(1): loaded http://localhost:60946/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60946/tests/kraken/driver.html?testName=ai-astar) 05:07:27 INFO - PROCESS | 12300 | RSS: Main: 283086848 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | __start_tp_report 05:07:27 INFO - PROCESS | 12300 | _x_x_mozilla_page_load 05:07:27 INFO - PROCESS | 12300 | _x_x_mozilla_page_load_details 05:07:27 INFO - PROCESS | 12300 | |i|pagename|runs| 05:07:27 INFO - PROCESS | 12300 | |0;ai-astar;111;114;107;111;112;113;102;114;113;113 05:07:27 INFO - PROCESS | 12300 | |1;audio-beat-detection;159;133;151;193;134;161;153;138;205;142 05:07:27 INFO - PROCESS | 12300 | |2;audio-dft;161;160;161;161;160;162;161;160;160;163 05:07:27 INFO - PROCESS | 12300 | |3;audio-fft;74;75;105;105;75;75;73;114;104;73 05:07:27 INFO - PROCESS | 12300 | |4;audio-oscillator;94;94;150;93;93;94;148;94;93;140 05:07:27 INFO - PROCESS | 12300 | |5;imaging-gaussian-blur;117;117;116;117;117;117;118;116;116;116 05:07:27 INFO - PROCESS | 12300 | |6;imaging-darkroom;147;146;147;145;146;146;146;145;146;146 05:07:27 INFO - PROCESS | 12300 | |7;imaging-desaturate;95;94;94;95;95;95;95;95;94;94 05:07:27 INFO - PROCESS | 12300 | |8;json-parse-financial;64;65;66;65;65;65;65;82;62;63 05:07:27 INFO - PROCESS | 12300 | |9;json-stringify-tinderbox;64;64;64;65;64;65;64;65;65;65 05:07:27 INFO - PROCESS | 12300 | |10;stanford-crypto-aes;77;87;78;79;79;81;82;80;81;81 05:07:27 INFO - PROCESS | 12300 | |11;stanford-crypto-ccm;132;138;131;130;129;140;137;138;174;133 05:07:27 INFO - PROCESS | 12300 | |12;stanford-crypto-pbkdf2;172;171;181;175;176;175;173;171;173;173 05:07:27 INFO - PROCESS | 12300 | |13;stanford-crypto-sha256-iterative;73;71;72;72;71;72;72;71;73;73 05:07:27 INFO - PROCESS | 12300 | __end_tp_report 05:07:27 INFO - PROCESS | 12300 | __start_cc_report 05:07:27 INFO - PROCESS | 12300 | _x_x_mozilla_cycle_collect,1895 05:07:27 INFO - PROCESS | 12300 | __end_cc_report 05:07:27 INFO - PROCESS | 12300 | __startTimestamp1461586047170__endTimestamp 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | ------- Summary: start ------- 05:07:27 INFO - PROCESS | 12300 | Number of tests: 14 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#0] ai-astar Cycles:10 Average:111.00 Median:113.00 stddev:3.77 (3.3%) stddev-sans-first:4.00 05:07:27 INFO - PROCESS | 12300 | Values: 111.0 114.0 107.0 111.0 112.0 113.0 102.0 114.0 113.0 113.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#1] audio-beat-detection Cycles:10 Average:156.90 Median:156.00 stddev:24.41 (15.6%) stddev-sans-first:25.88 05:07:27 INFO - PROCESS | 12300 | Values: 159.0 133.0 151.0 193.0 134.0 161.0 153.0 138.0 205.0 142.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 05:07:27 INFO - PROCESS | 12300 | Values: 161.0 160.0 161.0 161.0 160.0 162.0 161.0 160.0 160.0 163.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#3] audio-fft Cycles:10 Average:87.30 Median:73.50 stddev:17.19 (23.4%) stddev-sans-first:17.54 05:07:27 INFO - PROCESS | 12300 | Values: 74.0 75.0 105.0 105.0 75.0 75.0 73.0 114.0 104.0 73.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#4] audio-oscillator Cycles:10 Average:109.30 Median:93.50 stddev:25.45 (27.2%) stddev-sans-first:26.39 05:07:27 INFO - PROCESS | 12300 | Values: 94.0 94.0 150.0 93.0 93.0 94.0 148.0 94.0 93.0 140.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 05:07:27 INFO - PROCESS | 12300 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 118.0 116.0 116.0 116.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.60 05:07:27 INFO - PROCESS | 12300 | Values: 147.0 146.0 147.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:07:27 INFO - PROCESS | 12300 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:65.00 stddev:5.67 (8.7%) stddev-sans-first:5.96 05:07:27 INFO - PROCESS | 12300 | Values: 64.0 65.0 66.0 65.0 65.0 65.0 65.0 82.0 62.0 63.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:65.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 05:07:27 INFO - PROCESS | 12300 | Values: 64.0 64.0 64.0 65.0 64.0 65.0 64.0 65.0 65.0 65.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:81.00 stddev:2.76 (3.4%) stddev-sans-first:2.62 05:07:27 INFO - PROCESS | 12300 | Values: 77.0 87.0 78.0 79.0 79.0 81.0 82.0 80.0 81.0 81.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:137.50 stddev:13.15 (9.6%) stddev-sans-first:13.75 05:07:27 INFO - PROCESS | 12300 | Values: 132.0 138.0 131.0 130.0 129.0 140.0 137.0 138.0 174.0 133.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:174.00 stddev:2.98 (1.7%) stddev-sans-first:3.07 05:07:27 INFO - PROCESS | 12300 | Values: 172.0 171.0 181.0 175.0 176.0 175.0 173.0 171.0 173.0 173.0 05:07:27 INFO - PROCESS | 12300 | 05:07:27 INFO - PROCESS | 12300 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:72.00 stddev:0.82 (1.1%) stddev-sans-first:0.78 05:07:27 INFO - PROCESS | 12300 | Values: 73.0 71.0 72.0 72.0 71.0 72.0 72.0 71.0 73.0 73.0 05:07:27 INFO - PROCESS | 12300 | -------- Summary: end -------- 05:07:27 INFO - PROCESS | 12300 | 05:07:28 INFO - TEST-INFO | 12300: exit 0 05:07:28 INFO - TEST-OK | kraken | took 63270ms 05:07:28 INFO - SUITE-END | took 1181s 05:07:28 INFO - Completed test suite (00:19:41) 05:07:28 INFO - javascript benchmark 05:07:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2563.8333508321957, 2632.157709532949, 2641.4452964877373, 2652.854093024883, 2671.0784779251157, 4579.175748365301, 4605.723656580603, 4617.861464156076, 4639.072185562884, 4648.790804413801, 4661.347411362403, 4697.412823397075, 4757.595604373624, 4783.952160478394, 4788.760561971905, 4292.399064131024, 4324.6107850293465, 4341.1317736452675, 4402.4937132229725, 4420.690551661385, 4308.3106702927535, 4342.109867477159, 4385.057212699269, 4413.205622987861, 4418.823247070113, 4196.958030419718, 4255.021345090616, 4355.346697995295, 4387.495438024636, 4397.692161548693, 4223.176480586287, 4227.4292970448905, 4240.575942405766, 4249.213895429355, 4253.149370125956, 4188.643965262956, 4226.0, 4226.619604235603, 4233.534311225762, 4274.29474136767, 4517.728936263835, 4585.747783871886, 4608.7004344717825, 4646.024334889677, 4657.4876763555985, 5019.29729837823, 5026.824061157842, 5043.067032598981, 5082.466341034212, 5090.490950904917, 4906.607471402279, 5036.093503169428, 5042.823501177478, 5053.519915608012, 5058.418281897592, 4625.560571745713, 4659.743714095757, 4666.0, 4701.106789710013, 4709.7174169549935, 5146.588272938156, 5159.896802063938, 5194.27280180775, 5198.584113271005, 5243.973780131151, 4769.379980602499, 4935.0, 4966.379202599675, 4979.203327467588, 4984.451710311862, 5040.470750571138, 5066.6453348266, 5072.289879416885, 5085.74571271435, 5118.948810511922, 5012.473690262543, 5036.294918711384, 5041.59667226621, 5045.520675535819, 5085.00842335741, 4816.397950256218, 4908.803647854046, 4942.728149951715, 5005.174146265898, 5030.220315851049, 4222.767747773902, 4234.851780187741, 4251.44731184944, 4268.658507319407, 4288.356746488052, 3682.650148235913, 3690.022144131807, 3694.0949467380665, 3723.422869455239, 3752.7748335099436, 4192.140611174702, 4304.289792184317, 4317.805698743501, 4319.8488052918, 4389.407429996973, 5026.447090820006, 5029.5221953914315, 5040.621953353513, 5041.420236672756, 5069.518395752398, 4317.481902171759, 4323.913521729548, 4326.805293761789, 4349.739015659071, 4373.868783936487, 4386.912261754747, 4501.279795232747, 4504.752238626841, 4575.839845605455, 4592.265237561974, 4665.393498845128, 4709.152352576432, 4714.481407045222, 4718.504557021463, 4735.715857048588, 4988.650794444354, 5010.5240002199735, 5012.273220382951, 5013.097642424253, 5071.290019397214, 4642.257238841769, 4737.549932756383, 4794.137055330074, 4837.612990960644, 4842.95157048425, 4891.26631005352, 4902.828401005948, 4909.214525675875, 4912.656114072123, 4966.205407134841, 4821.083994041121, 4974.278729584118, 4998.425181104074, 5000.399952005782, 5048.192289233852, 4876.78054487556, 4927.457979622311, 4932.457429682804, 4970.080535101018, 4979.850604481871, 4805.423349198119, 4840.7337596432535, 4845.1278769820365, 4853.247746599282, 4895.657303988686, 4684.273937539687, 4744.217204161275, 4783.1390349737385, 4807.879803004813, 4828.975855120702, 4833.347498087714, 4856.39295088114, 4899.70601763881, 4918.991606720685, 4930.630202734737, 4781.163296423181, 4864.173090574682, 4865.051314993684, 4869.050535145754, 4907.043126590281], "value": 4601.278080867789, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.9793857214999, 600.9008513595257, 608.1334098909057, 625.115461621805, 633.5755044120439, 27199.04803331884, 33943.0, 34881.30237395251, 34925.0, 35006.0, 37244.0, 37921.24157516855, 37986.62013379866, 37990.43014354787, 38002.8099859501, 7303.342699157075, 7504.512206706574, 7510.0, 7529.209433009523, 7709.3061624453785, 7465.365443937272, 9617.278704097185, 10037.046480584333, 10037.247206459508, 10051.346662466953, 9714.22286217101, 9762.267829912786, 9800.31397802154, 9822.508874556279, 9823.066808653202, 7517.436192285601, 7817.530948143073, 9538.570764315622, 9701.70894873155, 9728.221742260637, 7417.740379086706, 9496.857547136799, 9827.656032038843, 9839.90160098397, 9857.408555486694, 33952.32095358079, 34394.48408273878, 34526.82736586326, 34561.48157777635, 34568.30863382718, 2859.742623163904, 2866.5700144978214, 3060.4491191585507, 3197.344544368399, 3362.613299470543, 26838.73161268382, 27132.457350852874, 27483.17550473489, 29289.850807316358, 29617.0, 3267.941197045846, 3283.5074738789135, 3301.5542901708204, 3404.455287154068, 3418.7265018798435, 178721.9999999987, 179476.0, 179879.0, 181291.0, 181792.0, 52364.73817630888, 53396.73301633467, 53499.46500535023, 53566.196507052424, 53581.732091340076, 55598.72200638971, 55864.720676396355, 55870.0, 55911.0, 55928.48321825528, 12965.15726477776, 13198.538051168163, 13207.933960330136, 13211.867881321255, 13215.669608259677, 8968.820623587491, 9244.95377523108, 9250.537473126316, 9519.714838496715, 10031.69904902854, 15570.688586228438, 15934.0, 16003.599910002344, 16039.358425662842, 16040.957337773007, 5604.103343465026, 5673.3475650299915, 5707.571932105108, 5732.971335143381, 5733.111367738007, 877.7147427086222, 887.7114937645291, 934.6027938126269, 1028.1825948371063, 1084.6908631040117, 25678.743212567635, 26151.869240654054, 26172.214833555023, 27767.444651106864, 27814.86092569524, 993.64228877604, 1009.5255230041869, 1014.3710899242517, 1071.0681706915034, 1078.6440474643348, 13884.375203115682, 14552.490662826962, 14782.556523304318, 15003.849961500464, 15037.323320450607, 332059.0, 336216.0, 338906.0, 341029.0, 341083.0, 47647.52352476431, 48128.27807582889, 48129.0, 48160.18398160226, 48190.0, 1203.1938601137258, 1207.3600991474357, 1209.989658635032, 1232.7411243638758, 1335.2122247873606, 24071.390284091733, 26928.596071058557, 27584.44831103407, 27958.44083118367, 28052.43895122127, 38861.0, 39549.80225098856, 39605.20789584135, 39647.80176099101, 39650.40524392194, 231854.0, 236477.0, 236630.0, 237813.0, 237937.0, 30602.84698576493, 31915.840420797747, 31955.520667190474, 31971.200719982186, 31979.200519986254, 33272.0, 41293.38059929164, 41349.37975930423, 41758.791206043774, 42081.78959105185, 33046.0, 33735.662643374235, 33814.49278260877, 33824.66175338215, 34207.82896085503, 33487.0, 33690.66309336973, 33875.4918676225, 33938.66061339454, 34301.313973720884, 30109.397812044073, 30687.0, 30808.691913080584, 30847.0, 30868.382632347675, 29209.269768255963, 29518.262043449085, 32177.195570110933, 33366.666333336354, 35091.82454087713, 23083.88458057699, 23761.287161384495, 23823.52352952966, 24060.39849003789, 24066.0], "value": 16935.15869928343, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.08180580286995, 380.29075773682075, 387.5794762682489, 388.1906225519792, 390.2974645637851, 10327.793444131114, 10931.125509959204, 10956.452177391118, 10971.341719496826, 15991.280392382341, 16344.83655163448, 16679.83320166798, 17614.735778963295, 17624.647507049853, 17642.558936026606, 5499.340079190482, 5722.599418040739, 7110.395616372589, 7133.9286607134045, 7143.85712285754, 6953.061336719554, 7009.334113259232, 7043.295670432967, 7067.187273463566, 7070.363667269945, 6926.268432891777, 7044.295570442966, 7105.182903966059, 7129.180144283422, 7132.108486439195, 6941.757038503653, 7074.469414793911, 7090.255523170044, 7124.679389427488, 7128.893066604005, 6705.4970877184405, 6734.292899245557, 6819.0, 6905.9309406906295, 6999.23008469068, 21858.344249672537, 22617.54764904709, 22631.0, 22689.319320420414, 22794.886025569933, 2062.6802845558964, 2072.357569153552, 2167.0248388225205, 2431.732509423962, 2449.534588428193, 14018.0, 14291.071080379743, 14411.495597654137, 15114.697706045927, 15119.54641360761, 1866.9159887804944, 1956.5695546979641, 2019.4446527204989, 2025.4126303372004, 2079.396974877284, 77416.61291693561, 78367.99999999943, 81458.77811832886, 82415.0, 82721.0, 16160.191990400668, 16231.513054608144, 16422.53661950717, 16561.6687666246, 16706.0, 29165.85417072901, 29452.410951780843, 29500.262493437836, 29569.704302957125, 29614.482768964448, 8240.381971352173, 8560.0, 8611.138886111465, 8697.21725044749, 8731.60707768152, 6491.253505846888, 6492.935070649233, 6839.418649414756, 6943.778810950751, 7137.357637812518, 8080.595970201466, 8313.376496762767, 8343.874841877378, 8353.457025293457, 8379.916200838035, 3207.631122420905, 3270.1438364029395, 3348.54643324378, 3408.812515311632, 3440.8623655053516, 410.1857812242696, 412.5626835554322, 415.5387519852961, 793.3137835772131, 925.7500474871747, 10123.291369604058, 10561.577536898592, 10691.572337106583, 10754.677359679223, 10888.891111088946, 428.52219774950703, 430.322242468114, 431.7193824014416, 444.1250736049976, 451.5687518419901, 5722.54219662426, 5738.741756620962, 5771.85570360736, 5775.133729940542, 5831.650100993954, 145110.0, 145553.27223363816, 145590.0, 145652.27173864061, 165537.0, 54308.45691543113, 71939.0, 81519.0, 82973.0, 83042.0, 442.8715672454952, 448.26484565313564, 449.8627918484828, 451.06854345775866, 452.1386758225585, 9627.663031793854, 9919.107280344804, 10036.0, 10129.949350253497, 10206.795864082826, 31932.0, 34103.147421314665, 34246.48630270498, 35034.29931401409, 35750.46374304336, 48578.27132592943, 48626.27060594023, 48754.512454874995, 48908.0, 49081.50918490769, 7424.331810137113, 7762.223777622193, 7770.417218708506, 7833.451658383859, 7937.563434011185, 10697.964167351984, 10740.0, 10876.836847447137, 11228.943855280671, 11424.143189260672, 9548.37935534188, 9690.079442452956, 9754.512274386394, 9881.604735810488, 9918.801623967625, 9511.334206605748, 9614.471204083842, 9992.900070999198, 10072.395656260647, 10143.442110683687, 9978.301518893608, 10138.949305253427, 10301.433421161908, 10409.895901040893, 10588.047075763217, 7547.622618868925, 7667.504836557041, 7793.10379306387, 7913.089994650689, 7948.443608947318, 9592.0, 9737.561809718438, 9783.657571984946, 10092.798144036931, 10213.712734964292], "value": 8246.476867575946, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1237.8700236475165, 1356.6947436826715, 1436.5044059799372, 1560.5864445921832, 1739.6085880676849, 6823.965880170598, 7080.787576372704, 7484.962575187116, 7661.6169191540475, 7686.846263074735, 7453.962730186341, 7477.663505142268, 7515.887261691079, 7517.962410187942, 7527.360174385184, 1916.252661462031, 2117.1637203304685, 2218.1792736687407, 2248.303026061918, 2252.087904398713, 2281.532285881399, 2300.275413244831, 2301.8158547316257, 2304.6082166031815, 2306.8961896714686, 2223.544173444448, 2224.4327696437385, 2233.530958498717, 2265.478939843837, 2274.442761523429, 1877.11775465531, 2130.957380852382, 2134.4877229464973, 2151.397608669575, 2240.6414973604146, 2231.0741042467357, 2259.6497542880716, 2262.468319944812, 2263.954720905589, 2266.490039741045, 7749.7675069748, 7806.414518911104, 7812.06255249368, 7817.296443320129, 7821.413393995416, 4518.502964673883, 4671.205894997858, 4682.8126874924965, 4688.601468875116, 4691.7888695008805, 2797.6642802863785, 2843.431313737261, 2939.514980028293, 2964.0, 2975.64292284925, 891.131147131548, 896.6771962093641, 915.9496227707474, 916.1937495004354, 924.3621900888365, 10792.51433685486, 10798.406087665175, 10812.729681758019, 10820.513076911559, 10841.566337346496, 4285.314349704033, 4300.698951073395, 4496.3705081288645, 4501.977490112594, 4523.140603285366, 9177.219936305355, 9188.586513606904, 9188.816223675489, 9206.631734730536, 9213.539323033821, 4746.1456937750845, 4746.4541577719, 4754.0, 4758.738269395148, 4771.856844294677, 3808.8476460941806, 3924.4505769192338, 3981.5421226558738, 4253.149370125925, 4327.653787696977, 3693.538307711536, 3706.1475860552223, 3784.9810750946067, 3813.256414999104, 3817.8854634361014, 1243.0801207106676, 1259.0431272232986, 1266.5187228852976, 1271.8600953895063, 1273.4842388832537, 705.5378726933867, 750.9586972716553, 763.9885401718979, 792.8374683189933, 800.371708209053, 5053.974730126326, 5227.895442091137, 5323.653962492425, 5366.651167674088, 5403.162509810985, 834.4284165346756, 873.5021038008401, 877.5436772878068, 879.366855863777, 879.5997820991433, 3352.5641666583674, 3450.8274586270586, 3461.7576769625884, 3477.4088404971217, 3498.160441494023, 9506.47714375716, 9902.207823374112, 9906.603735850485, 9944.950275248577, 9966.950165249127, 9178.357514973888, 9620.37467564606, 9833.213342932548, 9840.212782977345, 9861.852072218922, 6997.895031574531, 7042.119735033121, 7129.394001509826, 7129.857402851914, 7141.82145446368, 4973.229149481836, 4988.02733466985, 5093.821716239914, 5124.410692770379, 5161.329027226437, 8895.0, 8927.0, 8930.464172149692, 8944.552772361487, 9042.0, 8256.958715206385, 8596.269317107866, 8606.61270242853, 8628.39601227908, 9402.811943761224, 6127.417895299939, 6583.637899915359, 6632.83417914556, 6749.3588109131515, 6811.693473793592, 7414.592197429193, 7429.925700742923, 7479.738209162653, 7505.174430812715, 7510.399168066432, 6553.442957348488, 6727.024581435567, 6746.6626668665385, 6861.1080559526945, 6867.278935711678, 6620.337966203534, 6632.933670663231, 6649.301823308483, 6698.0, 6824.863502729819, 6265.8120256392385, 6288.0, 6305.180326557518, 6323.810285691437, 6324.3991820776955, 4284.764337961442, 4402.757848318373, 4479.910401792011, 4525.479569849509, 4551.704139230939, 4546.113507866066, 4547.363369128391, 4577.725336479822, 4580.221362368338, 4586.2661974083985], "value": 4130.813235128644, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.778785955901, 314.9795263307885, 319.5111479436462, 320.8556149732621, 323.8801643391944, 5124.12889808733, 5145.511176438242, 5154.974225128878, 5162.767675454605, 5171.715555644438, 5090.211017292317, 5105.489451054893, 5110.488951104888, 5114.386273647158, 5130.179171332587, 3778.1688028633725, 3899.25914076326, 3916.373380259159, 4023.7988100595, 4037.6567991720594, 4006.8797936061965, 4029.415734718464, 4032.254033003889, 4039.5556488786356, 4040.5555388907196, 3722.9441558376648, 3975.662068724162, 3992.960070399288, 3994.2011597680435, 3997.700172487075, 3727.235916637083, 3858.826352814116, 3901.141748815256, 4001.519817621875, 4008.3586626139677, 3525.576930768324, 3526.4710293456187, 3542.468629705539, 3543.716502679805, 3544.3240353533174, 4909.729964851932, 4922.089413458521, 4923.80304787808, 4936.827211047632, 4947.356843610308, 4506.684532082755, 4633.49031606523, 4634.142683603544, 4639.512851150648, 4647.86056418308, 5324.334458192726, 5344.331958505187, 5357.705326207057, 5362.892742145174, 5364.678119312854, 3860.1507668312925, 3881.3207688654375, 3890.396988466792, 3895.7857317847506, 3913.3347330953807, 5444.6188766786345, 5641.323041235037, 5663.461971112738, 5671.461211184931, 5672.234248376499, 3987.940180897289, 3995.340768773178, 4000.739951903117, 4003.579624139482, 4005.118873847749, 4059.594040595917, 4062.8106936055055, 4082.5713300103657, 4096.6313031826685, 4115.36211887158, 4370.737755734666, 4635.0, 4641.118187544355, 4642.094791515689, 4652.906941861143, 5412.188171774265, 5439.265699130646, 5451.746098397391, 5454.263674403984, 5463.672179669153, 1412.216219997911, 1430.8783753381078, 1436.1598464897943, 1436.2818590704649, 1437.173625165534, 895.583553647544, 954.8207963165485, 973.9610415583297, 983.975400614976, 999.3554157568328, 4671.11248862715, 4790.568848803624, 4795.20879054959, 4806.831760888352, 4810.542998415145, 5538.944610553924, 5634.295713035871, 5636.802711905064, 5645.322561292671, 5653.462921022497, 4573.63410927125, 4770.141374552544, 4775.21209000518, 4806.903861922742, 4808.975955120202, 2989.355854280137, 3023.4557779599445, 3032.9233122241285, 3036.6659667436566, 3046.2232130806947, 5310.150375939831, 5316.4582271140935, 5333.173358129496, 5336.199570064521, 5349.4650534946195, 5520.337559492807, 5826.096954971986, 5828.242328497353, 5829.09649004405, 5832.300123985064, 5307.3896501902, 5316.554384982874, 5326.135852451611, 5333.466653334635, 5336.919946200733, 5677.489025987681, 5686.374498805211, 5686.459786320293, 5690.3539853968105, 5695.7436915338085, 5278.868028299324, 5525.4474552544425, 5560.638558493685, 5569.827767170726, 5572.86067848307, 5258.868528286824, 5271.525562699222, 5279.683219006872, 5288.286081378965, 5296.708681022581, 3850.133719912997, 3873.6901047916663, 3907.29668659644, 3911.882643520699, 3916.6866650667307, 5537.6954267515675, 5635.887282254414, 5636.239107720569, 5638.182463542846, 5652.349979752216, 5425.996190704732, 5439.918401224064, 5450.209719590716, 5458.4541545845095, 5462.470508257694, 5215.765290561858, 5407.567394608343, 5425.972870135624, 5427.213054107211, 5434.239206511171, 5300.867478313073, 5340.893182136258, 5344.652597581145, 5353.705546194997, 5361.758720857649, 5184.533391994739, 5227.529522343008, 5434.347878254634, 5498.367687715964, 5503.587230957614, 5301.284326615858, 5312.468753124656, 5330.520253177077, 5340.465953404629, 5346.465353464622], "value": 4095.153288365941, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.0696383950084, 706.6219572528698, 734.5923012727937, 756.7237958145275, 770.626246270559, 10913.399763013042, 11235.101191904649, 11302.208845380816, 11318.434078296083, 11941.283522988626, 13875.16748995061, 13964.860351396483, 13986.370613322399, 13991.790123148161, 13994.720105597882, 630.6153246519619, 751.8533885892253, 774.5004472115496, 785.9253370929781, 786.3591173193829, 780.7813812132612, 791.2562191539963, 792.0455850699893, 792.9563873986929, 793.5000949401868, 808.8584497712906, 809.1180613131685, 814.2915663372873, 814.8044469327378, 814.8248126652768, 649.4933951517824, 791.9287264146254, 813.7640084375524, 817.6238930092135, 817.9100298967109, 594.0999385930319, 594.9434803693686, 595.5860676829606, 596.1802521532892, 596.83885350955, 10614.416207108607, 10728.141748660088, 10738.892611073945, 10748.086412654933, 10752.086072683831, 10895.073918716918, 11260.774784504347, 11351.09191264705, 11352.943235283854, 11415.258008229439, 9989.800203995952, 10080.747981300452, 10152.593896244141, 10258.640947566799, 10350.378977261389, 3125.984370078158, 3202.647708752035, 3203.727683146912, 3228.4834426491648, 3248.951265731016, 41552.79223603863, 47060.5293947063, 47251.29123063122, 47345.289820652375, 47406.0, 9178.173964343108, 9465.716028519155, 9822.705318840446, 9836.262280329003, 10232.232582556338, 16983.830161698224, 17140.91429542869, 17146.22841972114, 17147.0, 17149.142542872807, 6757.425618822357, 6896.93103068973, 6949.861002780017, 7100.360967512948, 7151.32062454066, 3768.5477742671055, 3800.638939300817, 3870.0518372998795, 3884.533855937249, 3899.6100389960775, 2885.783566232541, 2936.4861149299213, 2939.5590661400533, 2941.07356182806, 2944.6024786653957, 520.903632827928, 522.6289334572496, 529.3224672419309, 533.386605403781, 533.4931814775979, 472.8557789874121, 608.9604175728564, 615.8891399548124, 616.0851136062943, 628.8050704281687, 7065.1521817382245, 7202.1357437106835, 7314.45141614381, 7331.596019791448, 7629.237076292437, 484.672845829064, 527.8495628745789, 558.2324304081887, 566.8441178675897, 567.4524084258628, 3480.0951752544015, 3723.832427540767, 3725.869594564177, 3732.290864735746, 3829.751066180689, 53328.46671533235, 54119.72940135274, 54231.45768542343, 54317.1852422214, 54331.45668543343, 44544.0, 45317.77341113339, 45386.31920521195, 45734.0, 45832.0, 39436.60563394329, 41096.79451602723, 42094.78952605279, 42123.368149477785, 42463.57536424658, 6993.4055605274125, 7247.420206383582, 7250.528715633467, 7268.709251629875, 7391.630418478947, 27748.0, 28068.157955261377, 28446.57330140091, 28476.715232848233, 28678.71321286844, 34862.0, 35046.0, 37384.81307593445, 37669.434958476195, 38568.80715596404, 8812.60343284541, 8961.283097352327, 9576.281778866474, 9667.081627245401, 9761.9023809761, 10255.538500767334, 10280.0, 10296.742581435525, 10306.587736490457, 10399.908009658877, 9949.801003980025, 10039.096481316716, 10110.472381904523, 10264.64073757415, 10321.225908056776, 9510.904890951002, 9562.139407453362, 9593.184579310837, 9676.564554595201, 10079.34484258521, 10315.381077135396, 10656.14750819917, 10883.727906802393, 10896.945515272373, 11008.174386921173, 8661.13388661154, 8873.866891996755, 8916.55417229123, 8929.10708929102, 9103.0, 8422.157784221774, 8614.526201059001, 8707.91292087071, 8723.345749068973, 9139.177474027369], "value": 5701.357821223585, "unit": "score"}], "name": "dromaeo_css", "value": 6290.8742997555855, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 107.0, 111.0, 112.0, 113.0, 102.0, 114.0, 113.0, 113.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 133.0, 151.0, 193.0, 134.0, 161.0, 153.0, 138.0, 205.0, 142.0], "value": 156.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 160.0, 162.0, 161.0, 160.0, 160.0, 163.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 105.0, 105.0, 75.0, 75.0, 73.0, 114.0, 104.0, 73.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 150.0, 93.0, 93.0, 94.0, 148.0, 94.0, 93.0, 140.0], "value": 109.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 118.0, 116.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0, 82.0, 62.0, 63.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 65.0, 65.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 87.0, 78.0, 79.0, 79.0, 81.0, 82.0, 80.0, 81.0, 81.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 138.0, 131.0, 130.0, 129.0, 140.0, 137.0, 138.0, 174.0, 133.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 181.0, 175.0, 176.0, 175.0, 173.0, 171.0, 173.0, 173.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 72.0, 72.0, 71.0, 72.0, 72.0, 71.0, 73.0, 73.0], "value": 72.0, "unit": "score"}], "name": "kraken", "value": 1578.1, "alertThreshold": 2.0}]} 05:07:28 INFO - Return code: 0 05:07:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:07:28 ERROR - # TBPL SUCCESS # 05:07:28 INFO - Running post-action listener: _resource_record_post_action 05:07:28 INFO - Running post-run listener: _resource_record_post_run 05:07:29 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 517615616; Read time: 4; Write time: 417944 05:07:29 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234295296; Read time: 0; Write time: 232664 05:07:29 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283320320; Read time: 4; Write time: 185280 05:07:29 INFO - Running post-run listener: _upload_blobber_files 05:07:29 INFO - Blob upload gear active. 05:07:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:07:29 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 05:07:29 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'] 05:07:29 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 05:07:29 INFO - (blobuploader) - INFO - Open directory for files ... 05:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:07:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:30 INFO - (blobuploader) - INFO - Done attempting. 05:07:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:07:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:07:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:07:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:07:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:07:31 INFO - (blobuploader) - INFO - Done attempting. 05:07:31 INFO - (blobuploader) - INFO - Iteration through files over. 05:07:31 INFO - Return code: 0 05:07:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:07:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:07:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90e01ec4ceea7390bee0f3e0f1a8315d690330fb889d322dce13fe2e137bbd902ade6e02fc61514ffce456eb43b00d5b71b1cc59ceda09fad665d8c6f1db6e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6d0f7aeba3049b1c635732bbdffa2e5de076c5e13ee9bf8423b4540b2d44cb62ae3ac5f729cd532916c2a712a9fe16392fb28c0fee39d367632a2d78ac2b4320"} 05:07:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:07:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:07:31 INFO - Contents: 05:07:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/90e01ec4ceea7390bee0f3e0f1a8315d690330fb889d322dce13fe2e137bbd902ade6e02fc61514ffce456eb43b00d5b71b1cc59ceda09fad665d8c6f1db6e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6d0f7aeba3049b1c635732bbdffa2e5de076c5e13ee9bf8423b4540b2d44cb62ae3ac5f729cd532916c2a712a9fe16392fb28c0fee39d367632a2d78ac2b4320"} 05:07:31 INFO - Running post-run listener: copy_logs_to_upload_dir 05:07:31 INFO - Copying logs to upload dir... 05:07:31 INFO - mkdir: /builds/slave/test/build/upload/logs 05:07:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.104462 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-04-25 05:07:31.305107) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:31.310141) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891 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/90e01ec4ceea7390bee0f3e0f1a8315d690330fb889d322dce13fe2e137bbd902ade6e02fc61514ffce456eb43b00d5b71b1cc59ceda09fad665d8c6f1db6e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6d0f7aeba3049b1c635732bbdffa2e5de076c5e13ee9bf8423b4540b2d44cb62ae3ac5f729cd532916c2a712a9fe16392fb28c0fee39d367632a2d78ac2b4320"} build_url:https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011266 build_url: 'https://queue.taskcluster.net/v1/task/DxZ-lO9wSayuTkL92QAvgQ/artifacts/public/build/firefox-48.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/90e01ec4ceea7390bee0f3e0f1a8315d690330fb889d322dce13fe2e137bbd902ade6e02fc61514ffce456eb43b00d5b71b1cc59ceda09fad665d8c6f1db6e80", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6d0f7aeba3049b1c635732bbdffa2e5de076c5e13ee9bf8423b4540b2d44cb62ae3ac5f729cd532916c2a712a9fe16392fb28c0fee39d367632a2d78ac2b4320"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:31.572471) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:31.572884) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1461584336.877976-966309891 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 05:07:31.654357) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:07:31.654659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 05:07:31.657973) ========= ========= Total master_lag: 0.43 =========