builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-059 starttime: 1462833933.55 results: success (0) buildid: 20160509152513 builduid: f4dbe01610fe4e83858feaa996fe1855 revision: c94c82ce23659b4132ca50930ab124212aab3044 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.546507) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.546995) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.547274) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.580362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.580721) ========= 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=3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-09 15:45:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2016-05-09 15:45:33 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.119738 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.732801) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.733113) ========= 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=3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014027 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.783944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:33.784265) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c94c82ce23659b4132ca50930ab124212aab3044 --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 c94c82ce23659b4132ca50930ab124212aab3044 --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=3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-09 15:45:34,089 truncating revision to first 12 chars 2016-05-09 15:45:34,089 Setting DEBUG logging. 2016-05-09 15:45:34,089 attempt 1/10 2016-05-09 15:45:34,089 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c94c82ce2365?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 15:45:34,350 unpacking tar archive at: fx-team-c94c82ce2365/testing/mozharness/ program finished with exit code 0 elapsedTime=0.524269 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:34.322257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:34.322584) ========= script_repo_revision: c94c82ce23659b4132ca50930ab124212aab3044 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:34.322991) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:34.323255) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 15:45:34.337600) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-05-09 15:45:34.337915) ========= /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=3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:45:34 INFO - MultiFileLogger online at 20160509 15:45:34 in /builds/slave/test 15:45:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 15:45:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:45:34 INFO - {'append_to_log': False, 15:45:34 INFO - 'base_work_dir': '/builds/slave/test', 15:45:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 15:45:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:45:34 INFO - 'branch': 'Fx-Team-Non-PGO', 15:45:34 INFO - 'buildbot_json_path': 'buildprops.json', 15:45:34 INFO - 'config_files': ('talos/linux_config.py',), 15:45:34 INFO - 'default_actions': ('clobber', 15:45:34 INFO - 'read-buildbot-config', 15:45:34 INFO - 'download-and-extract', 15:45:34 INFO - 'populate-webroot', 15:45:34 INFO - 'create-virtualenv', 15:45:34 INFO - 'install', 15:45:34 INFO - 'run-tests'), 15:45:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:45:34 INFO - 'download_minidump_stackwalk': True, 15:45:34 INFO - 'download_symbols': 'ondemand', 15:45:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:45:34 INFO - 'tooltool.py': '/tools/tooltool.py', 15:45:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:45:34 INFO - '/tools/misc-python/virtualenv.py')}, 15:45:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:45:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:45:34 INFO - 'installer_path': 'installer.exe', 15:45:34 INFO - 'log_level': 'info', 15:45:34 INFO - 'log_name': 'talos', 15:45:34 INFO - 'log_to_console': True, 15:45:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:45:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:45:34 INFO - 'opt_config_files': (), 15:45:34 INFO - 'pip_index': False, 15:45:34 INFO - 'sps_profile': False, 15:45:34 INFO - 'sps_profile_interval': 0, 15:45:34 INFO - 'suite': 'dromaeojs', 15:45:34 INFO - 'system_bits': '32', 15:45:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:45:34 INFO - 'title': 'talos-linux64-ix-059', 15:45:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:45:34 INFO - 'use_talos_json': True, 15:45:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:45:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:45:34 INFO - 'work_dir': 'build'} 15:45:34 INFO - ##### 15:45:34 INFO - ##### Running clobber step. 15:45:34 INFO - ##### 15:45:34 INFO - Running pre-action listener: _resource_record_pre_action 15:45:34 INFO - Running main action method: clobber 15:45:34 INFO - rmtree: /builds/slave/test/build 15:45:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:45:35 INFO - Running post-action listener: _resource_record_post_action 15:45:35 INFO - ##### 15:45:35 INFO - ##### Finished clobber step (success) 15:45:35 INFO - ##### 15:45:35 INFO - ##### 15:45:35 INFO - ##### Running read-buildbot-config step. 15:45:35 INFO - ##### 15:45:35 INFO - Running pre-action listener: _resource_record_pre_action 15:45:35 INFO - Running main action method: read_buildbot_config 15:45:35 INFO - Using buildbot properties: 15:45:35 INFO - { 15:45:35 INFO - "project": "", 15:45:35 INFO - "product": "firefox", 15:45:35 INFO - "script_repo_revision": "production", 15:45:35 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 15:45:35 INFO - "repository": "", 15:45:35 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 15:45:35 INFO - "buildid": "20160509152513", 15:45:35 INFO - "pgo_build": "False", 15:45:35 INFO - "basedir": "/builds/slave/test", 15:45:35 INFO - "buildnumber": 1672, 15:45:35 INFO - "slavename": "talos-linux64-ix-059", 15:45:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:45:35 INFO - "platform": "ubuntu64_hw", 15:45:35 INFO - "scheduler": "tests-fx-team-linux64-talos", 15:45:35 INFO - "branch": "fx-team", 15:45:35 INFO - "repo_path": "integration/fx-team", 15:45:35 INFO - "revision": "c94c82ce23659b4132ca50930ab124212aab3044", 15:45:35 INFO - "stage_platform": "linux64", 15:45:35 INFO - "builduid": "f4dbe01610fe4e83858feaa996fe1855", 15:45:35 INFO - "slavebuilddir": "test" 15:45:35 INFO - } 15:45:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 15:45:35 INFO - Running post-action listener: _resource_record_post_action 15:45:35 INFO - ##### 15:45:35 INFO - ##### Finished read-buildbot-config step (success) 15:45:35 INFO - ##### 15:45:35 INFO - ##### 15:45:35 INFO - ##### Running download-and-extract step. 15:45:35 INFO - ##### 15:45:35 INFO - Running pre-action listener: _resource_record_pre_action 15:45:35 INFO - Running main action method: download_and_extract 15:45:35 INFO - mkdir: /builds/slave/test/build/tests 15:45:35 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')]} 15:45:35 INFO - https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:45:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:45:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:45:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:45:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:45:37 INFO - Downloaded 1361 bytes. 15:45:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 15:45:37 INFO - Using the following test package requirements: 15:45:37 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:45:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:45:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:45:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'jsshell-linux-x86_64.zip'], 15:45:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:45:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 15:45:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:45:37 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:45:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:45:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 15:45:37 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:45:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:45:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:37 INFO - https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:45:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:45:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:45:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:45:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:51 INFO - Downloaded 22323319 bytes. 15:45:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:45:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:45:52 INFO - Return code: 0 15:45:52 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:52 INFO - https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:45:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:45:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:45:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 15:45:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:45:56 INFO - Downloaded 22323319 bytes. 15:45:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:45:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:45:58 INFO - Return code: 0 15:45: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')]} 15:45:58 INFO - https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:45:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 15:45:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:45:59 INFO - Downloaded 10840152 bytes. 15:45:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:45:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:45:59 INFO - Return code: 0 15:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:45:59 INFO - https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:45:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:45:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:45:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:45:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:46:00 INFO - Downloaded 54859108 bytes. 15:46:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:46:00 INFO - mkdir: /builds/slave/test/properties 15:46:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:00 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:00 INFO - Contents: 15:46:00 INFO - build_url:https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 15:46:01 INFO - Running post-action listener: _resource_record_post_action 15:46:01 INFO - Running post-action listener: set_extra_try_arguments 15:46:01 INFO - ##### 15:46:01 INFO - ##### Finished download-and-extract step (success) 15:46:01 INFO - ##### 15:46:01 INFO - ##### 15:46:01 INFO - ##### Running populate-webroot step. 15:46:01 INFO - ##### 15:46:01 INFO - Running pre-action listener: _resource_record_pre_action 15:46:01 INFO - Running main action method: populate_webroot 15:46:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:46:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:46:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:46:01 INFO - '--tppagecycles', 15:46:01 INFO - '7'], 15:46:01 INFO - 'tests': ['tsvgm']}}, 15:46:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['damp', 'tps']}, 15:46:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'tests': ['damp', 'tps']}, 15:46:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['dromaeo_dom']}, 15:46:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['a11yr', 15:46:01 INFO - 'ts_paint', 15:46:01 INFO - 'tpaint', 15:46:01 INFO - 'sessionrestore', 15:46:01 INFO - 'sessionrestore_no_auto_restore', 15:46:01 INFO - 'tabpaint']}, 15:46:01 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:01 INFO - 'ts_paint', 15:46:01 INFO - 'tpaint', 15:46:01 INFO - 'sessionrestore', 15:46:01 INFO - 'sessionrestore_no_auto_restore', 15:46:01 INFO - 'tabpaint']}, 15:46:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:46:01 INFO - 'ts_paint', 15:46:01 INFO - 'tpaint', 15:46:01 INFO - 'sessionrestore', 15:46:01 INFO - 'sessionrestore_no_auto_restore', 15:46:01 INFO - 'tabpaint']}, 15:46:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:46:01 INFO - 'ts_paint', 15:46:01 INFO - 'tpaint', 15:46:01 INFO - 'sessionrestore', 15:46:01 INFO - 'sessionrestore_no_auto_restore', 15:46:01 INFO - 'tabpaint']}, 15:46:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['a11yr', 15:46:01 INFO - 'ts_paint', 15:46:01 INFO - 'tpaint', 15:46:01 INFO - 'sessionrestore', 15:46:01 INFO - 'sessionrestore_no_auto_restore', 15:46:01 INFO - 'tabpaint']}, 15:46:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['a11yr', 15:46:01 INFO - 'ts_paint', 15:46:01 INFO - 'tpaint', 15:46:01 INFO - 'sessionrestore', 15:46:01 INFO - 'sessionrestore_no_auto_restore', 15:46:01 INFO - 'tabpaint']}, 15:46:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['tsvgx', 15:46:01 INFO - 'tsvgr_opacity', 15:46:01 INFO - 'tart', 15:46:01 INFO - 'tscrollx', 15:46:01 INFO - 'cart']}, 15:46:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:01 INFO - 'tsvgr_opacity', 15:46:01 INFO - 'tart', 15:46:01 INFO - 'tscrollx', 15:46:01 INFO - 'cart']}, 15:46:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'talos_options': ['--disable-e10s'], 15:46:01 INFO - 'tests': ['tp5o']}, 15:46:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'tests': ['tp5o']}, 15:46:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'talos_options': ['--disable-e10s', 15:46:01 INFO - '--xperf_path', 15:46:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:01 INFO - 'tests': ['tp5n']}, 15:46:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:46:01 INFO - 'talos_options': ['--xperf_path', 15:46:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:01 INFO - 'tests': ['tp5n']}}, 15:46:01 INFO - 'talos.zip': {'path': '', 15:46:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:46:01 INFO - Running post-action listener: _resource_record_post_action 15:46:01 INFO - ##### 15:46:01 INFO - ##### Finished populate-webroot step (success) 15:46:01 INFO - ##### 15:46:01 INFO - ##### 15:46:01 INFO - ##### Running create-virtualenv step. 15:46:01 INFO - ##### 15:46:01 INFO - Running pre-action listener: _resource_record_pre_action 15:46:01 INFO - Running main action method: create_virtualenv 15:46:01 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:01 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 15:46:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:46:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:01 INFO - Using real prefix '/usr' 15:46:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:02 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:04 INFO - Installing pip.................done. 15:46:04 INFO - Return code: 0 15:46:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46:04 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')]} 15:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:04 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')]} 15:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:04 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:04 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 15:46:04 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 15:46:04 INFO - Using env: {'DISPLAY': ':0', 15:46:04 INFO - 'HOME': '/home/cltbld', 15:46:04 INFO - 'LANG': 'en_US.UTF-8', 15:46:04 INFO - 'LANGUAGE': 'en_US:en', 15:46:04 INFO - 'LOGNAME': 'cltbld', 15:46:04 INFO - 'MAIL': '/var/mail/cltbld', 15:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:04 INFO - 'MOZ_NO_REMOTE': '1', 15:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:04 INFO - 'NO_EM_RESTART': '1', 15:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:04 INFO - 'PWD': '/builds/slave/test', 15:46:04 INFO - 'SHELL': '/bin/bash', 15:46:04 INFO - 'SHLVL': '1', 15:46:04 INFO - 'TERM': 'linux', 15:46:04 INFO - 'TMOUT': '86400', 15:46:04 INFO - 'USER': 'cltbld', 15:46:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:04 INFO - '_': '/tools/buildbot/bin/python'} 15:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:04 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 15:46:04 INFO - Cleaning up... 15:46:04 INFO - Return code: 0 15:46:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:46:04 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')]} 15:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:04 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')]} 15:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:04 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:04 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 15:46:04 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 15:46:04 INFO - Using env: {'DISPLAY': ':0', 15:46:04 INFO - 'HOME': '/home/cltbld', 15:46:04 INFO - 'LANG': 'en_US.UTF-8', 15:46:04 INFO - 'LANGUAGE': 'en_US:en', 15:46:04 INFO - 'LOGNAME': 'cltbld', 15:46:04 INFO - 'MAIL': '/var/mail/cltbld', 15:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:04 INFO - 'MOZ_NO_REMOTE': '1', 15:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:04 INFO - 'NO_EM_RESTART': '1', 15:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:04 INFO - 'PWD': '/builds/slave/test', 15:46:04 INFO - 'SHELL': '/bin/bash', 15:46:04 INFO - 'SHLVL': '1', 15:46:04 INFO - 'TERM': 'linux', 15:46:04 INFO - 'TMOUT': '86400', 15:46:04 INFO - 'USER': 'cltbld', 15:46:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:04 INFO - '_': '/tools/buildbot/bin/python'} 15:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:04 INFO - Downloading/unpacking psutil>=0.7.1 15:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:46:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:06 INFO - Installing collected packages: psutil 15:46:06 INFO - Running setup.py install for psutil 15:46:06 INFO - building 'psutil._psutil_linux' extension 15:46:06 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 15:46:06 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 15:46:06 INFO - building 'psutil._psutil_posix' extension 15:46:06 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 15:46:06 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 15:46:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:07 INFO - Successfully installed psutil 15:46:07 INFO - Cleaning up... 15:46:07 INFO - Return code: 0 15:46:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46: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')]} 15:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46: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', '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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:46: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 mozsystemmonitor==0.0.0 15:46:07 INFO - Using env: {'DISPLAY': ':0', 15:46:07 INFO - 'HOME': '/home/cltbld', 15:46:07 INFO - 'LANG': 'en_US.UTF-8', 15:46:07 INFO - 'LANGUAGE': 'en_US:en', 15:46:07 INFO - 'LOGNAME': 'cltbld', 15:46:07 INFO - 'MAIL': '/var/mail/cltbld', 15:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:07 INFO - 'MOZ_NO_REMOTE': '1', 15:46:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:07 INFO - 'NO_EM_RESTART': '1', 15:46:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:07 INFO - 'PWD': '/builds/slave/test', 15:46:07 INFO - 'SHELL': '/bin/bash', 15:46:07 INFO - 'SHLVL': '1', 15:46:07 INFO - 'TERM': 'linux', 15:46:07 INFO - 'TMOUT': '86400', 15:46:07 INFO - 'USER': 'cltbld', 15:46:07 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:07 INFO - '_': '/tools/buildbot/bin/python'} 15:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:46:07 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 15:46:07 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 15:46:07 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 15:46:07 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 15:46:07 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 15:46:07 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 15:46:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:46:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:46:09 INFO - Installing collected packages: mozsystemmonitor 15:46:09 INFO - Running setup.py install for mozsystemmonitor 15:46:09 INFO - Successfully installed mozsystemmonitor 15:46:09 INFO - Cleaning up... 15:46:09 INFO - Return code: 0 15:46:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:46:09 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')]} 15:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:09 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')]} 15:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:09 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:09 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 15:46:09 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 15:46:09 INFO - Using env: {'DISPLAY': ':0', 15:46:09 INFO - 'HOME': '/home/cltbld', 15:46:09 INFO - 'LANG': 'en_US.UTF-8', 15:46:09 INFO - 'LANGUAGE': 'en_US:en', 15:46:09 INFO - 'LOGNAME': 'cltbld', 15:46:09 INFO - 'MAIL': '/var/mail/cltbld', 15:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:09 INFO - 'MOZ_NO_REMOTE': '1', 15:46:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:09 INFO - 'NO_EM_RESTART': '1', 15:46:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:09 INFO - 'PWD': '/builds/slave/test', 15:46:09 INFO - 'SHELL': '/bin/bash', 15:46:09 INFO - 'SHLVL': '1', 15:46:09 INFO - 'TERM': 'linux', 15:46:09 INFO - 'TMOUT': '86400', 15:46:09 INFO - 'USER': 'cltbld', 15:46:09 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:09 INFO - '_': '/tools/buildbot/bin/python'} 15:46:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:09 INFO - Downloading/unpacking blobuploader==1.2.4 15:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:11 INFO - Downloading blobuploader-1.2.4.tar.gz 15:46:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:46:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:46:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:46:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:12 INFO - Downloading docopt-0.6.1.tar.gz 15:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:46:12 INFO - Installing collected packages: blobuploader, requests, docopt 15:46:12 INFO - Running setup.py install for blobuploader 15:46:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:46:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:46:12 INFO - Running setup.py install for requests 15:46:12 INFO - Running setup.py install for docopt 15:46:12 INFO - Successfully installed blobuploader requests docopt 15:46:12 INFO - Cleaning up... 15:46:12 INFO - Return code: 0 15:46:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:12 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')]} 15:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:12 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')]} 15:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:12 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:12 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 15:46:12 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 15:46:12 INFO - Using env: {'DISPLAY': ':0', 15:46:12 INFO - 'HOME': '/home/cltbld', 15:46:12 INFO - 'LANG': 'en_US.UTF-8', 15:46:12 INFO - 'LANGUAGE': 'en_US:en', 15:46:12 INFO - 'LOGNAME': 'cltbld', 15:46:12 INFO - 'MAIL': '/var/mail/cltbld', 15:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:12 INFO - 'MOZ_NO_REMOTE': '1', 15:46:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:12 INFO - 'NO_EM_RESTART': '1', 15:46:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:12 INFO - 'PWD': '/builds/slave/test', 15:46:12 INFO - 'SHELL': '/bin/bash', 15:46:12 INFO - 'SHLVL': '1', 15:46:12 INFO - 'TERM': 'linux', 15:46:12 INFO - 'TMOUT': '86400', 15:46:12 INFO - 'USER': 'cltbld', 15:46:12 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:12 INFO - '_': '/tools/buildbot/bin/python'} 15:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:13 INFO - Running setup.py (path:/tmp/pip-SG3j_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:13 INFO - Running setup.py (path:/tmp/pip-6v4C9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:13 INFO - Running setup.py (path:/tmp/pip-ujW32g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:13 INFO - Running setup.py (path:/tmp/pip-zSV42b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:13 INFO - Running setup.py (path:/tmp/pip-ZzCHAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:13 INFO - Running setup.py (path:/tmp/pip-rtiB5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:13 INFO - Running setup.py (path:/tmp/pip-DeP5we-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:13 INFO - Running setup.py (path:/tmp/pip-4U6uZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:13 INFO - Running setup.py (path:/tmp/pip-R0mvs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:13 INFO - Running setup.py (path:/tmp/pip-JNM8j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:13 INFO - Running setup.py (path:/tmp/pip-3xal91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:13 INFO - Running setup.py (path:/tmp/pip-FirIH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:13 INFO - Running setup.py (path:/tmp/pip-Lly89d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:13 INFO - Running setup.py (path:/tmp/pip-97DzC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:14 INFO - Running setup.py (path:/tmp/pip-kQ4Spc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:14 INFO - Running setup.py (path:/tmp/pip-vcTb11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:14 INFO - Running setup.py (path:/tmp/pip-VqL7AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:46:14 INFO - Running setup.py install for manifestparser 15:46:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:46:14 INFO - Running setup.py install for mozcrash 15:46:14 INFO - Running setup.py install for mozdebug 15:46:14 INFO - Running setup.py install for mozdevice 15:46:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:46:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:46:14 INFO - Running setup.py install for mozfile 15:46:14 INFO - Running setup.py install for mozhttpd 15:46:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:46:14 INFO - Running setup.py install for mozinfo 15:46:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:46:14 INFO - Running setup.py install for mozInstall 15:46:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Running setup.py install for mozleak 15:46:15 INFO - Running setup.py install for mozlog 15:46:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Running setup.py install for moznetwork 15:46:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Running setup.py install for mozprocess 15:46:15 INFO - Running setup.py install for mozprofile 15:46:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Running setup.py install for mozrunner 15:46:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:46:15 INFO - Running setup.py install for mozscreenshot 15:46:15 INFO - Running setup.py install for moztest 15:46:15 INFO - Running setup.py install for mozversion 15:46:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:46:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:46:16 INFO - Cleaning up... 15:46:16 INFO - Return code: 0 15:46:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46: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')]} 15:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:16 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:16 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 15:46:16 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 15:46:16 INFO - Using env: {'DISPLAY': ':0', 15:46:16 INFO - 'HOME': '/home/cltbld', 15:46:16 INFO - 'LANG': 'en_US.UTF-8', 15:46:16 INFO - 'LANGUAGE': 'en_US:en', 15:46:16 INFO - 'LOGNAME': 'cltbld', 15:46:16 INFO - 'MAIL': '/var/mail/cltbld', 15:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:16 INFO - 'MOZ_NO_REMOTE': '1', 15:46:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:16 INFO - 'NO_EM_RESTART': '1', 15:46:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:16 INFO - 'PWD': '/builds/slave/test', 15:46:16 INFO - 'SHELL': '/bin/bash', 15:46:16 INFO - 'SHLVL': '1', 15:46:16 INFO - 'TERM': 'linux', 15:46:16 INFO - 'TMOUT': '86400', 15:46:16 INFO - 'USER': 'cltbld', 15:46:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:16 INFO - '_': '/tools/buildbot/bin/python'} 15:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:16 INFO - Running setup.py (path:/tmp/pip-TqRUvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:16 INFO - Running setup.py (path:/tmp/pip-XOFAYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:16 INFO - Running setup.py (path:/tmp/pip-HLsVrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:16 INFO - Running setup.py (path:/tmp/pip-x46zNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:16 INFO - Running setup.py (path:/tmp/pip-XrczFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:16 INFO - Running setup.py (path:/tmp/pip-nxwOlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:16 INFO - Running setup.py (path:/tmp/pip-9vhSR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:16 INFO - Running setup.py (path:/tmp/pip-NGZAud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:16 INFO - Running setup.py (path:/tmp/pip-nfX6cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:16 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)) 15:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:16 INFO - Running setup.py (path:/tmp/pip-hkHrL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:17 INFO - Running setup.py (path:/tmp/pip-qekx6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:17 INFO - Running setup.py (path:/tmp/pip-ZsQRHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:17 INFO - Running setup.py (path:/tmp/pip-0IOn6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:17 INFO - Running setup.py (path:/tmp/pip-K4y2Pr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:17 INFO - Running setup.py (path:/tmp/pip-ko1Ft8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:17 INFO - Running setup.py (path:/tmp/pip-NwiNJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:17 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)) 15:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:17 INFO - Running setup.py (path:/tmp/pip-jWfcaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:17 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)) 15:46:17 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)) 15:46:17 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)) 15:46:17 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)) 15:46:17 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)) 15:46:17 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)) 15:46:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:46:17 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 15:46:17 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 15:46:17 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 15:46:17 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 15:46:17 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 15:46:17 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 15:46:19 INFO - Downloading blessings-1.6.tar.gz 15:46:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:46:19 INFO - Installing collected packages: blessings 15:46:19 INFO - Running setup.py install for blessings 15:46:19 INFO - Successfully installed blessings 15:46:19 INFO - Cleaning up... 15:46:19 INFO - Return code: 0 15:46:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:46:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:19 INFO - Reading from file tmpfile_stdout 15:46:19 INFO - Current package versions: 15:46:19 INFO - argparse == 1.2.1 15:46:19 INFO - blessings == 1.6 15:46:19 INFO - blobuploader == 1.2.4 15:46:19 INFO - docopt == 0.6.1 15:46:19 INFO - manifestparser == 1.1 15:46:19 INFO - mozInstall == 1.12 15:46:19 INFO - mozcrash == 0.17 15:46:19 INFO - mozdebug == 0.1 15:46:19 INFO - mozdevice == 0.48 15:46:19 INFO - mozfile == 1.2 15:46:19 INFO - mozhttpd == 0.7 15:46:19 INFO - mozinfo == 0.9 15:46:19 INFO - mozleak == 0.1 15:46:19 INFO - mozlog == 3.1 15:46:19 INFO - moznetwork == 0.27 15:46:19 INFO - mozprocess == 0.22 15:46:19 INFO - mozprofile == 0.28 15:46:19 INFO - mozrunner == 6.11 15:46:19 INFO - mozscreenshot == 0.1 15:46:19 INFO - mozsystemmonitor == 0.0 15:46:19 INFO - moztest == 0.7 15:46:19 INFO - mozversion == 1.4 15:46:19 INFO - psutil == 3.1.1 15:46:19 INFO - requests == 1.2.3 15:46:19 INFO - wsgiref == 0.1.2 15:46:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46: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')]} 15:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46: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')]} 15:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:19 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:19 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 15:46:19 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 15:46:19 INFO - Using env: {'DISPLAY': ':0', 15:46:19 INFO - 'HOME': '/home/cltbld', 15:46:19 INFO - 'LANG': 'en_US.UTF-8', 15:46:19 INFO - 'LANGUAGE': 'en_US:en', 15:46:19 INFO - 'LOGNAME': 'cltbld', 15:46:19 INFO - 'MAIL': '/var/mail/cltbld', 15:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:19 INFO - 'MOZ_NO_REMOTE': '1', 15:46:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:19 INFO - 'NO_EM_RESTART': '1', 15:46:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:19 INFO - 'PWD': '/builds/slave/test', 15:46:19 INFO - 'SHELL': '/bin/bash', 15:46:19 INFO - 'SHLVL': '1', 15:46:19 INFO - 'TERM': 'linux', 15:46:19 INFO - 'TMOUT': '86400', 15:46:19 INFO - 'USER': 'cltbld', 15:46:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:19 INFO - '_': '/tools/buildbot/bin/python'} 15:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 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)) 15:46:20 INFO - Cleaning up... 15:46:20 INFO - Return code: 0 15:46:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:20 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:20 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 15:46:20 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 15:46:20 INFO - Using env: {'DISPLAY': ':0', 15:46:20 INFO - 'HOME': '/home/cltbld', 15:46:20 INFO - 'LANG': 'en_US.UTF-8', 15:46:20 INFO - 'LANGUAGE': 'en_US:en', 15:46:20 INFO - 'LOGNAME': 'cltbld', 15:46:20 INFO - 'MAIL': '/var/mail/cltbld', 15:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:20 INFO - 'MOZ_NO_REMOTE': '1', 15:46:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:20 INFO - 'NO_EM_RESTART': '1', 15:46:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:20 INFO - 'PWD': '/builds/slave/test', 15:46:20 INFO - 'SHELL': '/bin/bash', 15:46:20 INFO - 'SHLVL': '1', 15:46:20 INFO - 'TERM': 'linux', 15:46:20 INFO - 'TMOUT': '86400', 15:46:20 INFO - 'USER': 'cltbld', 15:46:20 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:20 INFO - '_': '/tools/buildbot/bin/python'} 15:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:20 INFO - Downloading/unpacking jsonschema 15:46:20 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 15:46:20 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 15:46:20 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 15:46:20 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 15:46:20 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 15:46:20 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 15:46:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:46:22 INFO - Downloading/unpacking functools32 (from jsonschema) 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:46:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:46:22 INFO - warning: no files found matching '*.txt' 15:46:22 INFO - no previously-included directories found matching 'build' 15:46:22 INFO - no previously-included directories found matching 'dist' 15:46:22 INFO - no previously-included directories found matching '.git*' 15:46:22 INFO - Installing collected packages: jsonschema, functools32 15:46:22 INFO - Running setup.py install for functools32 15:46:22 INFO - warning: no files found matching '*.txt' 15:46:22 INFO - no previously-included directories found matching 'build' 15:46:22 INFO - no previously-included directories found matching 'dist' 15:46:22 INFO - no previously-included directories found matching '.git*' 15:46:22 INFO - Successfully installed jsonschema functools32 15:46:22 INFO - Cleaning up... 15:46:22 INFO - Return code: 0 15:46:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:46:22 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')]} 15:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:22 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')]} 15:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:46:22 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 0x7f1663a01f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcab8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd778e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, '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': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', '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 15:46:22 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 15:46:22 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 15:46:22 INFO - Using env: {'DISPLAY': ':0', 15:46:22 INFO - 'HOME': '/home/cltbld', 15:46:22 INFO - 'LANG': 'en_US.UTF-8', 15:46:22 INFO - 'LANGUAGE': 'en_US:en', 15:46:22 INFO - 'LOGNAME': 'cltbld', 15:46:22 INFO - 'MAIL': '/var/mail/cltbld', 15:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:22 INFO - 'MOZ_NO_REMOTE': '1', 15:46:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:22 INFO - 'NO_EM_RESTART': '1', 15:46:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:22 INFO - 'PWD': '/builds/slave/test', 15:46:22 INFO - 'SHELL': '/bin/bash', 15:46:22 INFO - 'SHLVL': '1', 15:46:22 INFO - 'TERM': 'linux', 15:46:22 INFO - 'TMOUT': '86400', 15:46:22 INFO - 'USER': 'cltbld', 15:46:22 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:22 INFO - '_': '/tools/buildbot/bin/python'} 15:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:22 INFO - Downloading/unpacking flake8 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 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 15:46:22 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 15:46:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:46:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:46:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:24 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 15:46:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:46: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 15:46: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 15:46: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 15:46: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 15:46: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 15:46: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 15:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:46:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:46:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:46:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:46:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:46:25 INFO - no previously-included directories found matching 'docs/_build' 15:46:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:46:25 INFO - Running setup.py install for pep8 15:46:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:46:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:46:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:46:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:46:25 INFO - no previously-included directories found matching 'docs/_build' 15:46:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:46:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:46:25 INFO - Cleaning up... 15:46:25 INFO - Return code: 0 15:46:25 INFO - Running post-action listener: _resource_record_post_action 15:46:25 INFO - Running post-action listener: _start_resource_monitoring 15:46:25 INFO - Starting resource monitoring. 15:46:25 INFO - ##### 15:46:25 INFO - ##### Finished create-virtualenv step (success) 15:46:25 INFO - ##### 15:46:25 INFO - ##### 15:46:25 INFO - ##### Running install step. 15:46:25 INFO - ##### 15:46:25 INFO - Running pre-action listener: _resource_record_pre_action 15:46:25 INFO - Running main action method: install 15:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:25 INFO - Reading from file tmpfile_stdout 15:46:25 INFO - Detecting whether we're running mozinstall >=1.0... 15:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:46:26 INFO - Reading from file tmpfile_stdout 15:46:26 INFO - Output received: 15:46:26 INFO - Usage: mozinstall [options] installer 15:46:26 INFO - Options: 15:46:26 INFO - -h, --help show this help message and exit 15:46:26 INFO - -d DEST, --destination=DEST 15:46:26 INFO - Directory to install application into. [default: 15:46:26 INFO - "/builds/slave/test"] 15:46:26 INFO - --app=APP Application being installed. [default: firefox] 15:46:26 INFO - mkdir: /builds/slave/test/build/application 15:46:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:46:41 INFO - Reading from file tmpfile_stdout 15:46:41 INFO - Output received: 15:46:41 INFO - /builds/slave/test/build/application/firefox/firefox 15:46:41 INFO - Running post-action listener: _resource_record_post_action 15:46:41 INFO - ##### 15:46:41 INFO - ##### Finished install step (success) 15:46:41 INFO - ##### 15:46:41 INFO - ##### 15:46:41 INFO - ##### Running run-tests step. 15:46:41 INFO - ##### 15:46:41 INFO - Running pre-action listener: _resource_record_pre_action 15:46:41 INFO - Running main action method: run_tests 15:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:46:41 INFO - Python 2.7.3 15:46:41 INFO - Return code: 0 15:46:41 INFO - grabbing minidump binary from tooltool 15:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:46:41 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 0xe06b20>, '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 0xe07a20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe07eb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:46:41 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 15:46:41 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 15:46:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:46:41 INFO - Return code: 0 15:46:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:46:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:46:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:46:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:46:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:46:42 INFO - Return code: 0 15:46:42 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/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 15:46:42 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/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 15:46:42 INFO - Using env: {'DISPLAY': ':0', 15:46:42 INFO - 'HOME': '/home/cltbld', 15:46:42 INFO - 'LANG': 'en_US.UTF-8', 15:46:42 INFO - 'LANGUAGE': 'en_US:en', 15:46:42 INFO - 'LOGNAME': 'cltbld', 15:46:42 INFO - 'MAIL': '/var/mail/cltbld', 15:46:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:46:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:42 INFO - 'MOZ_NO_REMOTE': '1', 15:46:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:46:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:42 INFO - 'NO_EM_RESTART': '1', 15:46:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:42 INFO - 'PWD': '/builds/slave/test', 15:46:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:46:42 INFO - 'SHELL': '/bin/bash', 15:46:42 INFO - 'SHLVL': '1', 15:46:42 INFO - 'TERM': 'linux', 15:46:42 INFO - 'TMOUT': '86400', 15:46:42 INFO - 'USER': 'cltbld', 15:46:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979', 15:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:42 INFO - '_': '/tools/buildbot/bin/python'} 15:46:42 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/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 15:46:43 INFO - mozversion application_buildid: 20160509152513 15:46:43 INFO - mozversion application_changeset: c94c82ce23659b4132ca50930ab124212aab3044 15:46:43 INFO - mozversion application_display_name: Nightly 15:46:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:46:43 INFO - mozversion application_name: Firefox 15:46:43 INFO - mozversion application_remotingname: firefox 15:46:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:46:43 INFO - mozversion application_vendor: Mozilla 15:46:43 INFO - mozversion application_version: 49.0a1 15:46:43 INFO - mozversion platform_buildid: 20160509152513 15:46:43 INFO - mozversion platform_changeset: c94c82ce23659b4132ca50930ab124212aab3044 15:46:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:46:43 INFO - mozversion platform_version: 49.0a1 15:46:43 INFO - using testdate: 1462834003 15:46:43 INFO - actual date: 1462834003 15:46:43 INFO - starting webserver on 'localhost:33323' 15:46:43 INFO - SUITE-START | Running 2 tests 15:46:43 INFO - TEST-START | dromaeo_css 15:46:43 INFO - operating with platform_type : linux_ 15:46:43 INFO - Initialising browser for dromaeo_css test... 15:46:43 INFO - TEST-INFO | started process 29692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoFNkRB/profile http://localhost:33323/getInfo.html) 15:46:47 INFO - PROCESS | 29692 | __metrics Screen width/height:1600/1200 15:46:47 INFO - PROCESS | 29692 | colorDepth:24 15:46:47 INFO - PROCESS | 29692 | Browser inner width/height: 1024/697 15:46:47 INFO - PROCESS | 29692 | __metrics 15:46:48 ERROR - PROCESS | 29692 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:46:48 INFO - TEST-INFO | 29692: exit 0 15:46:48 INFO - Browser initialized. 15:46:48 INFO - Running cycle 1/1 for dromaeo_css test... 15:46:48 INFO - TEST-INFO | started process 29778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoFNkRB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:00 INFO - PROCESS | 29778 | RSS: Main: 153919488 15:47:00 INFO - PROCESS | 29778 | 15:47:52 INFO - PROCESS | 29778 | 1462834072339 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] 15:50:03 INFO - PROCESS | 29778 | Cycle 1(1): loaded http://localhost:33323/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33323/tests/dromaeo/cssquery-ext.html) 15:50:04 INFO - PROCESS | 29778 | RSS: Main: 232583168 15:50:04 INFO - PROCESS | 29778 | 15:53:07 INFO - PROCESS | 29778 | Cycle 1(1): loaded http://localhost:33323/tests/dromaeo/cssquery-ext.html (next: http://localhost:33323/tests/dromaeo/cssquery-jquery.html) 15:53:07 INFO - PROCESS | 29778 | RSS: Main: 236220416 15:53:07 INFO - PROCESS | 29778 | 15:56:10 INFO - PROCESS | 29778 | Cycle 1(1): loaded http://localhost:33323/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33323/tests/dromaeo/cssquery-mootools.html) 15:56:10 INFO - PROCESS | 29778 | RSS: Main: 238166016 15:56:10 INFO - PROCESS | 29778 | 15:59:12 INFO - PROCESS | 29778 | Cycle 1(1): loaded http://localhost:33323/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33323/tests/dromaeo/cssquery-prototype.html) 15:59:13 INFO - PROCESS | 29778 | RSS: Main: 232009728 15:59:13 INFO - PROCESS | 29778 | 16:02:16 INFO - PROCESS | 29778 | Cycle 1(1): loaded http://localhost:33323/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33323/tests/dromaeo/cssquery-yui.html) 16:02:16 INFO - PROCESS | 29778 | RSS: Main: 209838080 16:02:16 INFO - PROCESS | 29778 | 16:05:19 INFO - PROCESS | 29778 | Cycle 1(1): loaded http://localhost:33323/tests/dromaeo/cssquery-yui.html (next: http://localhost:33323/tests/dromaeo/cssquery-dojo.html) 16:05:19 INFO - PROCESS | 29778 | RSS: Main: 309862400 16:05:19 INFO - PROCESS | 29778 | 16:05:19 INFO - PROCESS | 29778 | __start_tp_report 16:05:19 INFO - PROCESS | 29778 | _x_x_mozilla_page_load 16:05:19 INFO - PROCESS | 29778 | _x_x_mozilla_page_load_details 16:05:19 INFO - PROCESS | 29778 | |i|pagename|runs| 16:05:19 INFO - PROCESS | 29778 | |0;dojo.html;2623.134365659332;2666.9199924002287;2695.326168457886;2697.3930865555253;2705.7700095491896;4591.311303304506;4638.304254361848;4641.2341963576055;4759.619230461568;4796.448408433032;4688.343631891538;4708.7645617719145;4729.64527660426;4757.001029783741;4778.617710583154;4368.432103826506;4391.67062470316;4459.710118842281;4483.529229430896;4574.268117101265;4470.26240670289;4488.7531185784765;4524.886877828048;4533.093381323732;4550;4242.024334403089;4276.615104640598;4303;4328.956710432887;4336.978315108436;4367.825286988516;4371.78141092944;4387.341898715186;4420.115976804652;4420.226460369422;4382.342648602703;4394.055278115221;4394.6923715339935;4408.911821763547;4449.065696203768;4490.259107247301;4623.283391074355;4652.116097941414;4704.458987216445;4714.952850471485;5131.897362052775;5139.665921715076;5198;5198.766055527511;5202.427732949411;5196.714180720059;5203.765830537635;5219.791208351661;5245.26566280721;5246.134387826004;4672.15901137795;4730.408698912636;4776.713397196179;4788.425388953278;4843.346148269939;5253.684778913277;5256.237845512455;5276.788928442896;5298.92051619226;5302.893942121136;4773.522647735199;5018.071656743514;5020.523050310215;5063.924041139386;5072.644914855999;5098.821541246039;5120.692758434506;5121.28302037715;5126.794928202905;5156.690598564098;5108;5108.233764935289;5108.6168537359845;5116.744162791845;5166.9224961625605;4797.160496913027;5078;5095.87260318495;5096.566791822736;5102.413222479388;4400.933985990213;4407;4423.756693381831;4463.754493502824;4467.977660111744;3830.71269654777;3840.0399900024995;3851.768893866377;3855.652991230803;3880.902977425587;4128.3601041838565;4167.958320416757;4284.250256205151;4387.956120438755;4424.579664931826;5059.418166910852;5069.898602028013;5072.264521644414;5085.771140298695;5135.974320128375;4302.118065796504;4309.633681137076;4315.935260971088;4368.301071828492;4369.541198174146;4505.414296141481;4539.978504836386;4555.840545580889;4602.102589994919;4602.792874320664;4729.243321068612;4733.9763301183275;4751.857444276678;4767.880802979815;4802.471728109905;5044.192929131322;5044.243363495505;5044.9243261350375;5053.0399224147295;5101.897962040813;4591.632669386374;4708.246680531099;4788.880277993078;4800.6159507238635;4803.567678908915;5049.2426136079885;5066.594672426123;5071.13790655582;5079.847604571869;5122.078025955215;5138.152204886152;5143.61422893277;5155.200943853709;5156.04613146569;5184.45563215872;4780.5219478053305;4965.751712414437;4968.850934471971;4985.227289770091;5015.523525265094;4971.527704868032;4998.350214472095;5007.824726134568;5010.348654674869;5061.240813877947;4926.5073492650445;4945.8021679132435;4967.05625931072;4993.251012348177;5010;4825.686330388514;4955.950440495549;4979.178435558238;5014.87462813418;5035.899282014412;4880.902381952412;4916.778744956414;4939.135651261086;4945.455999840086;4992.350994370709 16:05:19 INFO - PROCESS | 29778 | |1;ext.html;574.3395095640013;592.1827877529009;599.9700014999252;600.4895838537246;609.1411110334428;42659.573404265946;44169.55830441695;44250.55749442505;44284.55715442845;49011;43083.138337233235;43921;43932;44040.55959440405;44267;7719.459637825355;7925.484843485155;7933.920660793405;7934.920650793505;7938.126806051329;7892.289693927545;7899.881501777478;7978.9202107979045;7981.561014144219;8001.319887809544;8010.038795344567;8052.919470805305;8057.798555036142;8057.95971020144;8077.434579579432;7966.800829979239;8011.639476223584;8014.038315402131;8017.478863873888;8018.759437216893;7885.921140788576;7979.56112413817;7983.321417679446;7983.361331093499;7987.081485629169;39645.80177099096;40079;40136.187062229044;40252.59747402518;40253.597464025275;3134.09111357706;3210.855511501965;3253.6322620396254;3259.837008149583;3306.6197387300526;29572;30017.284329699854;30124.69875301241;30722.078337649906;31263.687363126533;3202.6156861176573;3214.341060082701;3224.1778346521724;3296.5549650797075;3299.868005279786;242614;243021;244410;244584;245301;34347.4847877282;56076.43923560712;56848.431515684315;56851.43148568544;57019.71490142606;34404.48393274103;36344.45483317752;36378;36388.81805590955;36410.089747755985;13592.388342524413;13810.37853296584;13850.65373365674;13863.168209907439;13872.4451021958;11069.889301107047;11261.493232804543;11295.774084518427;11296.717582060513;11315.773684526263;8446.831063378699;8460.492370457792;8470.872936905951;8516.74449766508;8545.572721363907;3231.079142444392;3250.5611742414944;3260.233845046412;3286.4248756467523;3306.470964645693;908.4867050116663;930.7829615007919;1024.3724845770575;1028.4857571214393;1040.5109598488698;28570.428591428055;28965;29172.562411563842;29177;29674.25814354615;998.201438848918;1004.2668851738126;1008.7679833638305;1009.5154325923597;1054.825953717628;15589.766153507706;15916.840831591768;15918.363265469252;16078.276477558538;16953.40663076811;372841;378886;379182;379296;381677;53775.46224537783;54248.728756355966;54462;54506;54516;1150.4880328253846;1253.2104773992326;1267.9556215532411;1276.8659290774522;1307.712303293293;28783.712162878102;29807.70192298136;29959;30641.387172256877;30835.84582077075;43801.78099109484;50298;51849;51936;57723;338395;342539;342873;344137;347627;33843;38978.61021389863;39101.8044909785;39990.600093998684;40252.79873600613;43717;43838.56161438345;43875.78062109669;43987;44481.55518444903;37130.44304335491;37429.77106867874;37664.43503347507;38310.80844595759;38364.80817595894;37840.62159378481;38133.237335253696;38359.42460863033;38371.80814096023;38837;36641.633583663825;37029.629703702616;37854.43218351782;37917.24165516619;37998.43002355022;31749.682503175598;34162.82918585391;34523.13692157716;34592.65407345894;34719.4792078114;26050.34874128162;26260.73739262583;26346.20961371162;26387.20838374852;26863.462730744886 16:05:20 INFO - PROCESS | 29778 | |2;jquery.html;373.8504598160735;374.1058869302368;378.5797764481425;391.1024199462234;399.0741479766942;16001.83998160018;16738.414155504546;17378.47864564062;17582;17640.294388224473;11180.161487888421;11759.823602645945;11761.235519691214;11771.529138834436;11779.23434976726;4302.870913872589;4799.976000119995;4816.494268101851;4990.525900039491;4992.625553083515;4877;4924.4583095859425;4930.4330002049865;4932.926006109911;4938.7530623468865;4936;4936.851894443173;5173.948260517384;5321.654092483996;5324.041672498949;5223.921641175385;5225.634205605609;5238.161894096946;5242.554382877441;5244.527992480657;4578.587927086578;4707.8587642370785;4791.520847915215;4839.128956787777;4840.5159484051665;12766.744665106646;12853.550125745554;12936.676583085498;13036.608901732963;13074.803877941838;1935.3516571948414;1968.9606207875906;2162.4918144236094;2389.629607410854;2487.1543675150538;9547.42715437076;9866.802663946753;9916.157126644244;11407.54369825206;13259.602211933561;1726.2404542001605;1806.2142967809045;1880.1539307311627;1886.1889387563342;1891.224597914862;67619;69976;70838;72089.63955180244;72501.6374918122;10319.1744660427;10355.94822025885;10391.324563903323;10602.522886470128;10643;14677.266136693337;14742;14778.891583131308;14814.185219813015;14821;7199.388052015637;7399.371053460516;7408.481406301615;7511.474196806171;7543.924560754432;6863.210730765926;6989.586620384967;7052.823679407953;7112.28877112295;7117.64411779409;4448.733076015449;4560.863174104782;4561.954380456153;4626.930596041062;4707.952920470751;1886.2172198537453;1888.977635782764;1897.724829899657;1937.8546609004381;1951.6682164031865;790.5928446849877;836.2807985132781;872.9869051964225;922.8846394200725;951.9190868776232;16686.666266674598;17072.402465913634;17097.401590944257;17224.569385765204;17439.65120697579;902.9458232505939;908.8136931928939;910.2217603948661;956.1299217711988;960.9615615375446;9338.48638324885;9408.153266206074;9567.952160239154;9711.223102151811;9801.362911410735;411900;412226;416434;417693;420931;77392.61303693446;77579;77629.6118519415;77966;80824.9793755149;920.9079092090738;933.9719808405758;939.1219210038636;940.9858852117223;970.7961328121032;15034.548963531111;15038.473653422077;15182.620434489227;15365.462208822637;15603.375864965274;34388.82805585956;34518.48222276718;35259.47110793289;35362.29275414529;36496.27007459783;48234.27648585204;48764.51235487741;48874.266885996025;49191;49401.50598493969;7422.925770742439;7431.219721929336;7461.104667439725;7634.6182690864125;7753.728619498291;11198.664040078811;11740.236884602473;11762.94118529402;11779.116566257393;12059.276443413422;10283.94858025705;10510.47447627631;10586.682399528027;10595.15238780911;10749.355038697704;10267.794644107225;10300.124489418484;10464.94767526188;10524.210684198868;10541.367517948947;10080.092791648787;10320.690379288333;10468.842967355344;10579.576816927236;11311.830322545004;6719.227288861844;6967.895481567882;7087.397571206299;8005.639746211551;8278.420510564203;10181.440020798927;10476.109530689671;10476.476176191312;10559.419231942316;10859.891401086204 16:05:20 INFO - PROCESS | 29778 | |3;mootools.html;1256.214865708932;1458.1469840143516;1483.9851601483983;1614.6205641674205;1806.1962426720106;6286.779962701306;6916.100906882105;7109.680064397102;7127.572345659264;7299.1605965314;7015.649217539129;7166.319199676022;7178.102737157856;7187.712491500333;7190.532615379997;2146.9355919322447;2174.0760176924823;2179.8147157491553;2181.814545763612;2182.9235976740815;2138.8823614701187;2162.664786958016;2185.6830759539857;2188.146622817095;2190.87950162741;2127.361791462555;2133.829293656504;2145.4314606629255;2155.752088509818;2171.4679903423603;2079.4489460292884;2081.9687704684443;2084.9478763030893;2088.5509615432757;2090.049027692404;2041.6835390514493;2091.9477013074643;2092.6233278009954;2094.3298144593737;2098.0663604695915;7116.822079448003;7146.964265178693;7172.46206534512;7178.389836863873;7352.889706654404;4468.597826195625;4571.200039993021;4616.8614941551805;4635.397398338195;4644.605208557243;2959.067893613505;2969.792114551982;3031.166429231957;3041.9391612167633;3053;947.6304241345881;994.8607194992707;997.1474389397033;997.276974193711;1030.9381437113798;9380.296477764196;9400.529973501298;9480.90519094814;9488.335816492849;9491.240700743992;4120.464339635888;4184.4141820145205;4188.8324467020975;4192.413062171298;4312.956870431318;7724.382049436032;7766.689332426753;7787.532748035136;7791.454598178073;7794.493357931717;4532.546745325507;4537.546245375502;4556.658250631216;4559.840405585788;4563.406757121553;3666.7799932004164;4002.799860006988;4096.754194748325;4253.680973926967;4277.871663850089;3356.63077061523;3370.376480351143;3440.518327434161;3454.6199918008997;3489.180042689966;1041.1306559023149;1050.3382868792612;1054.878688950765;1055.6094245129198;1079.379356869803;795.129333379948;857.5326447086353;886.8004698942815;889.5241046040467;892.2817132208635;5065.493450654905;5066.746662666852;5082.186850103965;5096.210087436453;5146.974265128651;425.987220383389;431.3378963291333;435.29047652326506;436.4130244820711;436.57434001848577;3403.9489407658907;3496.5629296337956;3509.2630547585295;3509.5613048368955;3579.069441945113;8784.956075219583;9090.954545227232;9192.49441280736;9206.033366496555;9213;9151;9159.313051521163;9217.76955576116;9243.491607961492;9247.44515329069;6989.126359205201;7030.753923612649;7044.894326585106;7183.173934997438;7200.567965921957;5043.142665746906;5049.6465247432325;5147.356580427447;5215.113430716711;5263.421023687315;8346.998360196816;8424.241818236389;8496.405251632574;8574.142585741376;8618.827623447622;8494.70268540598;8665.870011949699;8684;8758.912410876064;8827.955860220658;5545.445455454584;5961.254843144607;6104.38956104386;6161.907571386343;6174;7025.508214424941;7187.209406965334;7262.164851041986;7266;7275.636218189175;6359.90460143089;6513.8697226056165;6528.086067950586;6591;6687.264400915798;6231.781887633911;6265.906011409741;6324.019776934582;6350.904736428864;6513.5114866384265;6086.3000754913755;6106.32830388648;6144.600600960924;6147.72335244924;6154.815355539341;4392.3191905254735;4421.823127074881;4460.487043989982;4475.776211189493;4579.977100114478;4423.77881105937;4511.210538155875;4526.434195725535;4539.97730011361;4556.589906908394 16:05:20 INFO - PROCESS | 29778 | |4;prototype.html;309.185296743082;314.6837428384473;318.56993059369853;319.0699112088262;319.8272932616387;4733.4793172751015;4747.1692453820615;4783.521647835215;4805.759712014403;4816.97591512042;4704.694194877331;4719.693219940701;4727.314539391795;4727.808729107847;4731.053789242148;3614.638536146384;3689.2990331836863;3710.2764960832646;3779.79211143387;3820.2168555446065;3769.302679004379;3781.3382658034734;3804.9239015219678;3815.313243616148;3826.8469261229657;3578.874739384109;3744.213715119828;3752.512173417452;3761.661450469457;3773.471713960048;3646.6171052039554;3758.962410375889;3767.3595488766973;3770.2845205431463;3772.320982223199;3359.538600708675;3361.310931259086;3361.9159521011925;3368.2421455172635;3372.915677108092;4443.733375997451;4483.439570053743;4523.253663145577;4529.796159172845;4535.319702044687;3985.143194213229;4214.557471465513;4240.978795106035;4281.250781113293;4282.2656823986335;4870.391201099863;4900.509949005071;4916.360873086476;4937.432195297515;4941.851744447672;3525.171584677624;3533.886825649912;3567.23304489536;3571.821408929543;3573.5711714594413;5023.196288593844;5092.668976516515;5108.335916330853;5148.742562871879;5149.433562308143;3602.1715005548344;3604.279144171176;3642.5082613846794;3646.7082633389264;3648.835802388899;3650.8539658413897;3660.5973342932257;3680.5031320771886;3712.5916149223563;3716.9442458363146;4212.157568486315;4238.682098842599;4240.257954857949;4242.596953289432;4258.169656916872;4876.29293752411;4965.702057876539;4997.575206107522;5018.824341148043;5021.548060674557;1236.486857953957;1249.119370843553;1256.597888675615;1258.1947553565726;1259.4269607328642;805.7904944714417;816.8407160603745;819.0539926385035;821.4250024982537;858.8411143938391;4188.455500784939;4188.497380314321;4202.243596152661;4205.74765514064;4220.488809158545;4897.608191344684;4906.288588154697;4913.385826771653;4941.579965702884;4942.308076869242;4074.6740260779065;4135.648469880034;4145.523264824584;4234.894127646772;4270.679699022586;2686.965518275439;2698.7031426543062;2706.174616741913;2708.5915324031394;2710.9593356099676;4796.5203479652455;4804.711717296974;4807.711537307773;4823.614110871122;4848;4909.558139767438;5187.688738675692;5191.792328306826;5198.674338043775;5200.609954253446;4776.426828780499;4784.7607619619575;4803.046126632096;4806.687565308243;4813.278008298783;5039.798408063636;5074.644774865724;5074.923876141935;5089.211172268304;5090.142098134228;4779.283107533759;4965.379327584052;4976.402831660224;4978.726170060537;5059.367579052619;4847.175980083465;4871.3667223260745;4876.926846097241;4884.633652476007;4910.656254062182;3633.6373859802575;3656.634336566322;3658.8353524092013;3661.9816900915325;3672.4491326301268;5008.899822003613;5146.202338637517;5154.737089413118;5172.974135129451;5182.585393168462;4969.4782047884455;4973.950260497349;5001.52455025761;5005.4744251853035;5012.498750124958;4968.751562421937;4980.800767969241;5003.249512573143;5012.398512178416;5024.196128619404;4759.405074365704;4917.901641967069;4922.360093187863;4933.777979990838;4952.851414457572;4840.370751802243;4868.2940973557925;5022.547970682656;5043.87390315245;5058.468860769566;4905.583025442877;4918.655694101379;4919.557239848431;4942.703437793743;4967.8886473248 16:05:20 INFO - PROCESS | 29778 | |5;yui.html;692.7229108356655;747.8055705516566;798.3214267872313;800.0679208721835;815.160384803652;10449.895501044997;10596.099331556827;10600.151987840973;10616.363018218912;11207;11840.289582625048;12026.338551379671;12065.698357541065;12119.454624541895;12348.629541113782;665.8867992441296;858.772425307291;861.2808305065278;874.6545114679698;886.7738726624701;881.6209030117047;883.4875772052194;883.6686242659002;885.8272636835819;886.5478605911;928.4661319741176;930.4091901642438;930.7812664023949;931.417863835103;931.6040682709855;692.8094773937182;889.675268526983;912.2246090822814;913.9131782480654;921.617528725578;677.461418172554;677.8474843160299;677.8745932002595;678.1116737074449;678.8404724889647;11130.38782866942;11176.273542219811;11219.102471802236;11231.045361144312;11271.32372057671;11853;12193.817092743528;12339.753204935942;12340.81488777669;12875.356232188447;10594.735131621694;10655.520501577448;10733.248672592921;10772.08437282832;11047.171462140372;4031.354983202703;4094.733842300241;4118.279301122292;4226.260404429243;4235.449391579075;41174.588254117676;41372.37941430881;41459.792701036604;41468.585314146774;41672.374914376305;9931.50342482873;9947.20422366209;10041;10156.23828212887;10247.231457640706;16732.163391830603;16761.497155085366;16810.243539040526;16840.57898552546;17146.314147434212;6209.627422354673;6243.188385509855;6492.448141907991;6560.343965603402;6588.769393071219;3458.9654103458643;3484.77348972316;3530.6469353065004;3535.3636345458062;3603.6936860367164;2695.9326016849736;2786.205931309567;2787.6515435570554;2790.6511686039244;2790.944181116407;467.05571449111926;487.54171079185454;519.7167543688698;520.3703518742365;527.7018484556213;562.8733534954603;644.3620815392821;645.5416654175494;645.9806205813833;667.1727058447588;7994.6002699864775;8245.876311855327;8266.380021498411;8284;8330.66677332912;588.2470437839572;655.2431941108027;656.3174298729364;661.7915356662636;674.8616533610598;3624.4563315502887;3669.211119609297;3765.4540091686554;3831.712621553339;3847.4228865670375;55148.72425637846;55936.440635593935;56719.716401418555;56909.14636280459;56996;50894.745526272134;51123.74438127786;52763;53324.73337633287;53923.73038134784;45870.31194532085;46384.304235436495;46458;46475.30287045697;47277;7330.560166390033;7570.659320330488;7584.696612135453;7608.391328693802;7793.961030194812;29465.410691786474;30488.237794055327;30647.54028689616;30788.384232315675;31292.68707312898;39621.60378396179;40054;40498;41126.1774764509;41452.378214327415;9524.14282714559;9825.508724563886;9896.059874311928;9935.552900119368;10236.48817559134;10259.948700256451;10490.160787136858;10508.894911050791;10627.734306642396;11062.778744425228;9796.069373409515;9997.200223982209;10208.642697505551;10372.066514013775;10478.266521343432;9227.630894764134;9333;9507.857382139135;9529.740582605522;9734.659286924923;9314.264027325484;9392.483413412328;9446.102620251066;9581.808363832544;9595.952020239854;8113.269805717514;8122.796930076795;8316.085230624505;8613.31093512505;8786.384953053224;8040.638171282189;8399;8404.159584041547;8512.233898949124;8578.871316930376 16:05:20 INFO - PROCESS | 29778 | __end_tp_report 16:05:20 INFO - PROCESS | 29778 | __start_cc_report 16:05:20 INFO - PROCESS | 29778 | _x_x_mozilla_cycle_collect,1071 16:05:20 INFO - PROCESS | 29778 | __end_cc_report 16:05:20 INFO - PROCESS | 29778 | __startTimestamp1462835119990__endTimestamp 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | ------- Summary: start ------- 16:05:20 INFO - PROCESS | 29778 | Number of tests: 6 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | [#0] dojo.html Cycles:165 Average:4716.74 Median:4796.45 stddev:497.36 (10.4%) stddev-sans-first:470.98 16:05:20 INFO - PROCESS | 29778 | Values: 2623.1 2666.9 2695.3 2697.4 2705.8 4591.3 4638.3 4641.2 4759.6 4796.4 4688.3 4708.8 4729.6 4757.0 4778.6 4368.4 4391.7 4459.7 4483.5 4574.3 4470.3 4488.8 4524.9 4533.1 4550.0 4242.0 4276.6 4303.0 4329.0 4337.0 4367.8 4371.8 4387.3 4420.1 4420.2 4382.3 4394.1 4394.7 4408.9 4449.1 4490.3 4623.3 4652.1 4704.5 4715.0 5131.9 5139.7 5198.0 5198.8 5202.4 5196.7 5203.8 5219.8 5245.3 5246.1 4672.2 4730.4 4776.7 4788.4 4843.3 5253.7 5256.2 5276.8 5298.9 5302.9 4773.5 5018.1 5020.5 5063.9 5072.6 5098.8 5120.7 5121.3 5126.8 5156.7 5108.0 5108.2 5108.6 5116.7 5166.9 4797.2 5078.0 5095.9 5096.6 5102.4 4400.9 4407.0 4423.8 4463.8 4468.0 3830.7 3840.0 3851.8 3855.7 3880.9 4128.4 4168.0 4284.3 4388.0 4424.6 5059.4 5069.9 5072.3 5085.8 5136.0 4302.1 4309.6 4315.9 4368.3 4369.5 4505.4 4540.0 4555.8 4602.1 4602.8 4729.2 4734.0 4751.9 4767.9 4802.5 5044.2 5044.2 5044.9 5053.0 5101.9 4591.6 4708.2 4788.9 4800.6 4803.6 5049.2 5066.6 5071.1 5079.8 5122.1 5138.2 5143.6 5155.2 5156.0 5184.5 4780.5 4965.8 4968.9 4985.2 5015.5 4971.5 4998.4 5007.8 5010.3 5061.2 4926.5 4945.8 4967.1 4993.3 5010.0 4825.7 4956.0 4979.2 5014.9 5035.9 4880.9 4916.8 4939.1 4945.5 4992.4 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | [#1] ext.html Cycles:180 Average:48243.67 Median:37847.53 stddev:86056.80 (227.4%) stddev-sans-first:86223.78 16:05:20 INFO - PROCESS | 29778 | Values: 574.3 592.2 600.0 600.5 609.1 42659.6 44169.6 44250.6 44284.6 49011.0 43083.1 43921.0 43932.0 44040.6 44267.0 7719.5 7925.5 7933.9 7934.9 7938.1 7892.3 7899.9 7978.9 7981.6 8001.3 8010.0 8052.9 8057.8 8058.0 8077.4 7966.8 8011.6 8014.0 8017.5 8018.8 7885.9 7979.6 7983.3 7983.4 7987.1 39645.8 40079.0 40136.2 40252.6 40253.6 3134.1 3210.9 3253.6 3259.8 3306.6 29572.0 30017.3 30124.7 30722.1 31263.7 3202.6 3214.3 3224.2 3296.6 3299.9 242614.0 243021.0 244410.0 244584.0 245301.0 34347.5 56076.4 56848.4 56851.4 57019.7 34404.5 36344.5 36378.0 36388.8 36410.1 13592.4 13810.4 13850.7 13863.2 13872.4 11069.9 11261.5 11295.8 11296.7 11315.8 8446.8 8460.5 8470.9 8516.7 8545.6 3231.1 3250.6 3260.2 3286.4 3306.5 908.5 930.8 1024.4 1028.5 1040.5 28570.4 28965.0 29172.6 29177.0 29674.3 998.2 1004.3 1008.8 1009.5 1054.8 15589.8 15916.8 15918.4 16078.3 16953.4 372841.0 378886.0 379182.0 379296.0 381677.0 53775.5 54248.7 54462.0 54506.0 54516.0 1150.5 1253.2 1268.0 1276.9 1307.7 28783.7 29807.7 29959.0 30641.4 30835.8 43801.8 50298.0 51849.0 51936.0 57723.0 338395.0 342539.0 342873.0 344137.0 347627.0 33843.0 38978.6 39101.8 39990.6 40252.8 43717.0 43838.6 43875.8 43987.0 44481.6 37130.4 37429.8 37664.4 38310.8 38364.8 37840.6 38133.2 38359.4 38371.8 38837.0 36641.6 37029.6 37854.4 37917.2 37998.4 31749.7 34162.8 34523.1 34592.7 34719.5 26050.3 26260.7 26346.2 26387.2 26863.5 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | [#2] jquery.html Cycles:180 Average:24722.65 Median:419312.00 stddev:68562.33 (16.4%) stddev-sans-first:68730.29 16:05:20 INFO - PROCESS | 29778 | Values: 373.9 374.1 378.6 391.1 399.1 16001.8 16738.4 17378.5 17582.0 17640.3 11180.2 11759.8 11761.2 11771.5 11779.2 4302.9 4800.0 4816.5 4990.5 4992.6 4877.0 4924.5 4930.4 4932.9 4938.8 4936.0 4936.9 5173.9 5321.7 5324.0 5223.9 5225.6 5238.2 5242.6 5244.5 4578.6 4707.9 4791.5 4839.1 4840.5 12766.7 12853.6 12936.7 13036.6 13074.8 1935.4 1969.0 2162.5 2389.6 2487.2 9547.4 9866.8 9916.2 11407.5 13259.6 1726.2 1806.2 1880.2 1886.2 1891.2 67619.0 69976.0 70838.0 72089.6 72501.6 10319.2 10355.9 10391.3 10602.5 10643.0 14677.3 14742.0 14778.9 14814.2 14821.0 7199.4 7399.4 7408.5 7511.5 7543.9 6863.2 6989.6 7052.8 7112.3 7117.6 4448.7 4560.9 4562.0 4626.9 4708.0 1886.2 1889.0 1897.7 1937.9 1951.7 790.6 836.3 873.0 922.9 951.9 16686.7 17072.4 17097.4 17224.6 17439.7 902.9 908.8 910.2 956.1 961.0 9338.5 9408.2 9568.0 9711.2 9801.4 411900.0 412226.0 416434.0 417693.0 420931.0 77392.6 77579.0 77629.6 77966.0 80825.0 920.9 934.0 939.1 941.0 970.8 15034.5 15038.5 15182.6 15365.5 15603.4 34388.8 34518.5 35259.5 35362.3 36496.3 48234.3 48764.5 48874.3 49191.0 49401.5 7422.9 7431.2 7461.1 7634.6 7753.7 11198.7 11740.2 11762.9 11779.1 12059.3 10283.9 10510.5 10586.7 10595.2 10749.4 10267.8 10300.1 10464.9 10524.2 10541.4 10080.1 10320.7 10468.8 10579.6 11311.8 6719.2 6967.9 7087.4 8005.6 8278.4 10181.4 10476.1 10476.5 10559.4 10859.9 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | [#3] mootools.html Cycles:180 Average:4870.81 Median:5089.20 stddev:2644.04 (52.0%) stddev-sans-first:2637.50 16:05:20 INFO - PROCESS | 29778 | Values: 1256.2 1458.1 1484.0 1614.6 1806.2 6286.8 6916.1 7109.7 7127.6 7299.2 7015.6 7166.3 7178.1 7187.7 7190.5 2146.9 2174.1 2179.8 2181.8 2182.9 2138.9 2162.7 2185.7 2188.1 2190.9 2127.4 2133.8 2145.4 2155.8 2171.5 2079.4 2082.0 2084.9 2088.6 2090.0 2041.7 2091.9 2092.6 2094.3 2098.1 7116.8 7147.0 7172.5 7178.4 7352.9 4468.6 4571.2 4616.9 4635.4 4644.6 2959.1 2969.8 3031.2 3041.9 3053.0 947.6 994.9 997.1 997.3 1030.9 9380.3 9400.5 9480.9 9488.3 9491.2 4120.5 4184.4 4188.8 4192.4 4313.0 7724.4 7766.7 7787.5 7791.5 7794.5 4532.5 4537.5 4556.7 4559.8 4563.4 3666.8 4002.8 4096.8 4253.7 4277.9 3356.6 3370.4 3440.5 3454.6 3489.2 1041.1 1050.3 1054.9 1055.6 1079.4 795.1 857.5 886.8 889.5 892.3 5065.5 5066.7 5082.2 5096.2 5147.0 426.0 431.3 435.3 436.4 436.6 3403.9 3496.6 3509.3 3509.6 3579.1 8785.0 9091.0 9192.5 9206.0 9213.0 9151.0 9159.3 9217.8 9243.5 9247.4 6989.1 7030.8 7044.9 7183.2 7200.6 5043.1 5049.6 5147.4 5215.1 5263.4 8347.0 8424.2 8496.4 8574.1 8618.8 8494.7 8665.9 8684.0 8758.9 8828.0 5545.4 5961.3 6104.4 6161.9 6174.0 7025.5 7187.2 7262.2 7266.0 7275.6 6359.9 6513.9 6528.1 6591.0 6687.3 6231.8 6265.9 6324.0 6350.9 6513.5 6086.3 6106.3 6144.6 6147.7 6154.8 4392.3 4421.8 4460.5 4475.8 4580.0 4423.8 4511.2 4526.4 4540.0 4556.6 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | [#4] prototype.html Cycles:180 Average:4118.71 Median:4740.32 stddev:1186.27 (25.0%) stddev-sans-first:1154.63 16:05:20 INFO - PROCESS | 29778 | Values: 309.2 314.7 318.6 319.1 319.8 4733.5 4747.2 4783.5 4805.8 4817.0 4704.7 4719.7 4727.3 4727.8 4731.1 3614.6 3689.3 3710.3 3779.8 3820.2 3769.3 3781.3 3804.9 3815.3 3826.8 3578.9 3744.2 3752.5 3761.7 3773.5 3646.6 3759.0 3767.4 3770.3 3772.3 3359.5 3361.3 3361.9 3368.2 3372.9 4443.7 4483.4 4523.3 4529.8 4535.3 3985.1 4214.6 4241.0 4281.3 4282.3 4870.4 4900.5 4916.4 4937.4 4941.9 3525.2 3533.9 3567.2 3571.8 3573.6 5023.2 5092.7 5108.3 5148.7 5149.4 3602.2 3604.3 3642.5 3646.7 3648.8 3650.9 3660.6 3680.5 3712.6 3716.9 4212.2 4238.7 4240.3 4242.6 4258.2 4876.3 4965.7 4997.6 5018.8 5021.5 1236.5 1249.1 1256.6 1258.2 1259.4 805.8 816.8 819.1 821.4 858.8 4188.5 4188.5 4202.2 4205.7 4220.5 4897.6 4906.3 4913.4 4941.6 4942.3 4074.7 4135.6 4145.5 4234.9 4270.7 2687.0 2698.7 2706.2 2708.6 2711.0 4796.5 4804.7 4807.7 4823.6 4848.0 4909.6 5187.7 5191.8 5198.7 5200.6 4776.4 4784.8 4803.0 4806.7 4813.3 5039.8 5074.6 5074.9 5089.2 5090.1 4779.3 4965.4 4976.4 4978.7 5059.4 4847.2 4871.4 4876.9 4884.6 4910.7 3633.6 3656.6 3658.8 3662.0 3672.4 5008.9 5146.2 5154.7 5173.0 5182.6 4969.5 4974.0 5001.5 5005.5 5012.5 4968.8 4980.8 5003.2 5012.4 5024.2 4759.4 4917.9 4922.4 4933.8 4952.9 4840.4 4868.3 5022.5 5043.9 5058.5 4905.6 4918.7 4919.6 4942.7 4967.9 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - PROCESS | 29778 | [#5] yui.html Cycles:180 Average:12812.27 Median:53624.23 stddev:15285.62 (28.5%) stddev-sans-first:15301.40 16:05:20 INFO - PROCESS | 29778 | Values: 692.7 747.8 798.3 800.1 815.2 10449.9 10596.1 10600.2 10616.4 11207.0 11840.3 12026.3 12065.7 12119.5 12348.6 665.9 858.8 861.3 874.7 886.8 881.6 883.5 883.7 885.8 886.5 928.5 930.4 930.8 931.4 931.6 692.8 889.7 912.2 913.9 921.6 677.5 677.8 677.9 678.1 678.8 11130.4 11176.3 11219.1 11231.0 11271.3 11853.0 12193.8 12339.8 12340.8 12875.4 10594.7 10655.5 10733.2 10772.1 11047.2 4031.4 4094.7 4118.3 4226.3 4235.4 41174.6 41372.4 41459.8 41468.6 41672.4 9931.5 9947.2 10041.0 10156.2 10247.2 16732.2 16761.5 16810.2 16840.6 17146.3 6209.6 6243.2 6492.4 6560.3 6588.8 3459.0 3484.8 3530.6 3535.4 3603.7 2695.9 2786.2 2787.7 2790.7 2790.9 467.1 487.5 519.7 520.4 527.7 562.9 644.4 645.5 646.0 667.2 7994.6 8245.9 8266.4 8284.0 8330.7 588.2 655.2 656.3 661.8 674.9 3624.5 3669.2 3765.5 3831.7 3847.4 55148.7 55936.4 56719.7 56909.1 56996.0 50894.7 51123.7 52763.0 53324.7 53923.7 45870.3 46384.3 46458.0 46475.3 47277.0 7330.6 7570.7 7584.7 7608.4 7794.0 29465.4 30488.2 30647.5 30788.4 31292.7 39621.6 40054.0 40498.0 41126.2 41452.4 9524.1 9825.5 9896.1 9935.6 10236.5 10259.9 10490.2 10508.9 10627.7 11062.8 9796.1 9997.2 10208.6 10372.1 10478.3 9227.6 9333.0 9507.9 9529.7 9734.7 9314.3 9392.5 9446.1 9581.8 9596.0 8113.3 8122.8 8316.1 8613.3 8786.4 8040.6 8399.0 8404.2 8512.2 8578.9 16:05:20 INFO - PROCESS | 29778 | -------- Summary: end -------- 16:05:20 INFO - PROCESS | 29778 | 16:05:20 INFO - TEST-INFO | 29778: exit 0 16:05:20 INFO - TEST-OK | dromaeo_css | took 1117791ms 16:05:20 INFO - TEST-START | kraken 16:05:20 INFO - operating with platform_type : linux_ 16:05:20 INFO - Initialising browser for kraken test... 16:05:20 INFO - TEST-INFO | started process 29957 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDt5dvg/profile http://localhost:33323/getInfo.html) 16:05:24 INFO - PROCESS | 29957 | __metrics Screen width/height:1600/1200 16:05:24 INFO - PROCESS | 29957 | colorDepth:24 16:05:24 INFO - PROCESS | 29957 | Browser inner width/height: 1024/697 16:05:24 INFO - PROCESS | 29957 | __metrics 16:05:25 ERROR - PROCESS | 29957 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:05:26 INFO - TEST-INFO | 29957: exit 0 16:05:26 INFO - Browser initialized. 16:05:26 INFO - Running cycle 1/1 for kraken test... 16:05:26 INFO - TEST-INFO | started process 30037 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDt5dvg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:38 INFO - PROCESS | 30037 | RSS: Main: 154128384 16:05:38 INFO - PROCESS | 30037 | 16:05:41 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33323/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:42 INFO - PROCESS | 30037 | RSS: Main: 209633280 16:05:42 INFO - PROCESS | 30037 | 16:05:45 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33323/tests/kraken/driver.html?testName=audio-dft) 16:05:45 INFO - PROCESS | 30037 | RSS: Main: 198045696 16:05:45 INFO - PROCESS | 30037 | 16:05:49 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33323/tests/kraken/driver.html?testName=audio-fft) 16:05:49 INFO - PROCESS | 30037 | RSS: Main: 266665984 16:05:49 INFO - PROCESS | 30037 | 16:05:51 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33323/tests/kraken/driver.html?testName=audio-oscillator) 16:05:52 INFO - PROCESS | 30037 | RSS: Main: 175464448 16:05:52 INFO - PROCESS | 30037 | 16:05:54 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33323/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:05:55 INFO - PROCESS | 30037 | RSS: Main: 179142656 16:05:55 INFO - PROCESS | 30037 | 16:05:58 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33323/tests/kraken/driver.html?testName=imaging-darkroom) 16:05:59 INFO - PROCESS | 30037 | RSS: Main: 215392256 16:05:59 INFO - PROCESS | 30037 | 16:06:03 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33323/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:03 INFO - PROCESS | 30037 | RSS: Main: 213979136 16:06:03 INFO - PROCESS | 30037 | 16:06:06 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33323/tests/kraken/driver.html?testName=json-parse-financial) 16:06:07 INFO - PROCESS | 30037 | RSS: Main: 214347776 16:06:07 INFO - PROCESS | 30037 | 16:06:09 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33323/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:09 INFO - PROCESS | 30037 | RSS: Main: 207187968 16:06:09 INFO - PROCESS | 30037 | 16:06:11 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:06:12 INFO - PROCESS | 30037 | RSS: Main: 207781888 16:06:12 INFO - PROCESS | 30037 | 16:06:14 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:06:15 INFO - PROCESS | 30037 | RSS: Main: 212205568 16:06:15 INFO - PROCESS | 30037 | 16:06:18 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:06:18 INFO - PROCESS | 30037 | RSS: Main: 217186304 16:06:18 INFO - PROCESS | 30037 | 16:06:21 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:06:22 INFO - PROCESS | 30037 | RSS: Main: 216551424 16:06:22 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | Cycle 1(1): loaded http://localhost:33323/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33323/tests/kraken/driver.html?testName=ai-astar) 16:06:24 INFO - PROCESS | 30037 | RSS: Main: 263684096 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | __start_tp_report 16:06:24 INFO - PROCESS | 30037 | _x_x_mozilla_page_load 16:06:24 INFO - PROCESS | 30037 | _x_x_mozilla_page_load_details 16:06:24 INFO - PROCESS | 30037 | |i|pagename|runs| 16:06:24 INFO - PROCESS | 30037 | |0;ai-astar;115;112;117;115;116;114;114;114;114;115 16:06:24 INFO - PROCESS | 30037 | |1;audio-beat-detection;159;129;153;223;131;153;150;131;191;130 16:06:24 INFO - PROCESS | 30037 | |2;audio-dft;163;160;160;162;162;162;161;159;162;160 16:06:24 INFO - PROCESS | 30037 | |3;audio-fft;75;77;77;156;75;75;77;75;127;75 16:06:24 INFO - PROCESS | 30037 | |4;audio-oscillator;92;93;158;92;93;94;116;93;93;123 16:06:24 INFO - PROCESS | 30037 | |5;imaging-gaussian-blur;116;117;116;116;119;117;117;117;116;118 16:06:24 INFO - PROCESS | 30037 | |6;imaging-darkroom;146;146;146;146;147;146;146;148;150;146 16:06:24 INFO - PROCESS | 30037 | |7;imaging-desaturate;95;95;95;95;96;95;95;95;95;95 16:06:24 INFO - PROCESS | 30037 | |8;json-parse-financial;65;68;65;65;66;65;65;66;65;66 16:06:24 INFO - PROCESS | 30037 | |9;json-stringify-tinderbox;65;65;65;64;66;65;65;66;66;66 16:06:24 INFO - PROCESS | 30037 | |10;stanford-crypto-aes;82;82;87;81;82;82;82;85;160;82 16:06:24 INFO - PROCESS | 30037 | |11;stanford-crypto-ccm;154;134;141;130;131;130;132;132;160;136 16:06:24 INFO - PROCESS | 30037 | |12;stanford-crypto-pbkdf2;175;174;171;171;173;173;179;178;175;174 16:06:24 INFO - PROCESS | 30037 | |13;stanford-crypto-sha256-iterative;69;71;68;70;68;68;68;67;67;66 16:06:24 INFO - PROCESS | 30037 | __end_tp_report 16:06:24 INFO - PROCESS | 30037 | __start_cc_report 16:06:24 INFO - PROCESS | 30037 | _x_x_mozilla_cycle_collect,2086 16:06:24 INFO - PROCESS | 30037 | __end_cc_report 16:06:24 INFO - PROCESS | 30037 | __startTimestamp1462835184500__endTimestamp 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | ------- Summary: start ------- 16:06:24 INFO - PROCESS | 30037 | Number of tests: 14 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#0] ai-astar Cycles:10 Average:114.60 Median:115.00 stddev:1.35 (1.2%) stddev-sans-first:1.42 16:06:24 INFO - PROCESS | 30037 | Values: 115.0 112.0 117.0 115.0 116.0 114.0 114.0 114.0 114.0 115.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#1] audio-beat-detection Cycles:10 Average:155.00 Median:153.00 stddev:30.59 (20.0%) stddev-sans-first:32.41 16:06:24 INFO - PROCESS | 30037 | Values: 159.0 129.0 153.0 223.0 131.0 153.0 150.0 131.0 191.0 130.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#2] audio-dft Cycles:10 Average:161.10 Median:162.00 stddev:1.29 (0.8%) stddev-sans-first:1.17 16:06:24 INFO - PROCESS | 30037 | Values: 163.0 160.0 160.0 162.0 162.0 162.0 161.0 159.0 162.0 160.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#3] audio-fft Cycles:10 Average:88.90 Median:75.00 stddev:28.57 (38.1%) stddev-sans-first:29.85 16:06:24 INFO - PROCESS | 30037 | Values: 75.0 77.0 77.0 156.0 75.0 75.0 77.0 75.0 127.0 75.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#4] audio-oscillator Cycles:10 Average:104.70 Median:93.00 stddev:21.83 (23.5%) stddev-sans-first:22.66 16:06:24 INFO - PROCESS | 30037 | Values: 92.0 93.0 158.0 92.0 93.0 94.0 116.0 93.0 93.0 123.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.99 (0.8%) stddev-sans-first:1.00 16:06:24 INFO - PROCESS | 30037 | Values: 116.0 117.0 116.0 116.0 119.0 117.0 117.0 117.0 116.0 118.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:146.00 stddev:1.34 (0.9%) stddev-sans-first:1.39 16:06:24 INFO - PROCESS | 30037 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 148.0 150.0 146.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:06:24 INFO - PROCESS | 30037 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:65.50 stddev:0.97 (1.5%) stddev-sans-first:1.00 16:06:24 INFO - PROCESS | 30037 | Values: 65.0 68.0 65.0 65.0 66.0 65.0 65.0 66.0 65.0 66.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 16:06:24 INFO - PROCESS | 30037 | Values: 65.0 65.0 65.0 64.0 66.0 65.0 65.0 66.0 66.0 66.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:82.00 stddev:24.49 (29.9%) stddev-sans-first:25.78 16:06:24 INFO - PROCESS | 30037 | Values: 82.0 82.0 87.0 81.0 82.0 82.0 82.0 85.0 160.0 82.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:135.00 stddev:10.64 (7.9%) stddev-sans-first:9.58 16:06:24 INFO - PROCESS | 30037 | Values: 154.0 134.0 141.0 130.0 131.0 130.0 132.0 132.0 160.0 136.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:174.50 stddev:2.63 (1.5%) stddev-sans-first:2.77 16:06:24 INFO - PROCESS | 30037 | Values: 175.0 174.0 171.0 171.0 173.0 173.0 179.0 178.0 175.0 174.0 16:06:24 INFO - PROCESS | 30037 | 16:06:24 INFO - PROCESS | 30037 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.48 (2.2%) stddev-sans-first:1.54 16:06:24 INFO - PROCESS | 30037 | Values: 69.0 71.0 68.0 70.0 68.0 68.0 68.0 67.0 67.0 66.0 16:06:24 INFO - PROCESS | 30037 | -------- Summary: end -------- 16:06:24 INFO - PROCESS | 30037 | 16:06:25 INFO - TEST-INFO | 30037: exit 0 16:06:25 INFO - TEST-OK | kraken | took 64918ms 16:06:25 INFO - SUITE-END | took 1182s 16:06:26 INFO - Completed test suite (00:19:43) 16:06:26 INFO - javascript benchmark 16:06:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2623.134365659332, 2666.9199924002287, 2695.326168457886, 2697.3930865555253, 2705.7700095491896, 4591.311303304506, 4638.304254361848, 4641.2341963576055, 4759.619230461568, 4796.448408433032, 4688.343631891538, 4708.7645617719145, 4729.64527660426, 4757.001029783741, 4778.617710583154, 4368.432103826506, 4391.67062470316, 4459.710118842281, 4483.529229430896, 4574.268117101265, 4470.26240670289, 4488.7531185784765, 4524.886877828048, 4533.093381323732, 4550.0, 4242.024334403089, 4276.615104640598, 4303.0, 4328.956710432887, 4336.978315108436, 4367.825286988516, 4371.78141092944, 4387.341898715186, 4420.115976804652, 4420.226460369422, 4382.342648602703, 4394.055278115221, 4394.6923715339935, 4408.911821763547, 4449.065696203768, 4490.259107247301, 4623.283391074355, 4652.116097941414, 4704.458987216445, 4714.952850471485, 5131.897362052775, 5139.665921715076, 5198.0, 5198.766055527511, 5202.427732949411, 5196.714180720059, 5203.765830537635, 5219.791208351661, 5245.26566280721, 5246.134387826004, 4672.15901137795, 4730.408698912636, 4776.713397196179, 4788.425388953278, 4843.346148269939, 5253.684778913277, 5256.237845512455, 5276.788928442896, 5298.92051619226, 5302.893942121136, 4773.522647735199, 5018.071656743514, 5020.523050310215, 5063.924041139386, 5072.644914855999, 5098.821541246039, 5120.692758434506, 5121.28302037715, 5126.794928202905, 5156.690598564098, 5108.0, 5108.233764935289, 5108.6168537359845, 5116.744162791845, 5166.9224961625605, 4797.160496913027, 5078.0, 5095.87260318495, 5096.566791822736, 5102.413222479388, 4400.933985990213, 4407.0, 4423.756693381831, 4463.754493502824, 4467.977660111744, 3830.71269654777, 3840.0399900024995, 3851.768893866377, 3855.652991230803, 3880.902977425587, 4128.3601041838565, 4167.958320416757, 4284.250256205151, 4387.956120438755, 4424.579664931826, 5059.418166910852, 5069.898602028013, 5072.264521644414, 5085.771140298695, 5135.974320128375, 4302.118065796504, 4309.633681137076, 4315.935260971088, 4368.301071828492, 4369.541198174146, 4505.414296141481, 4539.978504836386, 4555.840545580889, 4602.102589994919, 4602.792874320664, 4729.243321068612, 4733.9763301183275, 4751.857444276678, 4767.880802979815, 4802.471728109905, 5044.192929131322, 5044.243363495505, 5044.9243261350375, 5053.0399224147295, 5101.897962040813, 4591.632669386374, 4708.246680531099, 4788.880277993078, 4800.6159507238635, 4803.567678908915, 5049.2426136079885, 5066.594672426123, 5071.13790655582, 5079.847604571869, 5122.078025955215, 5138.152204886152, 5143.61422893277, 5155.200943853709, 5156.04613146569, 5184.45563215872, 4780.5219478053305, 4965.751712414437, 4968.850934471971, 4985.227289770091, 5015.523525265094, 4971.527704868032, 4998.350214472095, 5007.824726134568, 5010.348654674869, 5061.240813877947, 4926.5073492650445, 4945.8021679132435, 4967.05625931072, 4993.251012348177, 5010.0, 4825.686330388514, 4955.950440495549, 4979.178435558238, 5014.87462813418, 5035.899282014412, 4880.902381952412, 4916.778744956414, 4939.135651261086, 4945.455999840086, 4992.350994370709], "value": 4685.205552714004, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.3395095640013, 592.1827877529009, 599.9700014999252, 600.4895838537246, 609.1411110334428, 42659.573404265946, 44169.55830441695, 44250.55749442505, 44284.55715442845, 49011.0, 43083.138337233235, 43921.0, 43932.0, 44040.55959440405, 44267.0, 7719.459637825355, 7925.484843485155, 7933.920660793405, 7934.920650793505, 7938.126806051329, 7892.289693927545, 7899.881501777478, 7978.9202107979045, 7981.561014144219, 8001.319887809544, 8010.038795344567, 8052.919470805305, 8057.798555036142, 8057.95971020144, 8077.434579579432, 7966.800829979239, 8011.639476223584, 8014.038315402131, 8017.478863873888, 8018.759437216893, 7885.921140788576, 7979.56112413817, 7983.321417679446, 7983.361331093499, 7987.081485629169, 39645.80177099096, 40079.0, 40136.187062229044, 40252.59747402518, 40253.597464025275, 3134.09111357706, 3210.855511501965, 3253.6322620396254, 3259.837008149583, 3306.6197387300526, 29572.0, 30017.284329699854, 30124.69875301241, 30722.078337649906, 31263.687363126533, 3202.6156861176573, 3214.341060082701, 3224.1778346521724, 3296.5549650797075, 3299.868005279786, 242614.0, 243021.0, 244410.0, 244584.0, 245301.0, 34347.4847877282, 56076.43923560712, 56848.431515684315, 56851.43148568544, 57019.71490142606, 34404.48393274103, 36344.45483317752, 36378.0, 36388.81805590955, 36410.089747755985, 13592.388342524413, 13810.37853296584, 13850.65373365674, 13863.168209907439, 13872.4451021958, 11069.889301107047, 11261.493232804543, 11295.774084518427, 11296.717582060513, 11315.773684526263, 8446.831063378699, 8460.492370457792, 8470.872936905951, 8516.74449766508, 8545.572721363907, 3231.079142444392, 3250.5611742414944, 3260.233845046412, 3286.4248756467523, 3306.470964645693, 908.4867050116663, 930.7829615007919, 1024.3724845770575, 1028.4857571214393, 1040.5109598488698, 28570.428591428055, 28965.0, 29172.562411563842, 29177.0, 29674.25814354615, 998.201438848918, 1004.2668851738126, 1008.7679833638305, 1009.5154325923597, 1054.825953717628, 15589.766153507706, 15916.840831591768, 15918.363265469252, 16078.276477558538, 16953.40663076811, 372841.0, 378886.0, 379182.0, 379296.0, 381677.0, 53775.46224537783, 54248.728756355966, 54462.0, 54506.0, 54516.0, 1150.4880328253846, 1253.2104773992326, 1267.9556215532411, 1276.8659290774522, 1307.712303293293, 28783.712162878102, 29807.70192298136, 29959.0, 30641.387172256877, 30835.84582077075, 43801.78099109484, 50298.0, 51849.0, 51936.0, 57723.0, 338395.0, 342539.0, 342873.0, 344137.0, 347627.0, 33843.0, 38978.61021389863, 39101.8044909785, 39990.600093998684, 40252.79873600613, 43717.0, 43838.56161438345, 43875.78062109669, 43987.0, 44481.55518444903, 37130.44304335491, 37429.77106867874, 37664.43503347507, 38310.80844595759, 38364.80817595894, 37840.62159378481, 38133.237335253696, 38359.42460863033, 38371.80814096023, 38837.0, 36641.633583663825, 37029.629703702616, 37854.43218351782, 37917.24165516619, 37998.43002355022, 31749.682503175598, 34162.82918585391, 34523.13692157716, 34592.65407345894, 34719.4792078114, 26050.34874128162, 26260.73739262583, 26346.20961371162, 26387.20838374852, 26863.462730744886], "value": 17441.514796276922, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.8504598160735, 374.1058869302368, 378.5797764481425, 391.1024199462234, 399.0741479766942, 16001.83998160018, 16738.414155504546, 17378.47864564062, 17582.0, 17640.294388224473, 11180.161487888421, 11759.823602645945, 11761.235519691214, 11771.529138834436, 11779.23434976726, 4302.870913872589, 4799.976000119995, 4816.494268101851, 4990.525900039491, 4992.625553083515, 4877.0, 4924.4583095859425, 4930.4330002049865, 4932.926006109911, 4938.7530623468865, 4936.0, 4936.851894443173, 5173.948260517384, 5321.654092483996, 5324.041672498949, 5223.921641175385, 5225.634205605609, 5238.161894096946, 5242.554382877441, 5244.527992480657, 4578.587927086578, 4707.8587642370785, 4791.520847915215, 4839.128956787777, 4840.5159484051665, 12766.744665106646, 12853.550125745554, 12936.676583085498, 13036.608901732963, 13074.803877941838, 1935.3516571948414, 1968.9606207875906, 2162.4918144236094, 2389.629607410854, 2487.1543675150538, 9547.42715437076, 9866.802663946753, 9916.157126644244, 11407.54369825206, 13259.602211933561, 1726.2404542001605, 1806.2142967809045, 1880.1539307311627, 1886.1889387563342, 1891.224597914862, 67619.0, 69976.0, 70838.0, 72089.63955180244, 72501.6374918122, 10319.1744660427, 10355.94822025885, 10391.324563903323, 10602.522886470128, 10643.0, 14677.266136693337, 14742.0, 14778.891583131308, 14814.185219813015, 14821.0, 7199.388052015637, 7399.371053460516, 7408.481406301615, 7511.474196806171, 7543.924560754432, 6863.210730765926, 6989.586620384967, 7052.823679407953, 7112.28877112295, 7117.64411779409, 4448.733076015449, 4560.863174104782, 4561.954380456153, 4626.930596041062, 4707.952920470751, 1886.2172198537453, 1888.977635782764, 1897.724829899657, 1937.8546609004381, 1951.6682164031865, 790.5928446849877, 836.2807985132781, 872.9869051964225, 922.8846394200725, 951.9190868776232, 16686.666266674598, 17072.402465913634, 17097.401590944257, 17224.569385765204, 17439.65120697579, 902.9458232505939, 908.8136931928939, 910.2217603948661, 956.1299217711988, 960.9615615375446, 9338.48638324885, 9408.153266206074, 9567.952160239154, 9711.223102151811, 9801.362911410735, 411900.0, 412226.0, 416434.0, 417693.0, 420931.0, 77392.61303693446, 77579.0, 77629.6118519415, 77966.0, 80824.9793755149, 920.9079092090738, 933.9719808405758, 939.1219210038636, 940.9858852117223, 970.7961328121032, 15034.548963531111, 15038.473653422077, 15182.620434489227, 15365.462208822637, 15603.375864965274, 34388.82805585956, 34518.48222276718, 35259.47110793289, 35362.29275414529, 36496.27007459783, 48234.27648585204, 48764.51235487741, 48874.266885996025, 49191.0, 49401.50598493969, 7422.925770742439, 7431.219721929336, 7461.104667439725, 7634.6182690864125, 7753.728619498291, 11198.664040078811, 11740.236884602473, 11762.94118529402, 11779.116566257393, 12059.276443413422, 10283.94858025705, 10510.47447627631, 10586.682399528027, 10595.15238780911, 10749.355038697704, 10267.794644107225, 10300.124489418484, 10464.94767526188, 10524.210684198868, 10541.367517948947, 10080.092791648787, 10320.690379288333, 10468.842967355344, 10579.576816927236, 11311.830322545004, 6719.227288861844, 6967.895481567882, 7087.397571206299, 8005.639746211551, 8278.420510564203, 10181.440020798927, 10476.109530689671, 10476.476176191312, 10559.419231942316, 10859.891401086204], "value": 8117.75101213992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.214865708932, 1458.1469840143516, 1483.9851601483983, 1614.6205641674205, 1806.1962426720106, 6286.779962701306, 6916.100906882105, 7109.680064397102, 7127.572345659264, 7299.1605965314, 7015.649217539129, 7166.319199676022, 7178.102737157856, 7187.712491500333, 7190.532615379997, 2146.9355919322447, 2174.0760176924823, 2179.8147157491553, 2181.814545763612, 2182.9235976740815, 2138.8823614701187, 2162.664786958016, 2185.6830759539857, 2188.146622817095, 2190.87950162741, 2127.361791462555, 2133.829293656504, 2145.4314606629255, 2155.752088509818, 2171.4679903423603, 2079.4489460292884, 2081.9687704684443, 2084.9478763030893, 2088.5509615432757, 2090.049027692404, 2041.6835390514493, 2091.9477013074643, 2092.6233278009954, 2094.3298144593737, 2098.0663604695915, 7116.822079448003, 7146.964265178693, 7172.46206534512, 7178.389836863873, 7352.889706654404, 4468.597826195625, 4571.200039993021, 4616.8614941551805, 4635.397398338195, 4644.605208557243, 2959.067893613505, 2969.792114551982, 3031.166429231957, 3041.9391612167633, 3053.0, 947.6304241345881, 994.8607194992707, 997.1474389397033, 997.276974193711, 1030.9381437113798, 9380.296477764196, 9400.529973501298, 9480.90519094814, 9488.335816492849, 9491.240700743992, 4120.464339635888, 4184.4141820145205, 4188.8324467020975, 4192.413062171298, 4312.956870431318, 7724.382049436032, 7766.689332426753, 7787.532748035136, 7791.454598178073, 7794.493357931717, 4532.546745325507, 4537.546245375502, 4556.658250631216, 4559.840405585788, 4563.406757121553, 3666.7799932004164, 4002.799860006988, 4096.754194748325, 4253.680973926967, 4277.871663850089, 3356.63077061523, 3370.376480351143, 3440.518327434161, 3454.6199918008997, 3489.180042689966, 1041.1306559023149, 1050.3382868792612, 1054.878688950765, 1055.6094245129198, 1079.379356869803, 795.129333379948, 857.5326447086353, 886.8004698942815, 889.5241046040467, 892.2817132208635, 5065.493450654905, 5066.746662666852, 5082.186850103965, 5096.210087436453, 5146.974265128651, 425.987220383389, 431.3378963291333, 435.29047652326506, 436.4130244820711, 436.57434001848577, 3403.9489407658907, 3496.5629296337956, 3509.2630547585295, 3509.5613048368955, 3579.069441945113, 8784.956075219583, 9090.954545227232, 9192.49441280736, 9206.033366496555, 9213.0, 9151.0, 9159.313051521163, 9217.76955576116, 9243.491607961492, 9247.44515329069, 6989.126359205201, 7030.753923612649, 7044.894326585106, 7183.173934997438, 7200.567965921957, 5043.142665746906, 5049.6465247432325, 5147.356580427447, 5215.113430716711, 5263.421023687315, 8346.998360196816, 8424.241818236389, 8496.405251632574, 8574.142585741376, 8618.827623447622, 8494.70268540598, 8665.870011949699, 8684.0, 8758.912410876064, 8827.955860220658, 5545.445455454584, 5961.254843144607, 6104.38956104386, 6161.907571386343, 6174.0, 7025.508214424941, 7187.209406965334, 7262.164851041986, 7266.0, 7275.636218189175, 6359.90460143089, 6513.8697226056165, 6528.086067950586, 6591.0, 6687.264400915798, 6231.781887633911, 6265.906011409741, 6324.019776934582, 6350.904736428864, 6513.5114866384265, 6086.3000754913755, 6106.32830388648, 6144.600600960924, 6147.72335244924, 6154.815355539341, 4392.3191905254735, 4421.823127074881, 4460.487043989982, 4475.776211189493, 4579.977100114478, 4423.77881105937, 4511.210538155875, 4526.434195725535, 4539.97730011361, 4556.589906908394], "value": 3918.7241409368826, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.185296743082, 314.6837428384473, 318.56993059369853, 319.0699112088262, 319.8272932616387, 4733.4793172751015, 4747.1692453820615, 4783.521647835215, 4805.759712014403, 4816.97591512042, 4704.694194877331, 4719.693219940701, 4727.314539391795, 4727.808729107847, 4731.053789242148, 3614.638536146384, 3689.2990331836863, 3710.2764960832646, 3779.79211143387, 3820.2168555446065, 3769.302679004379, 3781.3382658034734, 3804.9239015219678, 3815.313243616148, 3826.8469261229657, 3578.874739384109, 3744.213715119828, 3752.512173417452, 3761.661450469457, 3773.471713960048, 3646.6171052039554, 3758.962410375889, 3767.3595488766973, 3770.2845205431463, 3772.320982223199, 3359.538600708675, 3361.310931259086, 3361.9159521011925, 3368.2421455172635, 3372.915677108092, 4443.733375997451, 4483.439570053743, 4523.253663145577, 4529.796159172845, 4535.319702044687, 3985.143194213229, 4214.557471465513, 4240.978795106035, 4281.250781113293, 4282.2656823986335, 4870.391201099863, 4900.509949005071, 4916.360873086476, 4937.432195297515, 4941.851744447672, 3525.171584677624, 3533.886825649912, 3567.23304489536, 3571.821408929543, 3573.5711714594413, 5023.196288593844, 5092.668976516515, 5108.335916330853, 5148.742562871879, 5149.433562308143, 3602.1715005548344, 3604.279144171176, 3642.5082613846794, 3646.7082633389264, 3648.835802388899, 3650.8539658413897, 3660.5973342932257, 3680.5031320771886, 3712.5916149223563, 3716.9442458363146, 4212.157568486315, 4238.682098842599, 4240.257954857949, 4242.596953289432, 4258.169656916872, 4876.29293752411, 4965.702057876539, 4997.575206107522, 5018.824341148043, 5021.548060674557, 1236.486857953957, 1249.119370843553, 1256.597888675615, 1258.1947553565726, 1259.4269607328642, 805.7904944714417, 816.8407160603745, 819.0539926385035, 821.4250024982537, 858.8411143938391, 4188.455500784939, 4188.497380314321, 4202.243596152661, 4205.74765514064, 4220.488809158545, 4897.608191344684, 4906.288588154697, 4913.385826771653, 4941.579965702884, 4942.308076869242, 4074.6740260779065, 4135.648469880034, 4145.523264824584, 4234.894127646772, 4270.679699022586, 2686.965518275439, 2698.7031426543062, 2706.174616741913, 2708.5915324031394, 2710.9593356099676, 4796.5203479652455, 4804.711717296974, 4807.711537307773, 4823.614110871122, 4848.0, 4909.558139767438, 5187.688738675692, 5191.792328306826, 5198.674338043775, 5200.609954253446, 4776.426828780499, 4784.7607619619575, 4803.046126632096, 4806.687565308243, 4813.278008298783, 5039.798408063636, 5074.644774865724, 5074.923876141935, 5089.211172268304, 5090.142098134228, 4779.283107533759, 4965.379327584052, 4976.402831660224, 4978.726170060537, 5059.367579052619, 4847.175980083465, 4871.3667223260745, 4876.926846097241, 4884.633652476007, 4910.656254062182, 3633.6373859802575, 3656.634336566322, 3658.8353524092013, 3661.9816900915325, 3672.4491326301268, 5008.899822003613, 5146.202338637517, 5154.737089413118, 5172.974135129451, 5182.585393168462, 4969.4782047884455, 4973.950260497349, 5001.52455025761, 5005.4744251853035, 5012.498750124958, 4968.751562421937, 4980.800767969241, 5003.249512573143, 5012.398512178416, 5024.196128619404, 4759.405074365704, 4917.901641967069, 4922.360093187863, 4933.777979990838, 4952.851414457572, 4840.370751802243, 4868.2940973557925, 5022.547970682656, 5043.87390315245, 5058.468860769566, 4905.583025442877, 4918.655694101379, 4919.557239848431, 4942.703437793743, 4967.8886473248], "value": 3745.648937464799, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.7229108356655, 747.8055705516566, 798.3214267872313, 800.0679208721835, 815.160384803652, 10449.895501044997, 10596.099331556827, 10600.151987840973, 10616.363018218912, 11207.0, 11840.289582625048, 12026.338551379671, 12065.698357541065, 12119.454624541895, 12348.629541113782, 665.8867992441296, 858.772425307291, 861.2808305065278, 874.6545114679698, 886.7738726624701, 881.6209030117047, 883.4875772052194, 883.6686242659002, 885.8272636835819, 886.5478605911, 928.4661319741176, 930.4091901642438, 930.7812664023949, 931.417863835103, 931.6040682709855, 692.8094773937182, 889.675268526983, 912.2246090822814, 913.9131782480654, 921.617528725578, 677.461418172554, 677.8474843160299, 677.8745932002595, 678.1116737074449, 678.8404724889647, 11130.38782866942, 11176.273542219811, 11219.102471802236, 11231.045361144312, 11271.32372057671, 11853.0, 12193.817092743528, 12339.753204935942, 12340.81488777669, 12875.356232188447, 10594.735131621694, 10655.520501577448, 10733.248672592921, 10772.08437282832, 11047.171462140372, 4031.354983202703, 4094.733842300241, 4118.279301122292, 4226.260404429243, 4235.449391579075, 41174.588254117676, 41372.37941430881, 41459.792701036604, 41468.585314146774, 41672.374914376305, 9931.50342482873, 9947.20422366209, 10041.0, 10156.23828212887, 10247.231457640706, 16732.163391830603, 16761.497155085366, 16810.243539040526, 16840.57898552546, 17146.314147434212, 6209.627422354673, 6243.188385509855, 6492.448141907991, 6560.343965603402, 6588.769393071219, 3458.9654103458643, 3484.77348972316, 3530.6469353065004, 3535.3636345458062, 3603.6936860367164, 2695.9326016849736, 2786.205931309567, 2787.6515435570554, 2790.6511686039244, 2790.944181116407, 467.05571449111926, 487.54171079185454, 519.7167543688698, 520.3703518742365, 527.7018484556213, 562.8733534954603, 644.3620815392821, 645.5416654175494, 645.9806205813833, 667.1727058447588, 7994.6002699864775, 8245.876311855327, 8266.380021498411, 8284.0, 8330.66677332912, 588.2470437839572, 655.2431941108027, 656.3174298729364, 661.7915356662636, 674.8616533610598, 3624.4563315502887, 3669.211119609297, 3765.4540091686554, 3831.712621553339, 3847.4228865670375, 55148.72425637846, 55936.440635593935, 56719.716401418555, 56909.14636280459, 56996.0, 50894.745526272134, 51123.74438127786, 52763.0, 53324.73337633287, 53923.73038134784, 45870.31194532085, 46384.304235436495, 46458.0, 46475.30287045697, 47277.0, 7330.560166390033, 7570.659320330488, 7584.696612135453, 7608.391328693802, 7793.961030194812, 29465.410691786474, 30488.237794055327, 30647.54028689616, 30788.384232315675, 31292.68707312898, 39621.60378396179, 40054.0, 40498.0, 41126.1774764509, 41452.378214327415, 9524.14282714559, 9825.508724563886, 9896.059874311928, 9935.552900119368, 10236.48817559134, 10259.948700256451, 10490.160787136858, 10508.894911050791, 10627.734306642396, 11062.778744425228, 9796.069373409515, 9997.200223982209, 10208.642697505551, 10372.066514013775, 10478.266521343432, 9227.630894764134, 9333.0, 9507.857382139135, 9529.740582605522, 9734.659286924923, 9314.264027325484, 9392.483413412328, 9446.102620251066, 9581.808363832544, 9595.952020239854, 8113.269805717514, 8122.796930076795, 8316.085230624505, 8613.31093512505, 8786.384953053224, 8040.638171282189, 8399.0, 8404.159584041547, 8512.233898949124, 8578.871316930376], "value": 5910.739708447082, "unit": "score"}], "name": "dromaeo_css", "value": 6213.729698721245, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 117.0, 115.0, 116.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 129.0, 153.0, 223.0, 131.0, 153.0, 150.0, 131.0, 191.0, 130.0], "value": 155.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 162.0, 162.0, 162.0, 161.0, 159.0, 162.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 156.0, 75.0, 75.0, 77.0, 75.0, 127.0, 75.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 158.0, 92.0, 93.0, 94.0, 116.0, 93.0, 93.0, 123.0], "value": 104.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 119.0, 117.0, 117.0, 117.0, 116.0, 118.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 148.0, 150.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 66.0, 65.0, 65.0, 66.0, 66.0, 66.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 87.0, 81.0, 82.0, 82.0, 82.0, 85.0, 160.0, 82.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 134.0, 141.0, 130.0, 131.0, 130.0, 132.0, 132.0, 160.0, 136.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 171.0, 171.0, 173.0, 173.0, 179.0, 178.0, 175.0, 174.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 68.0, 70.0, 68.0, 68.0, 68.0, 67.0, 67.0, 66.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1584.9, "alertThreshold": 2.0}]} 16:06:26 INFO - Return code: 0 16:06:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:06:26 ERROR - # TBPL SUCCESS # 16:06:26 INFO - Running post-action listener: _resource_record_post_action 16:06:26 INFO - ##### 16:06:26 INFO - ##### Finished run-tests step (success) 16:06:26 INFO - ##### 16:06:26 INFO - Running post-run listener: _resource_record_post_run 16:06:27 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 530898944; Read time: 12; Write time: 386832 16:06:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 235016192; Read time: 0; Write time: 192188 16:06:27 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 295882752; Read time: 12; Write time: 194644 16:06:27 INFO - Running post-run listener: _upload_blobber_files 16:06:27 INFO - Blob upload gear active. 16:06:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:06:27 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 16:06:27 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'] 16:06:27 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 16:06:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:06:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:06:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:06:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:28 INFO - (blobuploader) - INFO - Done attempting. 16:06:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:06:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:06:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:29 INFO - (blobuploader) - INFO - Done attempting. 16:06:29 INFO - (blobuploader) - INFO - Iteration through files over. 16:06:29 INFO - Return code: 0 16:06:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:06:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:06:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/04c31135c387dd1793979f576f2af932991c77fb08b84a8e38090d48e4976254e49a0cda42a88c5d9e1607663b41bca192e94a2bc31b21539e93139f16219d5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49d3462e575ecdf721a4685a0c333b51e8324e0b30d8b3cadda2bb7c70dec6847b2b99cfd894a90943cd7278e51a75ab7c9ebc7388ee4cc4e6044ff6752e9636"} 16:06:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:06:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:06:29 INFO - Contents: 16:06:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/04c31135c387dd1793979f576f2af932991c77fb08b84a8e38090d48e4976254e49a0cda42a88c5d9e1607663b41bca192e94a2bc31b21539e93139f16219d5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49d3462e575ecdf721a4685a0c333b51e8324e0b30d8b3cadda2bb7c70dec6847b2b99cfd894a90943cd7278e51a75ab7c9ebc7388ee4cc4e6044ff6752e9636"} 16:06:29 INFO - Running post-run listener: copy_logs_to_upload_dir 16:06:29 INFO - Copying logs to upload dir... 16:06:29 INFO - mkdir: /builds/slave/test/build/upload/logs 16:06:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.763382 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-05-09 16:06:29.096668) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:06:29.099861) ========= 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=3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979 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/04c31135c387dd1793979f576f2af932991c77fb08b84a8e38090d48e4976254e49a0cda42a88c5d9e1607663b41bca192e94a2bc31b21539e93139f16219d5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49d3462e575ecdf721a4685a0c333b51e8324e0b30d8b3cadda2bb7c70dec6847b2b99cfd894a90943cd7278e51a75ab7c9ebc7388ee4cc4e6044ff6752e9636"} build_url:https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011159 build_url: 'https://queue.taskcluster.net/v1/task/MZWGA9_cQ_-96h9mOumCCw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/04c31135c387dd1793979f576f2af932991c77fb08b84a8e38090d48e4976254e49a0cda42a88c5d9e1607663b41bca192e94a2bc31b21539e93139f16219d5c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/49d3462e575ecdf721a4685a0c333b51e8324e0b30d8b3cadda2bb7c70dec6847b2b99cfd894a90943cd7278e51a75ab7c9ebc7388ee4cc4e6044ff6752e9636"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 16:06:29.126859) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:06:29.127536) ========= 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=3a3620fb28aea1604ab609ef000001d2-1462832873.93277-414483979 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 16:06:29.207701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:06:29.208379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 16:06:29.209105) ========= ========= Total master_lag: 0.17 =========