builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-013 starttime: 1461216156.1 results: success (0) buildid: 20160420210543 builduid: e80a7f157ce44433a3cb2b5539ffc973 revision: 849d0f0165a6b882894249ef4c465d989c002740 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.102251) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.102705) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.102979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.147618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.147933) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-20 22:22:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-04-20 22:22:36 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.318309) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.318621) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018774 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:36.368178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 22:22:36.368519) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 849d0f0165a6b882894249ef4c465d989c002740 --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 849d0f0165a6b882894249ef4c465d989c002740 --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=9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-20 22:22:36,599 truncating revision to first 12 chars 2016-04-20 22:22:36,599 Setting DEBUG logging. 2016-04-20 22:22:36,599 attempt 1/10 2016-04-20 22:22:36,599 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/849d0f0165a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 22:22:37,912 unpacking tar archive at: fx-team-849d0f0165a6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.592596 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 22:22:37.973892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:37.974227) ========= script_repo_revision: 849d0f0165a6b882894249ef4c465d989c002740 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:37.974612) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:37.974898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 22:22:37.983364) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-04-20 22:22:37.983648) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:22:38 INFO - MultiFileLogger online at 20160420 22:22:38 in /builds/slave/test 22:22:38 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 22:22:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:22:38 INFO - {'append_to_log': False, 22:22:38 INFO - 'base_work_dir': '/builds/slave/test', 22:22:38 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:22:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:22:38 INFO - 'branch': 'Fx-Team-Non-PGO', 22:22:38 INFO - 'buildbot_json_path': 'buildprops.json', 22:22:38 INFO - 'config_files': ('talos/linux_config.py',), 22:22:38 INFO - 'default_actions': ('clobber', 22:22:38 INFO - 'read-buildbot-config', 22:22:38 INFO - 'download-and-extract', 22:22:38 INFO - 'populate-webroot', 22:22:38 INFO - 'create-virtualenv', 22:22:38 INFO - 'install', 22:22:38 INFO - 'run-tests'), 22:22:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:22:38 INFO - 'download_minidump_stackwalk': True, 22:22:38 INFO - 'download_symbols': 'ondemand', 22:22:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:22:38 INFO - 'tooltool.py': '/tools/tooltool.py', 22:22:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:22:38 INFO - '/tools/misc-python/virtualenv.py')}, 22:22:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:22:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:22:38 INFO - 'installer_path': 'installer.exe', 22:22:38 INFO - 'log_level': 'info', 22:22:38 INFO - 'log_name': 'talos', 22:22:38 INFO - 'log_to_console': True, 22:22:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:22:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:22:38 INFO - 'opt_config_files': (), 22:22:38 INFO - 'pip_index': False, 22:22:38 INFO - 'sps_profile': False, 22:22:38 INFO - 'sps_profile_interval': 0, 22:22:38 INFO - 'suite': 'dromaeojs', 22:22:38 INFO - 'system_bits': '32', 22:22:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:22:38 INFO - 'title': 'talos-linux64-ix-013', 22:22:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:22:38 INFO - 'use_talos_json': True, 22:22:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:22:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:22:38 INFO - 'work_dir': 'build'} 22:22:38 INFO - ##### 22:22:38 INFO - ##### Running clobber step. 22:22:38 INFO - ##### 22:22:38 INFO - Running pre-action listener: _resource_record_pre_action 22:22:38 INFO - Running main action method: clobber 22:22:38 INFO - rmtree: /builds/slave/test/build 22:22:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:22:38 INFO - Running post-action listener: _resource_record_post_action 22:22:38 INFO - ##### 22:22:38 INFO - ##### Running read-buildbot-config step. 22:22:38 INFO - ##### 22:22:38 INFO - Running pre-action listener: _resource_record_pre_action 22:22:38 INFO - Running main action method: read_buildbot_config 22:22:38 INFO - Using buildbot properties: 22:22:38 INFO - { 22:22:38 INFO - "project": "", 22:22:38 INFO - "product": "firefox", 22:22:38 INFO - "script_repo_revision": "production", 22:22:38 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:22:38 INFO - "repository": "", 22:22:38 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:22:38 INFO - "buildid": "20160420210543", 22:22:38 INFO - "pgo_build": "False", 22:22:38 INFO - "basedir": "/builds/slave/test", 22:22:38 INFO - "buildnumber": 1693, 22:22:38 INFO - "slavename": "talos-linux64-ix-013", 22:22:38 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:22:38 INFO - "platform": "ubuntu64_hw", 22:22:38 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:22:38 INFO - "branch": "fx-team", 22:22:38 INFO - "repo_path": "integration/fx-team", 22:22:38 INFO - "revision": "849d0f0165a6b882894249ef4c465d989c002740", 22:22:38 INFO - "stage_platform": "linux64", 22:22:38 INFO - "builduid": "e80a7f157ce44433a3cb2b5539ffc973", 22:22:38 INFO - "slavebuilddir": "test" 22:22:38 INFO - } 22:22:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 22:22:38 INFO - Running post-action listener: _resource_record_post_action 22:22:38 INFO - ##### 22:22:38 INFO - ##### Running download-and-extract step. 22:22:38 INFO - ##### 22:22:38 INFO - Running pre-action listener: _resource_record_pre_action 22:22:38 INFO - Running main action method: download_and_extract 22:22:38 INFO - mkdir: /builds/slave/test/build/tests 22:22:38 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')]} 22:22:38 INFO - https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:22:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:22:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:22:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:22:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:22:45 INFO - Downloaded 1361 bytes. 22:22:45 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:22:45 INFO - Using the following test package requirements: 22:22:45 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:22:45 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:22:45 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:22:45 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'jsshell-linux-x86_64.zip'], 22:22:45 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:22:45 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:22:45 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:22:45 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:22:45 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:22:45 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:22:45 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:22:45 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:22:45 INFO - https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:22:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:22:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:22:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:23:00 INFO - Downloaded 22275133 bytes. 22:23:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:23:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:23:02 INFO - Return code: 0 22:23:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:02 INFO - https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:23:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:23:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:23:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:23:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:23:04 INFO - Downloaded 22275133 bytes. 22:23:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:23:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:23:05 INFO - Return code: 0 22:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:05 INFO - https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:23:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:23:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:23:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:23:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:23:07 INFO - Downloaded 10845952 bytes. 22:23:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:23:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:23:07 INFO - Return code: 0 22:23: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')]} 22:23:07 INFO - https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:23:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:23:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:23:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:23:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:23:12 INFO - Downloaded 54654785 bytes. 22:23:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:23:12 INFO - mkdir: /builds/slave/test/properties 22:23:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:12 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:12 INFO - Contents: 22:23:12 INFO - build_url:https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:23:12 INFO - Running post-action listener: _resource_record_post_action 22:23:12 INFO - Running post-action listener: set_extra_try_arguments 22:23:12 INFO - ##### 22:23:12 INFO - ##### Running populate-webroot step. 22:23:12 INFO - ##### 22:23:12 INFO - Running pre-action listener: _resource_record_pre_action 22:23:12 INFO - Running main action method: populate_webroot 22:23:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:12 INFO - '--tppagecycles', 22:23:12 INFO - '7'], 22:23:12 INFO - 'tests': ['tsvgm']}}, 22:23:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['damp', 'tps']}, 22:23:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'tests': ['damp', 'tps']}, 22:23:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['dromaeo_dom']}, 22:23:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['a11yr', 22:23:12 INFO - 'ts_paint', 22:23:12 INFO - 'tpaint', 22:23:12 INFO - 'sessionrestore', 22:23:12 INFO - 'sessionrestore_no_auto_restore', 22:23:12 INFO - 'tabpaint']}, 22:23:12 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:12 INFO - 'ts_paint', 22:23:12 INFO - 'tpaint', 22:23:12 INFO - 'sessionrestore', 22:23:12 INFO - 'sessionrestore_no_auto_restore', 22:23:12 INFO - 'tabpaint']}, 22:23:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:12 INFO - 'ts_paint', 22:23:12 INFO - 'tpaint', 22:23:12 INFO - 'sessionrestore', 22:23:12 INFO - 'sessionrestore_no_auto_restore', 22:23:12 INFO - 'tabpaint']}, 22:23:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:12 INFO - 'ts_paint', 22:23:12 INFO - 'tpaint', 22:23:12 INFO - 'sessionrestore', 22:23:12 INFO - 'sessionrestore_no_auto_restore', 22:23:12 INFO - 'tabpaint']}, 22:23:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['a11yr', 22:23:12 INFO - 'ts_paint', 22:23:12 INFO - 'tpaint', 22:23:12 INFO - 'sessionrestore', 22:23:12 INFO - 'sessionrestore_no_auto_restore', 22:23:12 INFO - 'tabpaint']}, 22:23:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['a11yr', 22:23:12 INFO - 'ts_paint', 22:23:12 INFO - 'tpaint', 22:23:12 INFO - 'sessionrestore', 22:23:12 INFO - 'sessionrestore_no_auto_restore', 22:23:12 INFO - 'tabpaint']}, 22:23:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['tsvgx', 22:23:12 INFO - 'tsvgr_opacity', 22:23:12 INFO - 'tart', 22:23:12 INFO - 'tscrollx', 22:23:12 INFO - 'cart']}, 22:23:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:12 INFO - 'tsvgr_opacity', 22:23:12 INFO - 'tart', 22:23:12 INFO - 'tscrollx', 22:23:12 INFO - 'cart']}, 22:23:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'talos_options': ['--disable-e10s'], 22:23:12 INFO - 'tests': ['tp5o']}, 22:23:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'tests': ['tp5o']}, 22:23:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'talos_options': ['--disable-e10s', 22:23:12 INFO - '--xperf_path', 22:23:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:12 INFO - 'tests': ['tp5n']}, 22:23:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:12 INFO - 'talos_options': ['--xperf_path', 22:23:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:12 INFO - 'tests': ['tp5n']}}, 22:23:12 INFO - 'talos.zip': {'path': '', 22:23:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:12 INFO - Running post-action listener: _resource_record_post_action 22:23:12 INFO - ##### 22:23:12 INFO - ##### Running create-virtualenv step. 22:23:12 INFO - ##### 22:23:12 INFO - Running pre-action listener: _resource_record_pre_action 22:23:12 INFO - Running main action method: create_virtualenv 22:23:12 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:12 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 22:23:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:13 INFO - Using real prefix '/usr' 22:23:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:13 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:15 INFO - Installing pip.................done. 22:23:15 INFO - Return code: 0 22:23:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:23:15 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')]} 22:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:15 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')]} 22:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:15 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:15 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 22:23:15 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 22:23:15 INFO - Using env: {'DISPLAY': ':0', 22:23:15 INFO - 'HOME': '/home/cltbld', 22:23:15 INFO - 'LANG': 'en_US.UTF-8', 22:23:15 INFO - 'LANGUAGE': 'en_US:en', 22:23:15 INFO - 'LOGNAME': 'cltbld', 22:23:15 INFO - 'MAIL': '/var/mail/cltbld', 22:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:15 INFO - 'MOZ_NO_REMOTE': '1', 22:23:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:15 INFO - 'NO_EM_RESTART': '1', 22:23:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:15 INFO - 'PWD': '/builds/slave/test', 22:23:15 INFO - 'SHELL': '/bin/bash', 22:23:15 INFO - 'SHLVL': '1', 22:23:15 INFO - 'TERM': 'linux', 22:23:15 INFO - 'TMOUT': '86400', 22:23:15 INFO - 'USER': 'cltbld', 22:23:15 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:15 INFO - '_': '/tools/buildbot/bin/python'} 22:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:15 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 22:23:15 INFO - Cleaning up... 22:23:15 INFO - Return code: 0 22:23:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:23:15 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')]} 22:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:15 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')]} 22:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:15 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:15 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 22:23:15 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 22:23:15 INFO - Using env: {'DISPLAY': ':0', 22:23:15 INFO - 'HOME': '/home/cltbld', 22:23:15 INFO - 'LANG': 'en_US.UTF-8', 22:23:15 INFO - 'LANGUAGE': 'en_US:en', 22:23:15 INFO - 'LOGNAME': 'cltbld', 22:23:15 INFO - 'MAIL': '/var/mail/cltbld', 22:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:15 INFO - 'MOZ_NO_REMOTE': '1', 22:23:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:15 INFO - 'NO_EM_RESTART': '1', 22:23:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:15 INFO - 'PWD': '/builds/slave/test', 22:23:15 INFO - 'SHELL': '/bin/bash', 22:23:15 INFO - 'SHLVL': '1', 22:23:15 INFO - 'TERM': 'linux', 22:23:15 INFO - 'TMOUT': '86400', 22:23:15 INFO - 'USER': 'cltbld', 22:23:15 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:15 INFO - '_': '/tools/buildbot/bin/python'} 22:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:15 INFO - Downloading/unpacking psutil>=0.7.1 22:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:17 INFO - Installing collected packages: psutil 22:23:17 INFO - Running setup.py install for psutil 22:23:17 INFO - building 'psutil._psutil_linux' extension 22:23:17 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 22:23:18 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 22:23:18 INFO - building 'psutil._psutil_posix' extension 22:23:18 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 22:23:18 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 22:23:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:18 INFO - Successfully installed psutil 22:23:18 INFO - Cleaning up... 22:23:18 INFO - Return code: 0 22:23:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:23:18 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')]} 22:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:18 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')]} 22:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:18 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:18 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 22:23:18 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 22:23:18 INFO - Using env: {'DISPLAY': ':0', 22:23:18 INFO - 'HOME': '/home/cltbld', 22:23:18 INFO - 'LANG': 'en_US.UTF-8', 22:23:18 INFO - 'LANGUAGE': 'en_US:en', 22:23:18 INFO - 'LOGNAME': 'cltbld', 22:23:18 INFO - 'MAIL': '/var/mail/cltbld', 22:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:18 INFO - 'MOZ_NO_REMOTE': '1', 22:23:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:18 INFO - 'NO_EM_RESTART': '1', 22:23:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:18 INFO - 'PWD': '/builds/slave/test', 22:23:18 INFO - 'SHELL': '/bin/bash', 22:23:18 INFO - 'SHLVL': '1', 22:23:18 INFO - 'TERM': 'linux', 22:23:18 INFO - 'TMOUT': '86400', 22:23:18 INFO - 'USER': 'cltbld', 22:23:18 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:18 INFO - '_': '/tools/buildbot/bin/python'} 22:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:18 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 22:23:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:23:20 INFO - Installing collected packages: mozsystemmonitor 22:23:20 INFO - Running setup.py install for mozsystemmonitor 22:23:20 INFO - Successfully installed mozsystemmonitor 22:23:20 INFO - Cleaning up... 22:23:20 INFO - Return code: 0 22:23:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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', '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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:23: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 blobuploader==1.2.4 22:23:20 INFO - Using env: {'DISPLAY': ':0', 22:23:20 INFO - 'HOME': '/home/cltbld', 22:23:20 INFO - 'LANG': 'en_US.UTF-8', 22:23:20 INFO - 'LANGUAGE': 'en_US:en', 22:23:20 INFO - 'LOGNAME': 'cltbld', 22:23:20 INFO - 'MAIL': '/var/mail/cltbld', 22:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:20 INFO - 'MOZ_NO_REMOTE': '1', 22:23:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:20 INFO - 'NO_EM_RESTART': '1', 22:23:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:20 INFO - 'PWD': '/builds/slave/test', 22:23:20 INFO - 'SHELL': '/bin/bash', 22:23:20 INFO - 'SHLVL': '1', 22:23:20 INFO - 'TERM': 'linux', 22:23:20 INFO - 'TMOUT': '86400', 22:23:20 INFO - 'USER': 'cltbld', 22:23:20 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:20 INFO - '_': '/tools/buildbot/bin/python'} 22:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:20 INFO - Downloading/unpacking blobuploader==1.2.4 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23:22 INFO - Downloading blobuploader-1.2.4.tar.gz 22:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:23:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:23:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:23 INFO - Downloading docopt-0.6.1.tar.gz 22:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:23:23 INFO - Installing collected packages: blobuploader, requests, docopt 22:23:23 INFO - Running setup.py install for blobuploader 22:23:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:23:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:23:23 INFO - Running setup.py install for requests 22:23:23 INFO - Running setup.py install for docopt 22:23:24 INFO - Successfully installed blobuploader requests docopt 22:23:24 INFO - Cleaning up... 22:23:24 INFO - Return code: 0 22:23:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:24 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')]} 22:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:24 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')]} 22:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:24 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:24 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 22:23:24 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 22:23:24 INFO - Using env: {'DISPLAY': ':0', 22:23:24 INFO - 'HOME': '/home/cltbld', 22:23:24 INFO - 'LANG': 'en_US.UTF-8', 22:23:24 INFO - 'LANGUAGE': 'en_US:en', 22:23:24 INFO - 'LOGNAME': 'cltbld', 22:23:24 INFO - 'MAIL': '/var/mail/cltbld', 22:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:24 INFO - 'MOZ_NO_REMOTE': '1', 22:23:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:24 INFO - 'NO_EM_RESTART': '1', 22:23:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:24 INFO - 'PWD': '/builds/slave/test', 22:23:24 INFO - 'SHELL': '/bin/bash', 22:23:24 INFO - 'SHLVL': '1', 22:23:24 INFO - 'TERM': 'linux', 22:23:24 INFO - 'TMOUT': '86400', 22:23:24 INFO - 'USER': 'cltbld', 22:23:24 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:24 INFO - '_': '/tools/buildbot/bin/python'} 22:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:24 INFO - Running setup.py (path:/tmp/pip-2_zVM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:24 INFO - Running setup.py (path:/tmp/pip-oO9vA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:24 INFO - Running setup.py (path:/tmp/pip-QvamNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:24 INFO - Running setup.py (path:/tmp/pip-8iu8ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:24 INFO - Running setup.py (path:/tmp/pip-rjzscP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:24 INFO - Running setup.py (path:/tmp/pip-qQHj86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:24 INFO - Running setup.py (path:/tmp/pip-vZhSiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:24 INFO - Running setup.py (path:/tmp/pip-bgaYo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:24 INFO - Running setup.py (path:/tmp/pip-aw7RLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:25 INFO - Running setup.py (path:/tmp/pip-tJfYmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:25 INFO - Running setup.py (path:/tmp/pip-wm93YG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:25 INFO - Running setup.py (path:/tmp/pip-8VHfpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:25 INFO - Running setup.py (path:/tmp/pip-ZDJc1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:25 INFO - Running setup.py (path:/tmp/pip-eBJBTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:25 INFO - Running setup.py (path:/tmp/pip-vwZvH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:25 INFO - Running setup.py (path:/tmp/pip-2Oshsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:25 INFO - Running setup.py (path:/tmp/pip-v5lD3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:23:25 INFO - Running setup.py install for manifestparser 22:23:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:23:25 INFO - Running setup.py install for mozcrash 22:23:25 INFO - Running setup.py install for mozdebug 22:23:25 INFO - Running setup.py install for mozdevice 22:23:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for mozfile 22:23:26 INFO - Running setup.py install for mozhttpd 22:23:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for mozinfo 22:23:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for mozInstall 22:23:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for mozleak 22:23:26 INFO - Running setup.py install for mozlog 22:23:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for moznetwork 22:23:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for mozprocess 22:23:26 INFO - Running setup.py install for mozprofile 22:23:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:23:26 INFO - Running setup.py install for mozrunner 22:23:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:23:27 INFO - Running setup.py install for mozscreenshot 22:23:27 INFO - Running setup.py install for moztest 22:23:27 INFO - Running setup.py install for mozversion 22:23:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:23:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:23:27 INFO - Cleaning up... 22:23:27 INFO - Return code: 0 22:23:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:27 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:27 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 22:23:27 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 22:23:27 INFO - Using env: {'DISPLAY': ':0', 22:23:27 INFO - 'HOME': '/home/cltbld', 22:23:27 INFO - 'LANG': 'en_US.UTF-8', 22:23:27 INFO - 'LANGUAGE': 'en_US:en', 22:23:27 INFO - 'LOGNAME': 'cltbld', 22:23:27 INFO - 'MAIL': '/var/mail/cltbld', 22:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:27 INFO - 'MOZ_NO_REMOTE': '1', 22:23:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:27 INFO - 'NO_EM_RESTART': '1', 22:23:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:27 INFO - 'PWD': '/builds/slave/test', 22:23:27 INFO - 'SHELL': '/bin/bash', 22:23:27 INFO - 'SHLVL': '1', 22:23:27 INFO - 'TERM': 'linux', 22:23:27 INFO - 'TMOUT': '86400', 22:23:27 INFO - 'USER': 'cltbld', 22:23:27 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:27 INFO - '_': '/tools/buildbot/bin/python'} 22:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:27 INFO - Running setup.py (path:/tmp/pip-LoEZhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:27 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)) 22:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:27 INFO - Running setup.py (path:/tmp/pip-oYXZEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:27 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)) 22:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:27 INFO - Running setup.py (path:/tmp/pip-hKKxFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:27 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)) 22:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:27 INFO - Running setup.py (path:/tmp/pip-ZRJDPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:27 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)) 22:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:27 INFO - Running setup.py (path:/tmp/pip-Dfsf3q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:27 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)) 22:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:27 INFO - Running setup.py (path:/tmp/pip-M26lKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:28 INFO - Running setup.py (path:/tmp/pip-SaBk0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:28 INFO - Running setup.py (path:/tmp/pip-Poi8bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:28 INFO - Running setup.py (path:/tmp/pip-u1kWwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:28 INFO - Running setup.py (path:/tmp/pip-24tVlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:28 INFO - Running setup.py (path:/tmp/pip-VmIhPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:28 INFO - Running setup.py (path:/tmp/pip-I9Hp3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:28 INFO - Running setup.py (path:/tmp/pip-u435Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:28 INFO - Running setup.py (path:/tmp/pip-9kr7FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:28 INFO - Running setup.py (path:/tmp/pip-qxfIZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:28 INFO - Running setup.py (path:/tmp/pip-uTH44e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:28 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)) 22:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:28 INFO - Running setup.py (path:/tmp/pip-n4iHwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:28 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)) 22:23:28 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)) 22:23:28 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)) 22:23:28 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)) 22:23:28 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)) 22:23:28 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)) 22:23:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:30 INFO - Downloading blessings-1.6.tar.gz 22:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:23:30 INFO - Installing collected packages: blessings 22:23:30 INFO - Running setup.py install for blessings 22:23:30 INFO - Successfully installed blessings 22:23:30 INFO - Cleaning up... 22:23:30 INFO - Return code: 0 22:23:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:31 INFO - Reading from file tmpfile_stdout 22:23:31 INFO - Current package versions: 22:23:31 INFO - argparse == 1.2.1 22:23:31 INFO - blessings == 1.6 22:23:31 INFO - blobuploader == 1.2.4 22:23:31 INFO - docopt == 0.6.1 22:23:31 INFO - manifestparser == 1.1 22:23:31 INFO - mozInstall == 1.12 22:23:31 INFO - mozcrash == 0.17 22:23:31 INFO - mozdebug == 0.1 22:23:31 INFO - mozdevice == 0.48 22:23:31 INFO - mozfile == 1.2 22:23:31 INFO - mozhttpd == 0.7 22:23:31 INFO - mozinfo == 0.9 22:23:31 INFO - mozleak == 0.1 22:23:31 INFO - mozlog == 3.1 22:23:31 INFO - moznetwork == 0.27 22:23:31 INFO - mozprocess == 0.22 22:23:31 INFO - mozprofile == 0.28 22:23:31 INFO - mozrunner == 6.11 22:23:31 INFO - mozscreenshot == 0.1 22:23:31 INFO - mozsystemmonitor == 0.0 22:23:31 INFO - moztest == 0.7 22:23:31 INFO - mozversion == 1.4 22:23:31 INFO - psutil == 3.1.1 22:23:31 INFO - requests == 1.2.3 22:23:31 INFO - wsgiref == 0.1.2 22:23:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:31 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 22:23:31 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 22:23:31 INFO - Using env: {'DISPLAY': ':0', 22:23:31 INFO - 'HOME': '/home/cltbld', 22:23:31 INFO - 'LANG': 'en_US.UTF-8', 22:23:31 INFO - 'LANGUAGE': 'en_US:en', 22:23:31 INFO - 'LOGNAME': 'cltbld', 22:23:31 INFO - 'MAIL': '/var/mail/cltbld', 22:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:31 INFO - 'MOZ_NO_REMOTE': '1', 22:23:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:31 INFO - 'NO_EM_RESTART': '1', 22:23:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:31 INFO - 'PWD': '/builds/slave/test', 22:23:31 INFO - 'SHELL': '/bin/bash', 22:23:31 INFO - 'SHLVL': '1', 22:23:31 INFO - 'TERM': 'linux', 22:23:31 INFO - 'TMOUT': '86400', 22:23:31 INFO - 'USER': 'cltbld', 22:23:31 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:31 INFO - '_': '/tools/buildbot/bin/python'} 22:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 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)) 22:23:31 INFO - Cleaning up... 22:23:31 INFO - Return code: 0 22:23:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:23:31 INFO - Using env: {'DISPLAY': ':0', 22:23:31 INFO - 'HOME': '/home/cltbld', 22:23:31 INFO - 'LANG': 'en_US.UTF-8', 22:23:31 INFO - 'LANGUAGE': 'en_US:en', 22:23:31 INFO - 'LOGNAME': 'cltbld', 22:23:31 INFO - 'MAIL': '/var/mail/cltbld', 22:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:31 INFO - 'MOZ_NO_REMOTE': '1', 22:23:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:31 INFO - 'NO_EM_RESTART': '1', 22:23:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:31 INFO - 'PWD': '/builds/slave/test', 22:23:31 INFO - 'SHELL': '/bin/bash', 22:23:31 INFO - 'SHLVL': '1', 22:23:31 INFO - 'TERM': 'linux', 22:23:31 INFO - 'TMOUT': '86400', 22:23:31 INFO - 'USER': 'cltbld', 22:23:31 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:31 INFO - '_': '/tools/buildbot/bin/python'} 22:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:31 INFO - Downloading/unpacking jsonschema 22:23:31 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 22:23:31 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 22:23:31 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 22:23:31 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 22:23:31 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 22:23:31 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 22:23:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:23:33 INFO - Downloading/unpacking functools32 (from jsonschema) 22:23:33 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 22:23:33 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 22:23:33 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 22:23:33 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 22:23:33 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 22:23:33 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 22:23:33 INFO - Downloading functools32-3.2.3-2.tar.gz 22:23:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:23:33 INFO - warning: no files found matching '*.txt' 22:23:33 INFO - no previously-included directories found matching 'build' 22:23:33 INFO - no previously-included directories found matching 'dist' 22:23:33 INFO - no previously-included directories found matching '.git*' 22:23:33 INFO - Installing collected packages: jsonschema, functools32 22:23:33 INFO - Running setup.py install for functools32 22:23:33 INFO - warning: no files found matching '*.txt' 22:23:33 INFO - no previously-included directories found matching 'build' 22:23:33 INFO - no previously-included directories found matching 'dist' 22:23:33 INFO - no previously-included directories found matching '.git*' 22:23:33 INFO - Successfully installed jsonschema functools32 22:23:33 INFO - Cleaning up... 22:23:33 INFO - Return code: 0 22:23:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:33 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 0x7fe33fd5df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cae8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d795a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', '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 22:23:33 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 22:23:33 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 22:23:33 INFO - Using env: {'DISPLAY': ':0', 22:23:33 INFO - 'HOME': '/home/cltbld', 22:23:33 INFO - 'LANG': 'en_US.UTF-8', 22:23:33 INFO - 'LANGUAGE': 'en_US:en', 22:23:33 INFO - 'LOGNAME': 'cltbld', 22:23:33 INFO - 'MAIL': '/var/mail/cltbld', 22:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:33 INFO - 'MOZ_NO_REMOTE': '1', 22:23:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:33 INFO - 'NO_EM_RESTART': '1', 22:23:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:33 INFO - 'PWD': '/builds/slave/test', 22:23:33 INFO - 'SHELL': '/bin/bash', 22:23:33 INFO - 'SHLVL': '1', 22:23:33 INFO - 'TERM': 'linux', 22:23:33 INFO - 'TMOUT': '86400', 22:23:33 INFO - 'USER': 'cltbld', 22:23:33 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:33 INFO - '_': '/tools/buildbot/bin/python'} 22:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:34 INFO - Downloading/unpacking flake8 22:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:23:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:23:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:23:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:23:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:23:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:23:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:23:36 INFO - no previously-included directories found matching 'docs/_build' 22:23:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:23:36 INFO - Running setup.py install for pep8 22:23:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:23:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:23:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:23:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:23:37 INFO - no previously-included directories found matching 'docs/_build' 22:23:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:23:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:23:37 INFO - Cleaning up... 22:23:37 INFO - Return code: 0 22:23:37 INFO - Running post-action listener: _resource_record_post_action 22:23:37 INFO - Running post-action listener: _start_resource_monitoring 22:23:37 INFO - Starting resource monitoring. 22:23:37 INFO - ##### 22:23:37 INFO - ##### Running install step. 22:23:37 INFO - ##### 22:23:37 INFO - Running pre-action listener: _resource_record_pre_action 22:23:37 INFO - Running main action method: install 22:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:37 INFO - Reading from file tmpfile_stdout 22:23:37 INFO - Detecting whether we're running mozinstall >=1.0... 22:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:23:37 INFO - Reading from file tmpfile_stdout 22:23:37 INFO - Output received: 22:23:37 INFO - Usage: mozinstall [options] installer 22:23:37 INFO - Options: 22:23:37 INFO - -h, --help show this help message and exit 22:23:37 INFO - -d DEST, --destination=DEST 22:23:37 INFO - Directory to install application into. [default: 22:23:37 INFO - "/builds/slave/test"] 22:23:37 INFO - --app=APP Application being installed. [default: firefox] 22:23:37 INFO - mkdir: /builds/slave/test/build/application 22:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:23:53 INFO - Reading from file tmpfile_stdout 22:23:53 INFO - Output received: 22:23:53 INFO - /builds/slave/test/build/application/firefox/firefox 22:23:53 INFO - Running post-action listener: _resource_record_post_action 22:23:53 INFO - ##### 22:23:53 INFO - ##### Running run-tests step. 22:23:53 INFO - ##### 22:23:53 INFO - Running pre-action listener: _resource_record_pre_action 22:23:53 INFO - Running main action method: run_tests 22:23:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:23:53 INFO - Python 2.7.3 22:23:53 INFO - Return code: 0 22:23:53 INFO - grabbing minidump binary from tooltool 22:23:53 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')]} 22:23:53 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 0x1e08410>, '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 0x1e09310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e097a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:23:53 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 22:23:53 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 22:23:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:23:53 INFO - Return code: 0 22:23:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:23:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:23:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:23:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:23:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:23:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:23:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:23:54 INFO - Return code: 0 22:23:54 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/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 22:23:54 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/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 22:23:54 INFO - Using env: {'DISPLAY': ':0', 22:23:54 INFO - 'HOME': '/home/cltbld', 22:23:54 INFO - 'LANG': 'en_US.UTF-8', 22:23:54 INFO - 'LANGUAGE': 'en_US:en', 22:23:54 INFO - 'LOGNAME': 'cltbld', 22:23:54 INFO - 'MAIL': '/var/mail/cltbld', 22:23:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:23:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:23:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:54 INFO - 'MOZ_NO_REMOTE': '1', 22:23:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:23:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:23:54 INFO - 'NO_EM_RESTART': '1', 22:23:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:23:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:54 INFO - 'PWD': '/builds/slave/test', 22:23:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:23:54 INFO - 'SHELL': '/bin/bash', 22:23:54 INFO - 'SHLVL': '1', 22:23:54 INFO - 'TERM': 'linux', 22:23:54 INFO - 'TMOUT': '86400', 22:23:54 INFO - 'USER': 'cltbld', 22:23:54 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075', 22:23:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:54 INFO - '_': '/tools/buildbot/bin/python'} 22:23:54 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/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 22:23:54 INFO - mozversion application_buildid: 20160420210543 22:23:54 INFO - mozversion application_changeset: 849d0f0165a6b882894249ef4c465d989c002740 22:23:54 INFO - mozversion application_display_name: Nightly 22:23:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:23:54 INFO - mozversion application_name: Firefox 22:23:54 INFO - mozversion application_remotingname: firefox 22:23:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:23:54 INFO - mozversion application_vendor: Mozilla 22:23:54 INFO - mozversion application_version: 48.0a1 22:23:54 INFO - mozversion platform_buildid: 20160420210543 22:23:54 INFO - mozversion platform_changeset: 849d0f0165a6b882894249ef4c465d989c002740 22:23:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:23:54 INFO - mozversion platform_version: 48.0a1 22:23:54 INFO - using testdate: 1461216234 22:23:54 INFO - actual date: 1461216234 22:23:54 INFO - starting webserver on 'localhost:45171' 22:23:54 INFO - SUITE-START | Running 2 tests 22:23:54 INFO - TEST-START | dromaeo_css 22:23:54 INFO - operating with platform_type : linux_ 22:23:54 INFO - Initialising browser for dromaeo_css test... 22:23:54 INFO - TEST-INFO | started process 11523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpysU2xc/profile http://localhost:45171/getInfo.html) 22:23:59 INFO - PROCESS | 11523 | __metrics Screen width/height:1600/1200 22:23:59 INFO - PROCESS | 11523 | colorDepth:24 22:23:59 INFO - PROCESS | 11523 | Browser inner width/height: 1024/697 22:23:59 INFO - PROCESS | 11523 | __metrics 22:24:00 ERROR - PROCESS | 11523 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:24:01 INFO - TEST-INFO | 11523: exit 0 22:24:01 INFO - Browser initialized. 22:24:01 INFO - Running cycle 1/1 for dromaeo_css test... 22:24:01 INFO - TEST-INFO | started process 11603 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpysU2xc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:13 INFO - PROCESS | 11603 | RSS: Main: 155754496 22:24:13 INFO - PROCESS | 11603 | 22:25:04 INFO - PROCESS | 11603 | 1461216304629 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:27:16 INFO - PROCESS | 11603 | Cycle 1(1): loaded http://localhost:45171/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45171/tests/dromaeo/cssquery-ext.html) 22:27:17 INFO - PROCESS | 11603 | RSS: Main: 273313792 22:27:17 INFO - PROCESS | 11603 | 22:30:20 INFO - PROCESS | 11603 | Cycle 1(1): loaded http://localhost:45171/tests/dromaeo/cssquery-ext.html (next: http://localhost:45171/tests/dromaeo/cssquery-jquery.html) 22:30:20 INFO - PROCESS | 11603 | RSS: Main: 236740608 22:30:20 INFO - PROCESS | 11603 | 22:33:22 INFO - PROCESS | 11603 | Cycle 1(1): loaded http://localhost:45171/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45171/tests/dromaeo/cssquery-mootools.html) 22:33:23 INFO - PROCESS | 11603 | RSS: Main: 233713664 22:33:23 INFO - PROCESS | 11603 | 22:36:25 INFO - PROCESS | 11603 | Cycle 1(1): loaded http://localhost:45171/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45171/tests/dromaeo/cssquery-prototype.html) 22:36:26 INFO - PROCESS | 11603 | RSS: Main: 230313984 22:36:26 INFO - PROCESS | 11603 | 22:39:29 INFO - PROCESS | 11603 | Cycle 1(1): loaded http://localhost:45171/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45171/tests/dromaeo/cssquery-yui.html) 22:39:29 INFO - PROCESS | 11603 | RSS: Main: 218931200 22:39:29 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | Cycle 1(1): loaded http://localhost:45171/tests/dromaeo/cssquery-yui.html (next: http://localhost:45171/tests/dromaeo/cssquery-dojo.html) 22:42:32 INFO - PROCESS | 11603 | RSS: Main: 257474560 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | __start_tp_report 22:42:32 INFO - PROCESS | 11603 | _x_x_mozilla_page_load 22:42:32 INFO - PROCESS | 11603 | _x_x_mozilla_page_load_details 22:42:32 INFO - PROCESS | 11603 | |i|pagename|runs| 22:42:32 INFO - PROCESS | 11603 | |0;dojo.html;2567.781738552223;2646.973530264697;2652.270625577967;2658.627792109105;2660.3083198368427;4539.7276163430215;4621.930671039929;4626.814927402904;4638.67529272951;4642.675012749105;4602.700824446409;4637.142128706183;4696.060787842436;4708.199606066968;4717.481077081527;4302.978485107578;4349.717268377562;4357.782110894458;4397.570789493411;4430.136123455927;4286.378475121106;4301.204277208711;4410.49279332876;4428.0701052778795;4430.7784610769495;4169.4162817205615;4242.978785106055;4344.348347747863;4382.298832186835;4399.912001759963;4228.365745138223;4249.957500425018;4304.504981927056;4314.158739045887;4314.395984562164;4310.4396428464415;4321.092570560186;4323.113761678848;4327.7186982846015;4354.738715677069;4439.422875026258;4526.7283962962;4576.199165146119;4584.426946631671;4639.930401043987;4951.752412379366;4951.752412379366;4953.5541801237705;4985.900281994376;4997.925031124536;4946.332245146895;4948.257761335793;4966.329545511346;4986.40163180417;5009.524095210949;4471.441069866267;4607.562281583244;4642.117997580415;4642.210824159901;4675.0883577702025;5116.667416617908;5138.434772175057;5139.331886854685;5150.587952963829;5157.097507936096;4913.828016019422;4950.3069570260195;4955.4301255356095;4955.653104282737;4997.075541024849;4885.096257192431;4951.282064100686;5001.774920128603;5047.495250474996;5056.469070747592;4845.927311090337;4866.6106711463;4875.536824001714;4881.8535443936735;4907.55831975124;4841.612670986313;4844.249141383091;4853.417589889236;4859;4892.143874821892;4044.8584299549375;4049.6962727795535;4112.609302116294;4119.279126152911;4119.485064366954;3602.8018458984484;3613.457981302826;3616.764910280823;3648.4527320901548;3660.4509323601674;4295.699301048897;4298.247806633826;4300.462442194726;4300.913981720348;4333.523312435629;4778.307145463888;4931.3589233399425;4963;4979.0788704089855;5024.974875125601;4322.5677432256525;4330.069035157393;4340.65274778017;4346.782660866944;4370.366296886934;4307.978460107679;4477.350784136282;4478.507364189936;4484.596386325246;4514.571115743999;4728.527147285244;4771.23660214364;4776.808927642995;4786.329913812069;4815.494373090915;4985.675931064469;5006.749662516787;5016.2224855147515;5019.447860735389;5054.570361519312;4566.862994110182;4721.4570324413135;4764.166270902648;4787.54518320759;4837.298591704253;4899.338589290543;4922.384701912261;4924.8276310328965;4952.4057113146655;4973.378327709036;4993.850184494471;4994.700317980933;5001.399832020181;5002.774875130701;5053.823116190915;4694.835680751157;4797.064572408347;4852.272159176152;4889.242167464049;4917.950820491749;4768.237082066853;4784.9951510183255;4796.640251981045;4827.95172048275;4828.806847726051;4697.719331428634;4721.362616046789;4727.267273572602;4734.502877197845;4777.761111944319;4689.484156742687;4695.600873925755;4810.39870016248;4816.95183048179;4894.535019173173;4682.4381074271305;4732.219183834629;4765.356676848581;4768.189407800613;4791.784369703302 22:42:32 INFO - PROCESS | 11603 | |1;ext.html;552.3592583721534;581.933077696065;600.4355905448875;604.5496105401473;607.5382709141052;33532;34932.952011439666;34972;35010.12474688128;35026.12434689134;35701.46447803279;36704.26591468169;36752.26495470096;36800.26399472009;36800.26399472015;7181.820454488653;7375.815604609874;7378.225286344937;7641.426892983035;7835.804104897366;9716.562754676057;10116.14012808912;10126.594936202542;10130.088292053713;10132.949335253314;9751.414915105117;9846.212303015778;9846.950765246163;9848.852267215998;9856;7339.449541284372;9466.053394660548;9664.661736839213;9733.90266097337;9776.120149186536;9610.66362677303;9731.12419882207;9749.853752193723;9758.951205243999;9764.072413120743;38493.80753096216;39264;39402.21195576072;39434.21131577381;39484.60515394838;3229.628592711821;3416.128887133765;3565.286942611462;3784.8675296364772;3830.272248272819;29404.558931616044;29425.264368390748;29826.10521684353;29853.40293194146;29974.85012574923;3496.1958749487762;3555.2178520725397;3576.356255873942;3618.710503159767;3700.9074773130887;222168;222231;222714.88642556846;222854;223373;56312.15531766945;56698.7165064172;57270.42729572734;57355.426445735844;57358.7132064337;58663;59160;59162;59360;59396;13558.525451609146;13725.107867988549;13730.313484325743;13738.038337316291;13763.105398149088;9215.354925155309;9587.280953928483;9587.280953928483;9920.553575089138;10257.692269231935;17951;18049.7292540612;18227.817721822612;18310.542236444195;18338.541536461427;6532;7044.604834356343;7115.89326160108;7234.204237533867;7275.89086163708;904.1997831918668;907.7912080221586;915.2586405011962;959.9760005999766;965.1458459263462;26813.597796033075;28257.44584047856;28566.428671426456;28847.5672864903;28989.85505072461;984.7488890333062;1002.4436437776958;1035.6219979707353;1039.27770199712;1082.1234799812178;14587.781183282259;15083.622909427351;15131.924340378448;15341.9232903837;15345.46290879814;335282;339188;342004;342055;342271;48377.758111209216;49098.75450622795;49116.7544162284;49144.754276229105;49201.26198107031;1088.8312311591717;1178.640514643025;1209.2683926224684;1213.3266037349356;1216.8235605837103;25815.354616134748;28116.156515304574;28330.85834570814;28817.42365152727;28856.134315970554;39557.60442395539;39708.801455992536;39960.800196;40047;40087.39868901911;224001;226104;227094;227272;228173;31700.682993169772;32854.507182391804;36947;37963;38450.80774596221;38508.80745596254;39801.80099099486;40214.19571608493;40214.396784047676;40825.795871020455;34471.31057378888;34773.652263477044;34998.650013500555;35357;35472;34218.82890585631;34322.31355372829;34475;34880.82559587288;35138.824305878305;30652;30993.53509697311;31166;31292.530612040384;35338.64661353456;30576.694233057384;31200.063998080095;31385.843070785413;31456;31566.36867262596;24109.638355425035;24294;24773.752262477145;25224.24327270185;25263.49473010493 22:42:32 INFO - PROCESS | 11603 | |2;jquery.html;365.89389077167624;368.73819588092454;368.9723270754693;374.0270075352517;377.381095004193;15723.091228122805;15861.20693965303;16168;16288.10415427151;16289.674206515863;15473.45842895499;16396.344146234136;17104.914475427606;17128.914355428205;17136;4929.457759646436;5077.314562534066;5109.41241757199;5116.411612664536;5120.718360490171;3630.183208778017;3830;3834.6357096075967;3837.7505462144873;5064.11378008847;3933.0167458135465;5780.190773291722;5869.647821130725;5880.059190529516;5884.08796636522;5095.490450954912;5690.374058853523;5799.9130013049835;5800.187973683687;5805.941940580582;3401.3027329397414;5209.374875015023;5215.374155101412;5219.217117432378;5221.242919776649;12300.75398492034;15431.305591248421;15724.44964426251;17267.222974966156;18928.864268143956;1763.3211213682696;1770.3007312111772;1802.8371700253263;1840.815918408162;1872.8220819022172;9194.632214711404;9418.717438476788;9642.084002019797;10027.548760305805;13073.803892941614;1597.3450885137038;1621.9026858388534;1630.5678995066316;1638.893471924333;1793.9103044847836;59169.40830591682;65549.34450655528;68906;68986.65506672485;69895.65052174707;10083.848742268872;10149.071676381463;10200.602858328288;10357.067863892285;10483.947580262049;13999.930000349934;14084.647883802987;14180.432782688576;14241.85758142426;14254.3585538651;6849.280825513349;6937.9653101735175;6987.196472405637;7037.331453511908;7084.43324534036;6437.613743175424;6466.1270728452;6497.64262965532;6566.770163044271;6593.472522198309;4489.595936365743;4491.977540112279;4492;4526.864194074183;4556.066006468666;1681.6636672665516;1797.2451570340488;1846.9353572624893;1866.2628261836498;1876.8498520118358;788.4283894176791;851.9701810436729;857.9656813727381;875.7898104455012;972.1348000279756;16483.505494835175;16708.832911670972;16783.83216167847;16970.490885273462;17387.304507819797;825.3149885594942;837.4807619276088;860.767592749954;865.0830120072617;895.7268033249921;8887.244584210415;8986.685466008788;9383;9387.624495020124;9483.573239204252;393653;397202;397976;398208;398605;67777.3222267771;70422.64788676024;77341.61329193319;79641;80114.19885801183;865.6970060478782;995.0348162282575;1009.4448053570507;1045.5869931377215;1060.9204309676804;13817.654558636115;14559.199244041256;14588.85411145875;14607.196604186447;15086.24568771535;30382;30952.69047309498;31426.371472570878;31619.525707113953;31652.683473165896;39789.60210397975;40462;40832.591674082876;44478.3328250083;45697;6192.81421557354;7777.805554861174;7847.646855891613;8190.058143313595;8215.917840821754;11114.777704445703;11817;11930.821037684269;11976.76046479083;12465.189762665397;10522.736931576772;10584.41785701763;10674.62638807638;10706.571737130738;10795.244332896937;10517.631882884063;10604.416757078434;10612.257141999986;10651.094656954245;10685.519151638351;10460;10721.41032243234;10756.085732712494;10866.239363244496;10876.12990960737;7839.33365663925;7840.647170877211;8090.959545202236;8093.31206847401;8194.672213111648;10190.898091018995;10326.535305911102;10367.84448233262;10451.216158788273;10703.732406689895 22:42:32 INFO - PROCESS | 11603 | |3;mootools.html;1134.9546018159274;1387.3756809435754;1448.073233130796;1517.309624121025;1688.603178253111;7395.482316237865;7707.30634242918;7713.228677132284;7719.614019299041;7739.26476984687;7530.171681115073;7649.426293028031;7665.080190377163;7669.4247931405225;7678.6544605492745;1876.878002929812;1939.806019398056;2183.355910006544;2228.9888550557225;2260.5365899990543;2255.3008567344095;2271.8636881787147;2277.81777457803;2279.5326957973575;2280.5552917181158;2230.8661480311234;2239.7200349956256;2266.093562574975;2269.954600907981;2273.193016479151;1871.4853415310831;2117.2589593642256;2173.7500187478327;2181.5854987552316;2209.8232141428725;2201.405620482477;2201.757806641268;2203.372038968886;2215.080741492279;2227.175944900381;7635.961820190863;7818.960905195495;7845.254700803472;7849.8037549061155;7858.449908506407;4435.290353543418;4593.977030114828;4608.400907881954;4615.076984603093;4629.629629629621;2896.681365049864;2917.5186094294418;2967.4510215609967;3012.0813151988636;3062.846857657108;1009.8030883977627;1727.9481615551554;1766.0992893624343;1783.4025601423539;1804.9548761281073;10163.847542286872;10165.949170254175;10178.643747468803;10213.33613315132;10221.64224252155;3977.065389633434;4069.348904175377;4081.142959978438;4184.786411940503;4214.115035842446;8169.101398846122;8171.83656326882;8190.221928916743;8196.057453392936;8197.83604327922;4455.042165934275;4458.621017213508;4460.330950357472;4471.821127154943;4476.462824461086;3692.7045836333027;4158.106007208465;4247.936280955788;4254.574542545721;4255.76593287366;3373.089265898243;3391.4912763085736;3470.444728843373;3489.668481494254;3502.824858757052;1051.232600201857;1068.8129577324094;1076.4832880217539;1077.7682798198425;1082.983755243672;847.1020718038899;853.2661910756744;861.3023451004706;869.7043005378075;888.2804928008334;5405.540529055074;5409.837704868782;5441.183822426668;5478.8248000437925;5632.098864181711;418.9308764053958;435.48830124603467;436.5721592838972;438.43660895749446;442.6879050269552;3586.605473397924;3619.8009109498703;3680.0799800049986;3734.514513113332;3744.588095309514;9237.815243695222;9559.617615295449;9569.33014688979;9728.367656102331;9745.463999479955;8873.778155546033;9216.262698984065;9271.536423178813;9278.72163835086;9333.766655833659;6960.408365289007;6976.616286104313;7107.964460177666;7108.822279442952;7135.143782746104;5433.538149257357;5440.619156659154;5495.917561236664;5533.446655334435;5559.72201389937;8505.064442911398;8590;8668.566571671518;8765.693200737944;8795.164459376349;8533.445326053787;8704.173103555153;9026.81946361056;9130.497822619554;9144.771380715536;6069.817905462844;6288.402601752826;6696.83257918556;6710.4631629470505;6771.255161932282;7280.344768970818;7338.889916651361;7384.261573842788;7392.704291828267;7404.518706284074;6598.406143447113;6765.391114799691;6783.762568310085;6879.724811007503;6894.758683446055;6507.967460162668;6519.511036672174;6532;6578.736850525926;6824.590524568542;6360.713767880549;6429.710663020269;6443.291237963726;6450.225972883284;6452.516061295328;4419.756913369795;4591.885202869955;4658.371119898906;4698.459677137173;4700.153972285021;4675.275332323496;4734.195186818318;4735.763211839326;4810.302506136661;4815.398075240595 22:42:32 INFO - PROCESS | 11603 | |4;prototype.html;294.16163932791545;305.06497584902274;306.3046883574287;311.28715242095586;314.42303373309977;4868.415790105183;5012.423571289303;5054.84835454936;5100.77046532906;5126.641135120539;4817.470078291385;5044.6216533760025;5056.317397151383;5056.949430505694;5058.190689489682;3969.126792105732;3976.2246361959283;3981.9203615927663;3987.4018897165365;4003.679705623543;3739.364308067635;3832.6358995895343;3983.760974341534;3987.5214974203136;3990.1421194443187;3928.9803550982347;3947.723659343847;3949.3286141355893;3953.3279342511696;3964.2666106770193;3944;3969.6625786808154;3972.205558888205;3974.145558704892;3976.6619837313865;3467.809270490122;3510.6840384365273;3514.2268700885766;3517.7713448626014;3531.523244362004;4742.19382704941;4803.279508073782;4808.639352048575;4839.588634966032;4855.902881942341;4423.461231015207;4451.109778044404;4454.754988475633;4459.620932220765;4474.485434175079;5267.578593712494;5296.470352964711;5299.502328463234;5304.575633949275;5305.761240744176;3509.4209455439823;3718.0704823794053;3721.0697325668584;3724.6461586149544;3725.1618385863235;5494.395616482224;5595;5595.300587426572;5601.495865372095;5607.317804658586;3811.542614886176;3812.4090765931323;3813;3813.0848596336677;3814.5422549293708;3590.605033446371;3773.962260377361;3778.7732736035928;3862.7489213201616;3876.4379165020914;4337.327714204303;4376.277914144194;4380.452443444569;4382.824687012548;4384.079343337871;5315.096433606296;5338.439463856239;5343.7862485500145;5348.625596208228;5354.518093371616;1267.176335382009;1300.7398520295978;1302.9869701303055;1311.278796661832;1311.9344032798322;856.8157846063002;867.314821291173;872.1496540872598;872.3413822563923;873.1966590736411;4664.86005419838;4689;4689.343491911135;4689.882752931136;4714.905701885943;5326.493983071602;5354.491323324278;5383.973080134574;5419.539339156137;5435.347758269033;4544.954550454519;4705.788239529229;4720.669553131248;4721.763911804396;4730.219513780187;2876.525373313422;2905.387509932008;2907.592936988823;2910.752586030169;2910.8690108945148;5150.613703972217;5178.663386879841;5180.7150606717;5189.818356357585;5195.116830138885;5472.885249176055;5549.278593782863;5597.188407680864;5606.103023516218;5607;5075.162598171334;5099.184130539096;5178.974105129526;5184.11869982104;5189.584833213334;5310.734463276898;5331.760070796746;5350.170723537858;5376.892462150813;5383.461653834585;5155.458676838878;5284.207368894697;5302.946970530245;5308.336457942757;5329.7868085277705;5100.69932167295;5111.693298402108;5126.2054381570915;5136.178211486144;5148.1248187808915;3794.2601192767147;3798.316303065364;3813.675837553839;3816.5229346331707;3830.1190726132586;5193.818216362409;5227.215917612389;5339.546138578109;5406.378266499403;5431.945680543302;5172.19830926207;5321.228421878884;5336.226247194213;5345.1982202669915;5358.223057656696;5292.867678308073;5319.760610772447;5343.332083489564;5346.786128554814;5349.598780091431;5122.334096567572;5229.496237520946;5251.947480525146;5257.63196576236;5270.920936185884;5348.5988550858065;5363.919541206807;5375.220593014069;5388.892222155613;5402.406290144496;5245.685258884327;5261.89476210466;5279.762410691605;5281.498257665516;5294.814681486129 22:42:32 INFO - PROCESS | 11603 | |5;yui.html;654.4737313968083;699.0981633692537;701.7614011236179;709.2659097833744;725.7242247945783;10551.841722374162;11421.600243991463;11622.128340374475;11637.941810290937;11669.533218671242;10881;10908.672739817817;10928.726781830439;10936.726581835459;10951.014408703215;626.7900253415089;773.7253275087334;785.8939043229177;786.8544319300919;787.1262898183012;739.6560599321315;782.3350152370497;782.6947490478718;783.5181363461459;784.7214238945178;822.3380178955938;824.3446460064233;824.9793755156139;829.8298848736025;829.9958500207521;656.5010591950105;674.7975607317785;820.9220124088203;821.9506829590214;823.4277177361706;497.6640767481935;584.2142318581501;604.5133667397743;604.8457643300931;605.2162449627742;11459.656210313704;11487.770244595145;11491.138164637685;11510.769784604345;11539.653810385618;11434.199606027581;12072.879271207352;12168.26990380571;12636.557720479832;12731.554395596202;9128.543572821398;9192.632294708204;9335.719928402159;9353.298502612262;9365;3256.902292931216;3283.1792051987004;3314.386838434897;3338.2822693121097;3404.7957122572725;43326;43534.12931741348;43566.56433435657;43737;45447.77276113598;8903.376763626484;9089.181973622406;9098.272138228926;9110.681126160553;9149.268058555299;16632.168391580373;16782.160891955355;16820.495385138467;16852;16856.747148792532;6724.092247546617;6744.932550674431;6904.13698287714;6935.237123916365;7001.684924178424;3673.9265214695556;3704.111013356775;3726.5341832270965;3742.101895545104;3806.54321481424;2902.521084021113;2962.140979116032;2962.3927094945484;2966.0508637235876;2967.4806908790874;498.03629975996563;515.5617724933851;518.3158231134942;524.5488879563572;524.7009204753253;501.8745313671582;600.6636283681153;631.9210098737658;645.5642441352162;658.2791842931982;7409.925900741031;7640.617969101522;7694.499857509356;7849.1758365370815;8054.516728996279;638.1863124516279;638.7636574467476;641.8663805095265;641.9967900160469;651.2673242602073;3539.345221134098;3673.559172899269;3717.219383929351;3750.474933509311;3784.375578029594;49802;50225.74887125614;50784.74607626938;50811;50856.491435085176;43002.13995720068;43077;43157.13685726268;43532.3470147948;43932.78033609811;39550.80224598916;40198.19603607911;40352.192956140716;40977.79511102485;41143.38284925729;7098.3256590623805;7167.283271672791;7206.855862882609;7269.200387957426;7285.7449989252245;27555.17334479969;27571.448571028868;28169.28159784871;28170.577441337988;28309;34343.4848477268;34362;35895;35967.1008224786;36591.08522286964;9129.406588571721;9168.633254670005;9218.52503599421;9224.215941645036;9293.907060929305;9802.950985245314;9889.55497002651;9917.206623470249;9966.651167209402;10050.748731281776;9168.770780730536;9468.28987825902;9485.952570237105;9525.904740952501;9790.804183916423;9271.953640231755;9391.953040234755;9655.131038206595;9679.128878400978;9686.9031309686;9123.863142052742;9270.119338662817;9400.200982916529;9444.433333999981;9671.323007389698;7793.181715919747;7836.37309015266;7941.563214023284;7981.521108733495;7988.680452781824;7748;7863.9213607863185;8066.556339401389;8193.098759136372;8211.01467823865 22:42:32 INFO - PROCESS | 11603 | __end_tp_report 22:42:32 INFO - PROCESS | 11603 | __start_cc_report 22:42:32 INFO - PROCESS | 11603 | _x_x_mozilla_cycle_collect,1122 22:42:32 INFO - PROCESS | 11603 | __end_cc_report 22:42:32 INFO - PROCESS | 11603 | __startTimestamp1461217352629__endTimestamp 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | ------- Summary: start ------- 22:42:32 INFO - PROCESS | 11603 | Number of tests: 6 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | [#0] dojo.html Cycles:165 Average:4594.39 Median:4721.46 stddev:476.51 (10.1%) stddev-sans-first:450.67 22:42:32 INFO - PROCESS | 11603 | Values: 2567.8 2647.0 2652.3 2658.6 2660.3 4539.7 4621.9 4626.8 4638.7 4642.7 4602.7 4637.1 4696.1 4708.2 4717.5 4303.0 4349.7 4357.8 4397.6 4430.1 4286.4 4301.2 4410.5 4428.1 4430.8 4169.4 4243.0 4344.3 4382.3 4399.9 4228.4 4250.0 4304.5 4314.2 4314.4 4310.4 4321.1 4323.1 4327.7 4354.7 4439.4 4526.7 4576.2 4584.4 4639.9 4951.8 4951.8 4953.6 4985.9 4997.9 4946.3 4948.3 4966.3 4986.4 5009.5 4471.4 4607.6 4642.1 4642.2 4675.1 5116.7 5138.4 5139.3 5150.6 5157.1 4913.8 4950.3 4955.4 4955.7 4997.1 4885.1 4951.3 5001.8 5047.5 5056.5 4845.9 4866.6 4875.5 4881.9 4907.6 4841.6 4844.2 4853.4 4859.0 4892.1 4044.9 4049.7 4112.6 4119.3 4119.5 3602.8 3613.5 3616.8 3648.5 3660.5 4295.7 4298.2 4300.5 4300.9 4333.5 4778.3 4931.4 4963.0 4979.1 5025.0 4322.6 4330.1 4340.7 4346.8 4370.4 4308.0 4477.4 4478.5 4484.6 4514.6 4728.5 4771.2 4776.8 4786.3 4815.5 4985.7 5006.7 5016.2 5019.4 5054.6 4566.9 4721.5 4764.2 4787.5 4837.3 4899.3 4922.4 4924.8 4952.4 4973.4 4993.9 4994.7 5001.4 5002.8 5053.8 4694.8 4797.1 4852.3 4889.2 4918.0 4768.2 4785.0 4796.6 4828.0 4828.8 4697.7 4721.4 4727.3 4734.5 4777.8 4689.5 4695.6 4810.4 4817.0 4894.5 4682.4 4732.2 4765.4 4768.2 4791.8 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | [#1] ext.html Cycles:180 Average:42907.60 Median:35238.74 stddev:70289.14 (199.5%) stddev-sans-first:70414.38 22:42:32 INFO - PROCESS | 11603 | Values: 552.4 581.9 600.4 604.5 607.5 33532.0 34933.0 34972.0 35010.1 35026.1 35701.5 36704.3 36752.3 36800.3 36800.3 7181.8 7375.8 7378.2 7641.4 7835.8 9716.6 10116.1 10126.6 10130.1 10132.9 9751.4 9846.2 9847.0 9848.9 9856.0 7339.4 9466.1 9664.7 9733.9 9776.1 9610.7 9731.1 9749.9 9759.0 9764.1 38493.8 39264.0 39402.2 39434.2 39484.6 3229.6 3416.1 3565.3 3784.9 3830.3 29404.6 29425.3 29826.1 29853.4 29974.9 3496.2 3555.2 3576.4 3618.7 3700.9 222168.0 222231.0 222714.9 222854.0 223373.0 56312.2 56698.7 57270.4 57355.4 57358.7 58663.0 59160.0 59162.0 59360.0 59396.0 13558.5 13725.1 13730.3 13738.0 13763.1 9215.4 9587.3 9587.3 9920.6 10257.7 17951.0 18049.7 18227.8 18310.5 18338.5 6532.0 7044.6 7115.9 7234.2 7275.9 904.2 907.8 915.3 960.0 965.1 26813.6 28257.4 28566.4 28847.6 28989.9 984.7 1002.4 1035.6 1039.3 1082.1 14587.8 15083.6 15131.9 15341.9 15345.5 335282.0 339188.0 342004.0 342055.0 342271.0 48377.8 49098.8 49116.8 49144.8 49201.3 1088.8 1178.6 1209.3 1213.3 1216.8 25815.4 28116.2 28330.9 28817.4 28856.1 39557.6 39708.8 39960.8 40047.0 40087.4 224001.0 226104.0 227094.0 227272.0 228173.0 31700.7 32854.5 36947.0 37963.0 38450.8 38508.8 39801.8 40214.2 40214.4 40825.8 34471.3 34773.7 34998.7 35357.0 35472.0 34218.8 34322.3 34475.0 34880.8 35138.8 30652.0 30993.5 31166.0 31292.5 35338.6 30576.7 31200.1 31385.8 31456.0 31566.4 24109.6 24294.0 24773.8 25224.2 25263.5 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | [#2] jquery.html Cycles:180 Average:23818.74 Median:375.70 stddev:65354.07 (17395.1%) stddev-sans-first:65513.69 22:42:32 INFO - PROCESS | 11603 | Values: 365.9 368.7 369.0 374.0 377.4 15723.1 15861.2 16168.0 16288.1 16289.7 15473.5 16396.3 17104.9 17128.9 17136.0 4929.5 5077.3 5109.4 5116.4 5120.7 3630.2 3830.0 3834.6 3837.8 5064.1 3933.0 5780.2 5869.6 5880.1 5884.1 5095.5 5690.4 5799.9 5800.2 5805.9 3401.3 5209.4 5215.4 5219.2 5221.2 12300.8 15431.3 15724.4 17267.2 18928.9 1763.3 1770.3 1802.8 1840.8 1872.8 9194.6 9418.7 9642.1 10027.5 13073.8 1597.3 1621.9 1630.6 1638.9 1793.9 59169.4 65549.3 68906.0 68986.7 69895.7 10083.8 10149.1 10200.6 10357.1 10483.9 13999.9 14084.6 14180.4 14241.9 14254.4 6849.3 6938.0 6987.2 7037.3 7084.4 6437.6 6466.1 6497.6 6566.8 6593.5 4489.6 4492.0 4492.0 4526.9 4556.1 1681.7 1797.2 1846.9 1866.3 1876.8 788.4 852.0 858.0 875.8 972.1 16483.5 16708.8 16783.8 16970.5 17387.3 825.3 837.5 860.8 865.1 895.7 8887.2 8986.7 9383.0 9387.6 9483.6 393653.0 397202.0 397976.0 398208.0 398605.0 67777.3 70422.6 77341.6 79641.0 80114.2 865.7 995.0 1009.4 1045.6 1060.9 13817.7 14559.2 14588.9 14607.2 15086.2 30382.0 30952.7 31426.4 31619.5 31652.7 39789.6 40462.0 40832.6 44478.3 45697.0 6192.8 7777.8 7847.6 8190.1 8215.9 11114.8 11817.0 11930.8 11976.8 12465.2 10522.7 10584.4 10674.6 10706.6 10795.2 10517.6 10604.4 10612.3 10651.1 10685.5 10460.0 10721.4 10756.1 10866.2 10876.1 7839.3 7840.6 8091.0 8093.3 8194.7 10190.9 10326.5 10367.8 10451.2 10703.7 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | [#3] mootools.html Cycles:180 Average:5072.14 Median:4717.17 stddev:2768.18 (58.7%) stddev-sans-first:2760.13 22:42:32 INFO - PROCESS | 11603 | Values: 1135.0 1387.4 1448.1 1517.3 1688.6 7395.5 7707.3 7713.2 7719.6 7739.3 7530.2 7649.4 7665.1 7669.4 7678.7 1876.9 1939.8 2183.4 2229.0 2260.5 2255.3 2271.9 2277.8 2279.5 2280.6 2230.9 2239.7 2266.1 2270.0 2273.2 1871.5 2117.3 2173.8 2181.6 2209.8 2201.4 2201.8 2203.4 2215.1 2227.2 7636.0 7819.0 7845.3 7849.8 7858.4 4435.3 4594.0 4608.4 4615.1 4629.6 2896.7 2917.5 2967.5 3012.1 3062.8 1009.8 1727.9 1766.1 1783.4 1805.0 10163.8 10165.9 10178.6 10213.3 10221.6 3977.1 4069.3 4081.1 4184.8 4214.1 8169.1 8171.8 8190.2 8196.1 8197.8 4455.0 4458.6 4460.3 4471.8 4476.5 3692.7 4158.1 4247.9 4254.6 4255.8 3373.1 3391.5 3470.4 3489.7 3502.8 1051.2 1068.8 1076.5 1077.8 1083.0 847.1 853.3 861.3 869.7 888.3 5405.5 5409.8 5441.2 5478.8 5632.1 418.9 435.5 436.6 438.4 442.7 3586.6 3619.8 3680.1 3734.5 3744.6 9237.8 9559.6 9569.3 9728.4 9745.5 8873.8 9216.3 9271.5 9278.7 9333.8 6960.4 6976.6 7108.0 7108.8 7135.1 5433.5 5440.6 5495.9 5533.4 5559.7 8505.1 8590.0 8668.6 8765.7 8795.2 8533.4 8704.2 9026.8 9130.5 9144.8 6069.8 6288.4 6696.8 6710.5 6771.3 7280.3 7338.9 7384.3 7392.7 7404.5 6598.4 6765.4 6783.8 6879.7 6894.8 6508.0 6519.5 6532.0 6578.7 6824.6 6360.7 6429.7 6443.3 6450.2 6452.5 4419.8 4591.9 4658.4 4698.5 4700.2 4675.3 4734.2 4735.8 4810.3 4815.4 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | [#4] prototype.html Cycles:180 Average:4385.84 Median:5056.63 stddev:1285.61 (25.4%) stddev-sans-first:1251.99 22:42:32 INFO - PROCESS | 11603 | Values: 294.2 305.1 306.3 311.3 314.4 4868.4 5012.4 5054.8 5100.8 5126.6 4817.5 5044.6 5056.3 5056.9 5058.2 3969.1 3976.2 3981.9 3987.4 4003.7 3739.4 3832.6 3983.8 3987.5 3990.1 3929.0 3947.7 3949.3 3953.3 3964.3 3944.0 3969.7 3972.2 3974.1 3976.7 3467.8 3510.7 3514.2 3517.8 3531.5 4742.2 4803.3 4808.6 4839.6 4855.9 4423.5 4451.1 4454.8 4459.6 4474.5 5267.6 5296.5 5299.5 5304.6 5305.8 3509.4 3718.1 3721.1 3724.6 3725.2 5494.4 5595.0 5595.3 5601.5 5607.3 3811.5 3812.4 3813.0 3813.1 3814.5 3590.6 3774.0 3778.8 3862.7 3876.4 4337.3 4376.3 4380.5 4382.8 4384.1 5315.1 5338.4 5343.8 5348.6 5354.5 1267.2 1300.7 1303.0 1311.3 1311.9 856.8 867.3 872.1 872.3 873.2 4664.9 4689.0 4689.3 4689.9 4714.9 5326.5 5354.5 5384.0 5419.5 5435.3 4545.0 4705.8 4720.7 4721.8 4730.2 2876.5 2905.4 2907.6 2910.8 2910.9 5150.6 5178.7 5180.7 5189.8 5195.1 5472.9 5549.3 5597.2 5606.1 5607.0 5075.2 5099.2 5179.0 5184.1 5189.6 5310.7 5331.8 5350.2 5376.9 5383.5 5155.5 5284.2 5302.9 5308.3 5329.8 5100.7 5111.7 5126.2 5136.2 5148.1 3794.3 3798.3 3813.7 3816.5 3830.1 5193.8 5227.2 5339.5 5406.4 5431.9 5172.2 5321.2 5336.2 5345.2 5358.2 5292.9 5319.8 5343.3 5346.8 5349.6 5122.3 5229.5 5251.9 5257.6 5270.9 5348.6 5363.9 5375.2 5388.9 5402.4 5245.7 5261.9 5279.8 5281.5 5294.8 22:42:32 INFO - PROCESS | 11603 | 22:42:32 INFO - PROCESS | 11603 | [#5] yui.html Cycles:180 Average:11884.58 Median:638.47 stddev:13698.40 (2145.5%) stddev-sans-first:13710.87 22:42:32 INFO - PROCESS | 11603 | Values: 654.5 699.1 701.8 709.3 725.7 10551.8 11421.6 11622.1 11637.9 11669.5 10881.0 10908.7 10928.7 10936.7 10951.0 626.8 773.7 785.9 786.9 787.1 739.7 782.3 782.7 783.5 784.7 822.3 824.3 825.0 829.8 830.0 656.5 674.8 820.9 822.0 823.4 497.7 584.2 604.5 604.8 605.2 11459.7 11487.8 11491.1 11510.8 11539.7 11434.2 12072.9 12168.3 12636.6 12731.6 9128.5 9192.6 9335.7 9353.3 9365.0 3256.9 3283.2 3314.4 3338.3 3404.8 43326.0 43534.1 43566.6 43737.0 45447.8 8903.4 9089.2 9098.3 9110.7 9149.3 16632.2 16782.2 16820.5 16852.0 16856.7 6724.1 6744.9 6904.1 6935.2 7001.7 3673.9 3704.1 3726.5 3742.1 3806.5 2902.5 2962.1 2962.4 2966.1 2967.5 498.0 515.6 518.3 524.5 524.7 501.9 600.7 631.9 645.6 658.3 7409.9 7640.6 7694.5 7849.2 8054.5 638.2 638.8 641.9 642.0 651.3 3539.3 3673.6 3717.2 3750.5 3784.4 49802.0 50225.7 50784.7 50811.0 50856.5 43002.1 43077.0 43157.1 43532.3 43932.8 39550.8 40198.2 40352.2 40977.8 41143.4 7098.3 7167.3 7206.9 7269.2 7285.7 27555.2 27571.4 28169.3 28170.6 28309.0 34343.5 34362.0 35895.0 35967.1 36591.1 9129.4 9168.6 9218.5 9224.2 9293.9 9803.0 9889.6 9917.2 9966.7 10050.7 9168.8 9468.3 9486.0 9525.9 9790.8 9272.0 9392.0 9655.1 9679.1 9686.9 9123.9 9270.1 9400.2 9444.4 9671.3 7793.2 7836.4 7941.6 7981.5 7988.7 7748.0 7863.9 8066.6 8193.1 8211.0 22:42:32 INFO - PROCESS | 11603 | -------- Summary: end -------- 22:42:32 INFO - PROCESS | 11603 | 22:42:33 INFO - TEST-INFO | 11603: exit 0 22:42:33 INFO - TEST-OK | dromaeo_css | took 1118346ms 22:42:33 INFO - TEST-START | kraken 22:42:33 INFO - operating with platform_type : linux_ 22:42:33 INFO - Initialising browser for kraken test... 22:42:33 INFO - TEST-INFO | started process 11781 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGs_BNY/profile http://localhost:45171/getInfo.html) 22:42:37 INFO - PROCESS | 11781 | __metrics Screen width/height:1600/1200 22:42:37 INFO - PROCESS | 11781 | colorDepth:24 22:42:37 INFO - PROCESS | 11781 | Browser inner width/height: 1024/697 22:42:37 INFO - PROCESS | 11781 | __metrics 22:42:38 ERROR - PROCESS | 11781 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:42:39 INFO - TEST-INFO | 11781: exit 0 22:42:39 INFO - Browser initialized. 22:42:39 INFO - Running cycle 1/1 for kraken test... 22:42:39 INFO - TEST-INFO | started process 11862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGs_BNY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:42:51 INFO - PROCESS | 11862 | RSS: Main: 154947584 22:42:51 INFO - PROCESS | 11862 | 22:42:54 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45171/tests/kraken/driver.html?testName=audio-beat-detection) 22:42:55 INFO - PROCESS | 11862 | RSS: Main: 209809408 22:42:55 INFO - PROCESS | 11862 | 22:42:57 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45171/tests/kraken/driver.html?testName=audio-dft) 22:42:58 INFO - PROCESS | 11862 | RSS: Main: 195473408 22:42:58 INFO - PROCESS | 11862 | 22:43:01 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45171/tests/kraken/driver.html?testName=audio-fft) 22:43:02 INFO - PROCESS | 11862 | RSS: Main: 266362880 22:43:02 INFO - PROCESS | 11862 | 22:43:04 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45171/tests/kraken/driver.html?testName=audio-oscillator) 22:43:05 INFO - PROCESS | 11862 | RSS: Main: 174395392 22:43:05 INFO - PROCESS | 11862 | 22:43:07 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45171/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:43:08 INFO - PROCESS | 11862 | RSS: Main: 179015680 22:43:08 INFO - PROCESS | 11862 | 22:43:11 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45171/tests/kraken/driver.html?testName=imaging-darkroom) 22:43:11 INFO - PROCESS | 11862 | RSS: Main: 214642688 22:43:11 INFO - PROCESS | 11862 | 22:43:15 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45171/tests/kraken/driver.html?testName=imaging-desaturate) 22:43:15 INFO - PROCESS | 11862 | RSS: Main: 214712320 22:43:15 INFO - PROCESS | 11862 | 22:43:18 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45171/tests/kraken/driver.html?testName=json-parse-financial) 22:43:19 INFO - PROCESS | 11862 | RSS: Main: 215556096 22:43:19 INFO - PROCESS | 11862 | 22:43:20 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45171/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:43:21 INFO - PROCESS | 11862 | RSS: Main: 200126464 22:43:21 INFO - PROCESS | 11862 | 22:43:23 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:43:23 INFO - PROCESS | 11862 | RSS: Main: 202006528 22:43:23 INFO - PROCESS | 11862 | 22:43:26 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:43:26 INFO - PROCESS | 11862 | RSS: Main: 218963968 22:43:26 INFO - PROCESS | 11862 | 22:43:29 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:43:30 INFO - PROCESS | 11862 | RSS: Main: 219729920 22:43:30 INFO - PROCESS | 11862 | 22:43:33 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:43:33 INFO - PROCESS | 11862 | RSS: Main: 214548480 22:43:33 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | Cycle 1(1): loaded http://localhost:45171/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45171/tests/kraken/driver.html?testName=ai-astar) 22:43:35 INFO - PROCESS | 11862 | RSS: Main: 278880256 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | __start_tp_report 22:43:35 INFO - PROCESS | 11862 | _x_x_mozilla_page_load 22:43:35 INFO - PROCESS | 11862 | _x_x_mozilla_page_load_details 22:43:35 INFO - PROCESS | 11862 | |i|pagename|runs| 22:43:35 INFO - PROCESS | 11862 | |0;ai-astar;113;116;109;114;115;115;104;113;113;113 22:43:35 INFO - PROCESS | 11862 | |1;audio-beat-detection;164;129;152;198;131;166;156;131;182;133 22:43:35 INFO - PROCESS | 11862 | |2;audio-dft;162;162;160;160;160;160;161;161;160;163 22:43:35 INFO - PROCESS | 11862 | |3;audio-fft;73;73;116;123;75;74;76;114;100;73 22:43:35 INFO - PROCESS | 11862 | |4;audio-oscillator;93;93;142;92;94;93;142;93;92;144 22:43:35 INFO - PROCESS | 11862 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;116 22:43:35 INFO - PROCESS | 11862 | |6;imaging-darkroom;146;147;146;146;146;146;146;146;147;146 22:43:35 INFO - PROCESS | 11862 | |7;imaging-desaturate;94;94;95;94;95;95;94;95;94;95 22:43:35 INFO - PROCESS | 11862 | |8;json-parse-financial;63;64;64;68;63;65;65;87;63;61 22:43:35 INFO - PROCESS | 11862 | |9;json-stringify-tinderbox;64;64;64;65;65;63;63;64;65;64 22:43:35 INFO - PROCESS | 11862 | |10;stanford-crypto-aes;80;80;79;79;79;79;79;79;81;79 22:43:35 INFO - PROCESS | 11862 | |11;stanford-crypto-ccm;136;137;134;135;139;133;131;132;210;133 22:43:35 INFO - PROCESS | 11862 | |12;stanford-crypto-pbkdf2;179;179;182;176;178;178;175;176;177;182 22:43:35 INFO - PROCESS | 11862 | |13;stanford-crypto-sha256-iterative;74;72;74;72;74;73;71;73;72;73 22:43:35 INFO - PROCESS | 11862 | __end_tp_report 22:43:35 INFO - PROCESS | 11862 | __start_cc_report 22:43:35 INFO - PROCESS | 11862 | _x_x_mozilla_cycle_collect,1902 22:43:35 INFO - PROCESS | 11862 | __end_cc_report 22:43:35 INFO - PROCESS | 11862 | __startTimestamp1461217415877__endTimestamp 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | ------- Summary: start ------- 22:43:35 INFO - PROCESS | 11862 | Number of tests: 14 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:3.54 (3.1%) stddev-sans-first:3.75 22:43:35 INFO - PROCESS | 11862 | Values: 113.0 116.0 109.0 114.0 115.0 115.0 104.0 113.0 113.0 113.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#1] audio-beat-detection Cycles:10 Average:154.20 Median:160.00 stddev:23.79 (14.9%) stddev-sans-first:24.97 22:43:35 INFO - PROCESS | 11862 | Values: 164.0 129.0 152.0 198.0 131.0 166.0 156.0 131.0 182.0 133.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.09 22:43:35 INFO - PROCESS | 11862 | Values: 162.0 162.0 160.0 160.0 160.0 160.0 161.0 161.0 160.0 163.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#3] audio-fft Cycles:10 Average:89.70 Median:73.00 stddev:21.04 (28.8%) stddev-sans-first:21.43 22:43:35 INFO - PROCESS | 11862 | Values: 73.0 73.0 116.0 123.0 75.0 74.0 76.0 114.0 100.0 73.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#4] audio-oscillator Cycles:10 Average:107.80 Median:93.00 stddev:24.07 (25.9%) stddev-sans-first:24.93 22:43:35 INFO - PROCESS | 11862 | Values: 93.0 93.0 142.0 92.0 94.0 93.0 142.0 93.0 92.0 144.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:43:35 INFO - PROCESS | 11862 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 22:43:35 INFO - PROCESS | 11862 | Values: 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 146.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 22:43:35 INFO - PROCESS | 11862 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 95.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:64.50 stddev:7.50 (11.6%) stddev-sans-first:7.86 22:43:35 INFO - PROCESS | 11862 | Values: 63.0 64.0 64.0 68.0 63.0 65.0 65.0 87.0 63.0 61.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 22:43:35 INFO - PROCESS | 11862 | Values: 64.0 64.0 64.0 65.0 65.0 63.0 63.0 64.0 65.0 64.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:0.70 (0.9%) stddev-sans-first:0.71 22:43:35 INFO - PROCESS | 11862 | Values: 80.0 80.0 79.0 79.0 79.0 79.0 79.0 79.0 81.0 79.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:135.50 stddev:24.01 (17.7%) stddev-sans-first:25.37 22:43:35 INFO - PROCESS | 11862 | Values: 136.0 137.0 134.0 135.0 139.0 133.0 131.0 132.0 210.0 133.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:178.50 stddev:2.39 (1.3%) stddev-sans-first:2.52 22:43:35 INFO - PROCESS | 11862 | Values: 179.0 179.0 182.0 176.0 178.0 178.0 175.0 176.0 177.0 182.0 22:43:35 INFO - PROCESS | 11862 | 22:43:35 INFO - PROCESS | 11862 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:73.00 stddev:1.03 (1.4%) stddev-sans-first:1.00 22:43:35 INFO - PROCESS | 11862 | Values: 74.0 72.0 74.0 72.0 74.0 73.0 71.0 73.0 72.0 73.0 22:43:35 INFO - PROCESS | 11862 | -------- Summary: end -------- 22:43:35 INFO - PROCESS | 11862 | 22:43:36 INFO - TEST-INFO | 11862: exit 0 22:43:36 INFO - TEST-OK | kraken | took 63682ms 22:43:36 INFO - SUITE-END | took 1182s 22:43:37 INFO - Completed test suite (00:19:42) 22:43:37 INFO - javascript benchmark 22:43:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2567.781738552223, 2646.973530264697, 2652.270625577967, 2658.627792109105, 2660.3083198368427, 4539.7276163430215, 4621.930671039929, 4626.814927402904, 4638.67529272951, 4642.675012749105, 4602.700824446409, 4637.142128706183, 4696.060787842436, 4708.199606066968, 4717.481077081527, 4302.978485107578, 4349.717268377562, 4357.782110894458, 4397.570789493411, 4430.136123455927, 4286.378475121106, 4301.204277208711, 4410.49279332876, 4428.0701052778795, 4430.7784610769495, 4169.4162817205615, 4242.978785106055, 4344.348347747863, 4382.298832186835, 4399.912001759963, 4228.365745138223, 4249.957500425018, 4304.504981927056, 4314.158739045887, 4314.395984562164, 4310.4396428464415, 4321.092570560186, 4323.113761678848, 4327.7186982846015, 4354.738715677069, 4439.422875026258, 4526.7283962962, 4576.199165146119, 4584.426946631671, 4639.930401043987, 4951.752412379366, 4951.752412379366, 4953.5541801237705, 4985.900281994376, 4997.925031124536, 4946.332245146895, 4948.257761335793, 4966.329545511346, 4986.40163180417, 5009.524095210949, 4471.441069866267, 4607.562281583244, 4642.117997580415, 4642.210824159901, 4675.0883577702025, 5116.667416617908, 5138.434772175057, 5139.331886854685, 5150.587952963829, 5157.097507936096, 4913.828016019422, 4950.3069570260195, 4955.4301255356095, 4955.653104282737, 4997.075541024849, 4885.096257192431, 4951.282064100686, 5001.774920128603, 5047.495250474996, 5056.469070747592, 4845.927311090337, 4866.6106711463, 4875.536824001714, 4881.8535443936735, 4907.55831975124, 4841.612670986313, 4844.249141383091, 4853.417589889236, 4859.0, 4892.143874821892, 4044.8584299549375, 4049.6962727795535, 4112.609302116294, 4119.279126152911, 4119.485064366954, 3602.8018458984484, 3613.457981302826, 3616.764910280823, 3648.4527320901548, 3660.4509323601674, 4295.699301048897, 4298.247806633826, 4300.462442194726, 4300.913981720348, 4333.523312435629, 4778.307145463888, 4931.3589233399425, 4963.0, 4979.0788704089855, 5024.974875125601, 4322.5677432256525, 4330.069035157393, 4340.65274778017, 4346.782660866944, 4370.366296886934, 4307.978460107679, 4477.350784136282, 4478.507364189936, 4484.596386325246, 4514.571115743999, 4728.527147285244, 4771.23660214364, 4776.808927642995, 4786.329913812069, 4815.494373090915, 4985.675931064469, 5006.749662516787, 5016.2224855147515, 5019.447860735389, 5054.570361519312, 4566.862994110182, 4721.4570324413135, 4764.166270902648, 4787.54518320759, 4837.298591704253, 4899.338589290543, 4922.384701912261, 4924.8276310328965, 4952.4057113146655, 4973.378327709036, 4993.850184494471, 4994.700317980933, 5001.399832020181, 5002.774875130701, 5053.823116190915, 4694.835680751157, 4797.064572408347, 4852.272159176152, 4889.242167464049, 4917.950820491749, 4768.237082066853, 4784.9951510183255, 4796.640251981045, 4827.95172048275, 4828.806847726051, 4697.719331428634, 4721.362616046789, 4727.267273572602, 4734.502877197845, 4777.761111944319, 4689.484156742687, 4695.600873925755, 4810.39870016248, 4816.95183048179, 4894.535019173173, 4682.4381074271305, 4732.219183834629, 4765.356676848581, 4768.189407800613, 4791.784369703302], "value": 4564.505946981424, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.3592583721534, 581.933077696065, 600.4355905448875, 604.5496105401473, 607.5382709141052, 33532.0, 34932.952011439666, 34972.0, 35010.12474688128, 35026.12434689134, 35701.46447803279, 36704.26591468169, 36752.26495470096, 36800.26399472009, 36800.26399472015, 7181.820454488653, 7375.815604609874, 7378.225286344937, 7641.426892983035, 7835.804104897366, 9716.562754676057, 10116.14012808912, 10126.594936202542, 10130.088292053713, 10132.949335253314, 9751.414915105117, 9846.212303015778, 9846.950765246163, 9848.852267215998, 9856.0, 7339.449541284372, 9466.053394660548, 9664.661736839213, 9733.90266097337, 9776.120149186536, 9610.66362677303, 9731.12419882207, 9749.853752193723, 9758.951205243999, 9764.072413120743, 38493.80753096216, 39264.0, 39402.21195576072, 39434.21131577381, 39484.60515394838, 3229.628592711821, 3416.128887133765, 3565.286942611462, 3784.8675296364772, 3830.272248272819, 29404.558931616044, 29425.264368390748, 29826.10521684353, 29853.40293194146, 29974.85012574923, 3496.1958749487762, 3555.2178520725397, 3576.356255873942, 3618.710503159767, 3700.9074773130887, 222168.0, 222231.0, 222714.88642556846, 222854.0, 223373.0, 56312.15531766945, 56698.7165064172, 57270.42729572734, 57355.426445735844, 57358.7132064337, 58663.0, 59160.0, 59162.0, 59360.0, 59396.0, 13558.525451609146, 13725.107867988549, 13730.313484325743, 13738.038337316291, 13763.105398149088, 9215.354925155309, 9587.280953928483, 9587.280953928483, 9920.553575089138, 10257.692269231935, 17951.0, 18049.7292540612, 18227.817721822612, 18310.542236444195, 18338.541536461427, 6532.0, 7044.604834356343, 7115.89326160108, 7234.204237533867, 7275.89086163708, 904.1997831918668, 907.7912080221586, 915.2586405011962, 959.9760005999766, 965.1458459263462, 26813.597796033075, 28257.44584047856, 28566.428671426456, 28847.5672864903, 28989.85505072461, 984.7488890333062, 1002.4436437776958, 1035.6219979707353, 1039.27770199712, 1082.1234799812178, 14587.781183282259, 15083.622909427351, 15131.924340378448, 15341.9232903837, 15345.46290879814, 335282.0, 339188.0, 342004.0, 342055.0, 342271.0, 48377.758111209216, 49098.75450622795, 49116.7544162284, 49144.754276229105, 49201.26198107031, 1088.8312311591717, 1178.640514643025, 1209.2683926224684, 1213.3266037349356, 1216.8235605837103, 25815.354616134748, 28116.156515304574, 28330.85834570814, 28817.42365152727, 28856.134315970554, 39557.60442395539, 39708.801455992536, 39960.800196, 40047.0, 40087.39868901911, 224001.0, 226104.0, 227094.0, 227272.0, 228173.0, 31700.682993169772, 32854.507182391804, 36947.0, 37963.0, 38450.80774596221, 38508.80745596254, 39801.80099099486, 40214.19571608493, 40214.396784047676, 40825.795871020455, 34471.31057378888, 34773.652263477044, 34998.650013500555, 35357.0, 35472.0, 34218.82890585631, 34322.31355372829, 34475.0, 34880.82559587288, 35138.824305878305, 30652.0, 30993.53509697311, 31166.0, 31292.530612040384, 35338.64661353456, 30576.694233057384, 31200.063998080095, 31385.843070785413, 31456.0, 31566.36867262596, 24109.638355425035, 24294.0, 24773.752262477145, 25224.24327270185, 25263.49473010493], "value": 17682.39179006872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.89389077167624, 368.73819588092454, 368.9723270754693, 374.0270075352517, 377.381095004193, 15723.091228122805, 15861.20693965303, 16168.0, 16288.10415427151, 16289.674206515863, 15473.45842895499, 16396.344146234136, 17104.914475427606, 17128.914355428205, 17136.0, 4929.457759646436, 5077.314562534066, 5109.41241757199, 5116.411612664536, 5120.718360490171, 3630.183208778017, 3830.0, 3834.6357096075967, 3837.7505462144873, 5064.11378008847, 3933.0167458135465, 5780.190773291722, 5869.647821130725, 5880.059190529516, 5884.08796636522, 5095.490450954912, 5690.374058853523, 5799.9130013049835, 5800.187973683687, 5805.941940580582, 3401.3027329397414, 5209.374875015023, 5215.374155101412, 5219.217117432378, 5221.242919776649, 12300.75398492034, 15431.305591248421, 15724.44964426251, 17267.222974966156, 18928.864268143956, 1763.3211213682696, 1770.3007312111772, 1802.8371700253263, 1840.815918408162, 1872.8220819022172, 9194.632214711404, 9418.717438476788, 9642.084002019797, 10027.548760305805, 13073.803892941614, 1597.3450885137038, 1621.9026858388534, 1630.5678995066316, 1638.893471924333, 1793.9103044847836, 59169.40830591682, 65549.34450655528, 68906.0, 68986.65506672485, 69895.65052174707, 10083.848742268872, 10149.071676381463, 10200.602858328288, 10357.067863892285, 10483.947580262049, 13999.930000349934, 14084.647883802987, 14180.432782688576, 14241.85758142426, 14254.3585538651, 6849.280825513349, 6937.9653101735175, 6987.196472405637, 7037.331453511908, 7084.43324534036, 6437.613743175424, 6466.1270728452, 6497.64262965532, 6566.770163044271, 6593.472522198309, 4489.595936365743, 4491.977540112279, 4492.0, 4526.864194074183, 4556.066006468666, 1681.6636672665516, 1797.2451570340488, 1846.9353572624893, 1866.2628261836498, 1876.8498520118358, 788.4283894176791, 851.9701810436729, 857.9656813727381, 875.7898104455012, 972.1348000279756, 16483.505494835175, 16708.832911670972, 16783.83216167847, 16970.490885273462, 17387.304507819797, 825.3149885594942, 837.4807619276088, 860.767592749954, 865.0830120072617, 895.7268033249921, 8887.244584210415, 8986.685466008788, 9383.0, 9387.624495020124, 9483.573239204252, 393653.0, 397202.0, 397976.0, 398208.0, 398605.0, 67777.3222267771, 70422.64788676024, 77341.61329193319, 79641.0, 80114.19885801183, 865.6970060478782, 995.0348162282575, 1009.4448053570507, 1045.5869931377215, 1060.9204309676804, 13817.654558636115, 14559.199244041256, 14588.85411145875, 14607.196604186447, 15086.24568771535, 30382.0, 30952.69047309498, 31426.371472570878, 31619.525707113953, 31652.683473165896, 39789.60210397975, 40462.0, 40832.591674082876, 44478.3328250083, 45697.0, 6192.81421557354, 7777.805554861174, 7847.646855891613, 8190.058143313595, 8215.917840821754, 11114.777704445703, 11817.0, 11930.821037684269, 11976.76046479083, 12465.189762665397, 10522.736931576772, 10584.41785701763, 10674.62638807638, 10706.571737130738, 10795.244332896937, 10517.631882884063, 10604.416757078434, 10612.257141999986, 10651.094656954245, 10685.519151638351, 10460.0, 10721.41032243234, 10756.085732712494, 10866.239363244496, 10876.12990960737, 7839.33365663925, 7840.647170877211, 8090.959545202236, 8093.31206847401, 8194.672213111648, 10190.898091018995, 10326.535305911102, 10367.84448233262, 10451.216158788273, 10703.732406689895], "value": 8001.091663964193, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1134.9546018159274, 1387.3756809435754, 1448.073233130796, 1517.309624121025, 1688.603178253111, 7395.482316237865, 7707.30634242918, 7713.228677132284, 7719.614019299041, 7739.26476984687, 7530.171681115073, 7649.426293028031, 7665.080190377163, 7669.4247931405225, 7678.6544605492745, 1876.878002929812, 1939.806019398056, 2183.355910006544, 2228.9888550557225, 2260.5365899990543, 2255.3008567344095, 2271.8636881787147, 2277.81777457803, 2279.5326957973575, 2280.5552917181158, 2230.8661480311234, 2239.7200349956256, 2266.093562574975, 2269.954600907981, 2273.193016479151, 1871.4853415310831, 2117.2589593642256, 2173.7500187478327, 2181.5854987552316, 2209.8232141428725, 2201.405620482477, 2201.757806641268, 2203.372038968886, 2215.080741492279, 2227.175944900381, 7635.961820190863, 7818.960905195495, 7845.254700803472, 7849.8037549061155, 7858.449908506407, 4435.290353543418, 4593.977030114828, 4608.400907881954, 4615.076984603093, 4629.629629629621, 2896.681365049864, 2917.5186094294418, 2967.4510215609967, 3012.0813151988636, 3062.846857657108, 1009.8030883977627, 1727.9481615551554, 1766.0992893624343, 1783.4025601423539, 1804.9548761281073, 10163.847542286872, 10165.949170254175, 10178.643747468803, 10213.33613315132, 10221.64224252155, 3977.065389633434, 4069.348904175377, 4081.142959978438, 4184.786411940503, 4214.115035842446, 8169.101398846122, 8171.83656326882, 8190.221928916743, 8196.057453392936, 8197.83604327922, 4455.042165934275, 4458.621017213508, 4460.330950357472, 4471.821127154943, 4476.462824461086, 3692.7045836333027, 4158.106007208465, 4247.936280955788, 4254.574542545721, 4255.76593287366, 3373.089265898243, 3391.4912763085736, 3470.444728843373, 3489.668481494254, 3502.824858757052, 1051.232600201857, 1068.8129577324094, 1076.4832880217539, 1077.7682798198425, 1082.983755243672, 847.1020718038899, 853.2661910756744, 861.3023451004706, 869.7043005378075, 888.2804928008334, 5405.540529055074, 5409.837704868782, 5441.183822426668, 5478.8248000437925, 5632.098864181711, 418.9308764053958, 435.48830124603467, 436.5721592838972, 438.43660895749446, 442.6879050269552, 3586.605473397924, 3619.8009109498703, 3680.0799800049986, 3734.514513113332, 3744.588095309514, 9237.815243695222, 9559.617615295449, 9569.33014688979, 9728.367656102331, 9745.463999479955, 8873.778155546033, 9216.262698984065, 9271.536423178813, 9278.72163835086, 9333.766655833659, 6960.408365289007, 6976.616286104313, 7107.964460177666, 7108.822279442952, 7135.143782746104, 5433.538149257357, 5440.619156659154, 5495.917561236664, 5533.446655334435, 5559.72201389937, 8505.064442911398, 8590.0, 8668.566571671518, 8765.693200737944, 8795.164459376349, 8533.445326053787, 8704.173103555153, 9026.81946361056, 9130.497822619554, 9144.771380715536, 6069.817905462844, 6288.402601752826, 6696.83257918556, 6710.4631629470505, 6771.255161932282, 7280.344768970818, 7338.889916651361, 7384.261573842788, 7392.704291828267, 7404.518706284074, 6598.406143447113, 6765.391114799691, 6783.762568310085, 6879.724811007503, 6894.758683446055, 6507.967460162668, 6519.511036672174, 6532.0, 6578.736850525926, 6824.590524568542, 6360.713767880549, 6429.710663020269, 6443.291237963726, 6450.225972883284, 6452.516061295328, 4419.756913369795, 4591.885202869955, 4658.371119898906, 4698.459677137173, 4700.153972285021, 4675.275332323496, 4734.195186818318, 4735.763211839326, 4810.302506136661, 4815.398075240595], "value": 4085.653529706892, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.16163932791545, 305.06497584902274, 306.3046883574287, 311.28715242095586, 314.42303373309977, 4868.415790105183, 5012.423571289303, 5054.84835454936, 5100.77046532906, 5126.641135120539, 4817.470078291385, 5044.6216533760025, 5056.317397151383, 5056.949430505694, 5058.190689489682, 3969.126792105732, 3976.2246361959283, 3981.9203615927663, 3987.4018897165365, 4003.679705623543, 3739.364308067635, 3832.6358995895343, 3983.760974341534, 3987.5214974203136, 3990.1421194443187, 3928.9803550982347, 3947.723659343847, 3949.3286141355893, 3953.3279342511696, 3964.2666106770193, 3944.0, 3969.6625786808154, 3972.205558888205, 3974.145558704892, 3976.6619837313865, 3467.809270490122, 3510.6840384365273, 3514.2268700885766, 3517.7713448626014, 3531.523244362004, 4742.19382704941, 4803.279508073782, 4808.639352048575, 4839.588634966032, 4855.902881942341, 4423.461231015207, 4451.109778044404, 4454.754988475633, 4459.620932220765, 4474.485434175079, 5267.578593712494, 5296.470352964711, 5299.502328463234, 5304.575633949275, 5305.761240744176, 3509.4209455439823, 3718.0704823794053, 3721.0697325668584, 3724.6461586149544, 3725.1618385863235, 5494.395616482224, 5595.0, 5595.300587426572, 5601.495865372095, 5607.317804658586, 3811.542614886176, 3812.4090765931323, 3813.0, 3813.0848596336677, 3814.5422549293708, 3590.605033446371, 3773.962260377361, 3778.7732736035928, 3862.7489213201616, 3876.4379165020914, 4337.327714204303, 4376.277914144194, 4380.452443444569, 4382.824687012548, 4384.079343337871, 5315.096433606296, 5338.439463856239, 5343.7862485500145, 5348.625596208228, 5354.518093371616, 1267.176335382009, 1300.7398520295978, 1302.9869701303055, 1311.278796661832, 1311.9344032798322, 856.8157846063002, 867.314821291173, 872.1496540872598, 872.3413822563923, 873.1966590736411, 4664.86005419838, 4689.0, 4689.343491911135, 4689.882752931136, 4714.905701885943, 5326.493983071602, 5354.491323324278, 5383.973080134574, 5419.539339156137, 5435.347758269033, 4544.954550454519, 4705.788239529229, 4720.669553131248, 4721.763911804396, 4730.219513780187, 2876.525373313422, 2905.387509932008, 2907.592936988823, 2910.752586030169, 2910.8690108945148, 5150.613703972217, 5178.663386879841, 5180.7150606717, 5189.818356357585, 5195.116830138885, 5472.885249176055, 5549.278593782863, 5597.188407680864, 5606.103023516218, 5607.0, 5075.162598171334, 5099.184130539096, 5178.974105129526, 5184.11869982104, 5189.584833213334, 5310.734463276898, 5331.760070796746, 5350.170723537858, 5376.892462150813, 5383.461653834585, 5155.458676838878, 5284.207368894697, 5302.946970530245, 5308.336457942757, 5329.7868085277705, 5100.69932167295, 5111.693298402108, 5126.2054381570915, 5136.178211486144, 5148.1248187808915, 3794.2601192767147, 3798.316303065364, 3813.675837553839, 3816.5229346331707, 3830.1190726132586, 5193.818216362409, 5227.215917612389, 5339.546138578109, 5406.378266499403, 5431.945680543302, 5172.19830926207, 5321.228421878884, 5336.226247194213, 5345.1982202669915, 5358.223057656696, 5292.867678308073, 5319.760610772447, 5343.332083489564, 5346.786128554814, 5349.598780091431, 5122.334096567572, 5229.496237520946, 5251.947480525146, 5257.63196576236, 5270.920936185884, 5348.5988550858065, 5363.919541206807, 5375.220593014069, 5388.892222155613, 5402.406290144496, 5245.685258884327, 5261.89476210466, 5279.762410691605, 5281.498257665516, 5294.814681486129], "value": 3971.7233071866103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.4737313968083, 699.0981633692537, 701.7614011236179, 709.2659097833744, 725.7242247945783, 10551.841722374162, 11421.600243991463, 11622.128340374475, 11637.941810290937, 11669.533218671242, 10881.0, 10908.672739817817, 10928.726781830439, 10936.726581835459, 10951.014408703215, 626.7900253415089, 773.7253275087334, 785.8939043229177, 786.8544319300919, 787.1262898183012, 739.6560599321315, 782.3350152370497, 782.6947490478718, 783.5181363461459, 784.7214238945178, 822.3380178955938, 824.3446460064233, 824.9793755156139, 829.8298848736025, 829.9958500207521, 656.5010591950105, 674.7975607317785, 820.9220124088203, 821.9506829590214, 823.4277177361706, 497.6640767481935, 584.2142318581501, 604.5133667397743, 604.8457643300931, 605.2162449627742, 11459.656210313704, 11487.770244595145, 11491.138164637685, 11510.769784604345, 11539.653810385618, 11434.199606027581, 12072.879271207352, 12168.26990380571, 12636.557720479832, 12731.554395596202, 9128.543572821398, 9192.632294708204, 9335.719928402159, 9353.298502612262, 9365.0, 3256.902292931216, 3283.1792051987004, 3314.386838434897, 3338.2822693121097, 3404.7957122572725, 43326.0, 43534.12931741348, 43566.56433435657, 43737.0, 45447.77276113598, 8903.376763626484, 9089.181973622406, 9098.272138228926, 9110.681126160553, 9149.268058555299, 16632.168391580373, 16782.160891955355, 16820.495385138467, 16852.0, 16856.747148792532, 6724.092247546617, 6744.932550674431, 6904.13698287714, 6935.237123916365, 7001.684924178424, 3673.9265214695556, 3704.111013356775, 3726.5341832270965, 3742.101895545104, 3806.54321481424, 2902.521084021113, 2962.140979116032, 2962.3927094945484, 2966.0508637235876, 2967.4806908790874, 498.03629975996563, 515.5617724933851, 518.3158231134942, 524.5488879563572, 524.7009204753253, 501.8745313671582, 600.6636283681153, 631.9210098737658, 645.5642441352162, 658.2791842931982, 7409.925900741031, 7640.617969101522, 7694.499857509356, 7849.1758365370815, 8054.516728996279, 638.1863124516279, 638.7636574467476, 641.8663805095265, 641.9967900160469, 651.2673242602073, 3539.345221134098, 3673.559172899269, 3717.219383929351, 3750.474933509311, 3784.375578029594, 49802.0, 50225.74887125614, 50784.74607626938, 50811.0, 50856.491435085176, 43002.13995720068, 43077.0, 43157.13685726268, 43532.3470147948, 43932.78033609811, 39550.80224598916, 40198.19603607911, 40352.192956140716, 40977.79511102485, 41143.38284925729, 7098.3256590623805, 7167.283271672791, 7206.855862882609, 7269.200387957426, 7285.7449989252245, 27555.17334479969, 27571.448571028868, 28169.28159784871, 28170.577441337988, 28309.0, 34343.4848477268, 34362.0, 35895.0, 35967.1008224786, 36591.08522286964, 9129.406588571721, 9168.633254670005, 9218.52503599421, 9224.215941645036, 9293.907060929305, 9802.950985245314, 9889.55497002651, 9917.206623470249, 9966.651167209402, 10050.748731281776, 9168.770780730536, 9468.28987825902, 9485.952570237105, 9525.904740952501, 9790.804183916423, 9271.953640231755, 9391.953040234755, 9655.131038206595, 9679.128878400978, 9686.9031309686, 9123.863142052742, 9270.119338662817, 9400.200982916529, 9444.433333999981, 9671.323007389698, 7793.181715919747, 7836.37309015266, 7941.563214023284, 7981.521108733495, 7988.680452781824, 7748.0, 7863.9213607863185, 8066.556339401389, 8193.098759136372, 8211.01467823865], "value": 5573.353210616461, "unit": "score"}], "name": "dromaeo_css", "value": 6228.953008791079, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 109.0, 114.0, 115.0, 115.0, 104.0, 113.0, 113.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 129.0, 152.0, 198.0, 131.0, 166.0, 156.0, 131.0, 182.0, 133.0], "value": 154.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 160.0, 163.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 116.0, 123.0, 75.0, 74.0, 76.0, 114.0, 100.0, 73.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 142.0, 92.0, 94.0, 93.0, 142.0, 93.0, 92.0, 144.0], "value": 107.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 68.0, 63.0, 65.0, 65.0, 87.0, 63.0, 61.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 65.0, 63.0, 63.0, 64.0, 65.0, 64.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 79.0, 79.0, 79.0, 79.0, 79.0, 81.0, 79.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 134.0, 135.0, 139.0, 133.0, 131.0, 132.0, 210.0, 133.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 179.0, 182.0, 176.0, 178.0, 178.0, 175.0, 176.0, 177.0, 182.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 74.0, 72.0, 74.0, 73.0, 71.0, 73.0, 72.0, 73.0], "value": 72.8, "unit": "score"}], "name": "kraken", "value": 1585.3999999999999, "alertThreshold": 2.0}]} 22:43:37 INFO - Return code: 0 22:43:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:43:37 ERROR - # TBPL SUCCESS # 22:43:37 INFO - Running post-action listener: _resource_record_post_action 22:43:37 INFO - Running post-run listener: _resource_record_post_run 22:43:38 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 4096; Write bytes: 386154496; Read time: 8; Write time: 269244 22:43:38 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105873408; Read time: 0; Write time: 63288 22:43:38 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 4096; Write bytes: 277823488; Read time: 8; Write time: 202408 22:43:38 INFO - Running post-run listener: _upload_blobber_files 22:43:38 INFO - Blob upload gear active. 22:43:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:43:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:43:38 INFO - (blobuploader) - INFO - Open directory for files ... 22:43:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:43:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:43:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:39 INFO - (blobuploader) - INFO - Done attempting. 22:43:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:43:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:43:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:43:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:43:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:43:40 INFO - (blobuploader) - INFO - Done attempting. 22:43:40 INFO - (blobuploader) - INFO - Iteration through files over. 22:43:40 INFO - Return code: 0 22:43:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:43:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:43:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f1b50a8d9a4fc70cb3189e8f458cb1e8ac23994fdfe70d39ad581d7cb18351cf58e1cacad8eb69f82dee8944a4fb9808dd309c328d2ccf630c940d108b2be82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ce5cb6317ba74d3187c736498285c0329f93de0f854b9ab3b5f3cf6796087edc5478e6330fea9454d70b4262bd87dcfb9dc586e2c8e6e2f2855601dec2c9de9a"} 22:43:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:43:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:43:40 INFO - Contents: 22:43:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f1b50a8d9a4fc70cb3189e8f458cb1e8ac23994fdfe70d39ad581d7cb18351cf58e1cacad8eb69f82dee8944a4fb9808dd309c328d2ccf630c940d108b2be82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ce5cb6317ba74d3187c736498285c0329f93de0f854b9ab3b5f3cf6796087edc5478e6330fea9454d70b4262bd87dcfb9dc586e2c8e6e2f2855601dec2c9de9a"} 22:43:40 INFO - Running post-run listener: copy_logs_to_upload_dir 22:43:40 INFO - Copying logs to upload dir... 22:43:40 INFO - mkdir: /builds/slave/test/build/upload/logs 22:43:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.061326 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-04-20 22:43:40.065708) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:40.069694) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075 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/7f1b50a8d9a4fc70cb3189e8f458cb1e8ac23994fdfe70d39ad581d7cb18351cf58e1cacad8eb69f82dee8944a4fb9808dd309c328d2ccf630c940d108b2be82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ce5cb6317ba74d3187c736498285c0329f93de0f854b9ab3b5f3cf6796087edc5478e6330fea9454d70b4262bd87dcfb9dc586e2c8e6e2f2855601dec2c9de9a"} build_url:https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011124 build_url: 'https://queue.taskcluster.net/v1/task/E9v9gUR8QHWpSnLvn4RNjQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7f1b50a8d9a4fc70cb3189e8f458cb1e8ac23994fdfe70d39ad581d7cb18351cf58e1cacad8eb69f82dee8944a4fb9808dd309c328d2ccf630c940d108b2be82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ce5cb6317ba74d3187c736498285c0329f93de0f854b9ab3b5f3cf6796087edc5478e6330fea9454d70b4262bd87dcfb9dc586e2c8e6e2f2855601dec2c9de9a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:40.094746) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:40.095093) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1461209247.164477-160532075 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 22:43:40.177225) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 22:43:40.177549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 22:43:40.177853) ========= ========= Total master_lag: 0.18 =========