builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1453336575.95 results: success (0) buildid: 20160120155520 builduid: 3bc953d0ef814210bfa2e3c15290e038 revision: 493d6820745e4e3ed22e48a5f9229ea033a64a3a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:15.949397) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:15.949875) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:15.950175) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006265 basedir: '/builds/slave/test' ========= master_lag: 0.13 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.085419) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.085800) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.116508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.116873) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015228 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.166328) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.166687) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.167064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.167334) ========= 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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-20 16:36:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.8M=0s 2016-01-20 16:36:16 (43.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.360593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.360935) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.172612 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.544923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:16.545271) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 493d6820745e4e3ed22e48a5f9229ea033a64a3a --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 493d6820745e4e3ed22e48a5f9229ea033a64a3a --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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-20 16:36:16,480 truncating revision to first 12 chars 2016-01-20 16:36:16,480 Setting DEBUG logging. 2016-01-20 16:36:16,480 attempt 1/10 2016-01-20 16:36:16,480 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/493d6820745e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-20 16:36:16,773 unpacking tar archive at: fx-team-493d6820745e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.594525 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.153491) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.153853) ========= echo 493d6820745e4e3ed22e48a5f9229ea033a64a3a in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'493d6820745e4e3ed22e48a5f9229ea033a64a3a'] 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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 493d6820745e4e3ed22e48a5f9229ea033a64a3a program finished with exit code 0 elapsedTime=0.004071 script_repo_revision: '493d6820745e4e3ed22e48a5f9229ea033a64a3a' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.204849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.205173) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.216918) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.217318) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/493d6820745e4e3ed22e48a5f9229ea033a64a3a ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-20 16:36:17.217856) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 49 secs) (at 2016-01-20 16:36:17.218161) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:36:17 INFO - MultiFileLogger online at 20160120 16:36:17 in /builds/slave/test 16:36:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 16:36:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:36:17 INFO - {'append_to_log': False, 16:36:17 INFO - 'base_work_dir': '/builds/slave/test', 16:36:17 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 16:36:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:36:17 INFO - 'branch': 'Fx-Team-Non-PGO', 16:36:17 INFO - 'buildbot_json_path': 'buildprops.json', 16:36:17 INFO - 'config_files': ('talos/linux_config.py',), 16:36:17 INFO - 'default_actions': ('clobber', 16:36:17 INFO - 'read-buildbot-config', 16:36:17 INFO - 'download-and-extract', 16:36:17 INFO - 'populate-webroot', 16:36:17 INFO - 'create-virtualenv', 16:36:17 INFO - 'install', 16:36:17 INFO - 'run-tests'), 16:36:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:36:17 INFO - 'download_minidump_stackwalk': True, 16:36:17 INFO - 'download_symbols': 'ondemand', 16:36:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:36:17 INFO - 'tooltool.py': '/tools/tooltool.py', 16:36:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:36:17 INFO - '/tools/misc-python/virtualenv.py')}, 16:36:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:36:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:36:17 INFO - 'installer_path': 'installer.exe', 16:36:17 INFO - 'log_level': 'info', 16:36:17 INFO - 'log_name': 'talos', 16:36:17 INFO - 'log_to_console': True, 16:36:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:36:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:36:17 INFO - 'opt_config_files': (), 16:36:17 INFO - 'pip_index': False, 16:36:17 INFO - 'sps_profile': False, 16:36:17 INFO - 'sps_profile_interval': 0, 16:36:17 INFO - 'suite': 'dromaeojs-e10s', 16:36:17 INFO - 'system_bits': '32', 16:36:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:36:17 INFO - 'title': 'talos-linux64-ix-034', 16:36:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:36:17 INFO - 'use_talos_json': True, 16:36:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:36:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:36:17 INFO - 'work_dir': 'build'} 16:36:17 INFO - ##### 16:36:17 INFO - ##### Running clobber step. 16:36:17 INFO - ##### 16:36:17 INFO - Running pre-action listener: _resource_record_pre_action 16:36:17 INFO - Running main action method: clobber 16:36:17 INFO - rmtree: /builds/slave/test/build 16:36:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:36:23 INFO - Running post-action listener: _resource_record_post_action 16:36:23 INFO - ##### 16:36:23 INFO - ##### Running read-buildbot-config step. 16:36:23 INFO - ##### 16:36:23 INFO - Running pre-action listener: _resource_record_pre_action 16:36:23 INFO - Running main action method: read_buildbot_config 16:36:23 INFO - Using buildbot properties: 16:36:23 INFO - { 16:36:23 INFO - "project": "", 16:36:23 INFO - "product": "firefox", 16:36:23 INFO - "script_repo_revision": "production", 16:36:23 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 16:36:23 INFO - "repository": "", 16:36:23 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 16:36:23 INFO - "buildid": "20160120155520", 16:36:23 INFO - "pgo_build": "False", 16:36:23 INFO - "basedir": "/builds/slave/test", 16:36:23 INFO - "buildnumber": 683, 16:36:23 INFO - "slavename": "talos-linux64-ix-034", 16:36:23 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:36:23 INFO - "platform": "ubuntu64_hw", 16:36:23 INFO - "scheduler": "tests-fx-team-linux64-talos", 16:36:23 INFO - "branch": "fx-team", 16:36:23 INFO - "repo_path": "integration/fx-team", 16:36:23 INFO - "revision": "493d6820745e4e3ed22e48a5f9229ea033a64a3a", 16:36:23 INFO - "stage_platform": "linux64", 16:36:23 INFO - "builduid": "3bc953d0ef814210bfa2e3c15290e038", 16:36:23 INFO - "slavebuilddir": "test" 16:36:23 INFO - } 16:36:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 16:36:23 INFO - Running post-action listener: _resource_record_post_action 16:36:23 INFO - ##### 16:36:23 INFO - ##### Running download-and-extract step. 16:36:23 INFO - ##### 16:36:23 INFO - Running pre-action listener: _resource_record_pre_action 16:36:23 INFO - Running main action method: download_and_extract 16:36:23 INFO - mkdir: /builds/slave/test/build/tests 16:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:23 INFO - https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:36:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/test_packages.json 16:36:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/test_packages.json 16:36:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 16:36:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 16:36:27 INFO - Downloaded 1448 bytes. 16:36:27 INFO - Reading from file /builds/slave/test/build/test_packages.json 16:36:27 INFO - Using the following test package requirements: 16:36:27 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:27 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:36:27 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:36:27 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'jsshell-linux-x86_64.zip'], 16:36:27 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:36:27 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:27 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:36:27 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:36:27 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:36:27 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:36:27 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:36:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:36:27 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:36: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')]} 16:36:27 INFO - https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:36:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:36:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:36:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:36:29 INFO - Downloaded 21217723 bytes. 16:36:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:36:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:36:30 INFO - Return code: 0 16:36:30 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:36:30 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')]} 16:36:30 INFO - https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:36:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:36:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:36:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:36:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:36:33 INFO - Downloaded 21217723 bytes. 16:36:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:36:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:36:35 INFO - Return code: 0 16:36:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:35 INFO - https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:36:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:36:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:36:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:36:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:36:36 INFO - Downloaded 11132144 bytes. 16:36:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:36:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:36:36 INFO - Return code: 0 16:36:36 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')]} 16:36:36 INFO - https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:36:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:36:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:36:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:36:38 INFO - Downloaded 54182914 bytes. 16:36:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:36:38 INFO - mkdir: /builds/slave/test/properties 16:36:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:36:38 INFO - Writing to file /builds/slave/test/properties/build_url 16:36:38 INFO - Contents: 16:36:38 INFO - build_url:https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:36:38 INFO - Running post-action listener: _resource_record_post_action 16:36:38 INFO - Running post-action listener: set_extra_try_arguments 16:36:38 INFO - ##### 16:36:38 INFO - ##### Running populate-webroot step. 16:36:38 INFO - ##### 16:36:38 INFO - Running pre-action listener: _resource_record_pre_action 16:36:38 INFO - Running main action method: populate_webroot 16:36:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:36:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:36:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:36:38 INFO - '--tppagecycles', 16:36:38 INFO - '7'], 16:36:38 INFO - 'tests': ['tsvgm']}}, 16:36:38 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:36:38 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:36:38 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:36:38 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:36:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:36:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:36:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'tests': ['damp', 'tps']}, 16:36:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['damp', 'tps']}, 16:36:38 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:36:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:36:38 INFO - 'other': {'tests': ['a11yr', 16:36:38 INFO - 'ts_paint', 16:36:38 INFO - 'tpaint', 16:36:38 INFO - 'sessionrestore', 16:36:38 INFO - 'sessionrestore_no_auto_restore']}, 16:36:38 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['a11yr', 16:36:38 INFO - 'ts_paint', 16:36:38 INFO - 'tpaint', 16:36:38 INFO - 'sessionrestore', 16:36:38 INFO - 'sessionrestore_no_auto_restore']}, 16:36:38 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['a11yr', 16:36:38 INFO - 'ts_paint', 16:36:38 INFO - 'tpaint', 16:36:38 INFO - 'sessionrestore', 16:36:38 INFO - 'sessionrestore_no_auto_restore']}, 16:36:38 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['a11yr', 16:36:38 INFO - 'ts_paint', 16:36:38 INFO - 'tpaint', 16:36:38 INFO - 'sessionrestore', 16:36:38 INFO - 'sessionrestore_no_auto_restore']}, 16:36:38 INFO - 'other_l64': {'tests': ['a11yr', 16:36:38 INFO - 'ts_paint', 16:36:38 INFO - 'tpaint', 16:36:38 INFO - 'sessionrestore', 16:36:38 INFO - 'sessionrestore_no_auto_restore']}, 16:36:38 INFO - 'other_nol64': {'tests': ['a11yr', 16:36:38 INFO - 'ts_paint', 16:36:38 INFO - 'tpaint', 16:36:38 INFO - 'sessionrestore', 16:36:38 INFO - 'sessionrestore_no_auto_restore']}, 16:36:38 INFO - 'svgr': {'tests': ['tsvgx', 16:36:38 INFO - 'tsvgr_opacity', 16:36:38 INFO - 'tart', 16:36:38 INFO - 'tscrollx', 16:36:38 INFO - 'cart']}, 16:36:38 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['tsvgx', 16:36:38 INFO - 'tsvgr_opacity', 16:36:38 INFO - 'tart', 16:36:38 INFO - 'tscrollx', 16:36:38 INFO - 'cart']}, 16:36:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'tests': ['tp5o']}, 16:36:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'talos_options': ['--e10s'], 16:36:38 INFO - 'tests': ['tp5o']}, 16:36:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'talos_options': ['--xperf_path', 16:36:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:36:38 INFO - 'tests': ['tp5n']}, 16:36:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:36:38 INFO - 'talos_options': ['--e10s', 16:36:38 INFO - '--xperf_path', 16:36:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:36:38 INFO - 'tests': ['tp5n']}}, 16:36:38 INFO - 'talos.zip': {'path': '', 16:36:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:36:38 INFO - Running post-action listener: _resource_record_post_action 16:36:38 INFO - ##### 16:36:38 INFO - ##### Running create-virtualenv step. 16:36:38 INFO - ##### 16:36:38 INFO - Running pre-action listener: _resource_record_pre_action 16:36:38 INFO - Running main action method: create_virtualenv 16:36:38 INFO - Creating virtualenv /builds/slave/test/build/venv 16:36:38 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 16:36:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:36:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:36:38 INFO - Using real prefix '/usr' 16:36:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:36:40 INFO - Installing distribute.............................................................................................................................................................................................done. 16:36:42 INFO - Installing pip.................done. 16:36:42 INFO - Return code: 0 16:36:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:42 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:42 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 16:36:42 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 16:36:42 INFO - Using env: {'DISPLAY': ':0', 16:36:42 INFO - 'HOME': '/home/cltbld', 16:36:42 INFO - 'LANG': 'en_US.UTF-8', 16:36:42 INFO - 'LANGUAGE': 'en_US:en', 16:36:42 INFO - 'LOGNAME': 'cltbld', 16:36:42 INFO - 'MAIL': '/var/mail/cltbld', 16:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:42 INFO - 'MOZ_NO_REMOTE': '1', 16:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:42 INFO - 'NO_EM_RESTART': '1', 16:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:42 INFO - 'PWD': '/builds/slave/test', 16:36:42 INFO - 'SHELL': '/bin/bash', 16:36:42 INFO - 'SHLVL': '1', 16:36:42 INFO - 'TERM': 'linux', 16:36:42 INFO - 'TMOUT': '86400', 16:36:42 INFO - 'USER': 'cltbld', 16:36:42 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:42 INFO - '_': '/tools/buildbot/bin/python'} 16:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:43 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 16:36:43 INFO - Cleaning up... 16:36:43 INFO - Return code: 0 16:36:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:43 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:43 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 16:36:43 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 16:36:43 INFO - Using env: {'DISPLAY': ':0', 16:36:43 INFO - 'HOME': '/home/cltbld', 16:36:43 INFO - 'LANG': 'en_US.UTF-8', 16:36:43 INFO - 'LANGUAGE': 'en_US:en', 16:36:43 INFO - 'LOGNAME': 'cltbld', 16:36:43 INFO - 'MAIL': '/var/mail/cltbld', 16:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:43 INFO - 'MOZ_NO_REMOTE': '1', 16:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:43 INFO - 'NO_EM_RESTART': '1', 16:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:43 INFO - 'PWD': '/builds/slave/test', 16:36:43 INFO - 'SHELL': '/bin/bash', 16:36:43 INFO - 'SHLVL': '1', 16:36:43 INFO - 'TERM': 'linux', 16:36:43 INFO - 'TMOUT': '86400', 16:36:43 INFO - 'USER': 'cltbld', 16:36:43 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:43 INFO - '_': '/tools/buildbot/bin/python'} 16:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:43 INFO - Downloading/unpacking psutil>=0.7.1 16:36:43 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 16:36:43 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 16:36:43 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 16:36:43 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 16:36:43 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 16:36:43 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 16:36:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:36:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:36:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:36:45 INFO - Installing collected packages: psutil 16:36:45 INFO - Running setup.py install for psutil 16:36:45 INFO - building 'psutil._psutil_linux' extension 16:36:45 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 16:36:47 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 16:36:47 INFO - building 'psutil._psutil_posix' extension 16:36:47 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 16:36:47 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 16:36:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:36:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:36:47 INFO - Successfully installed psutil 16:36:47 INFO - Cleaning up... 16:36:47 INFO - Return code: 0 16:36:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:36:47 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')]} 16:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:47 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')]} 16:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:47 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:47 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 16:36:47 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 16:36:47 INFO - Using env: {'DISPLAY': ':0', 16:36:47 INFO - 'HOME': '/home/cltbld', 16:36:47 INFO - 'LANG': 'en_US.UTF-8', 16:36:47 INFO - 'LANGUAGE': 'en_US:en', 16:36:47 INFO - 'LOGNAME': 'cltbld', 16:36:47 INFO - 'MAIL': '/var/mail/cltbld', 16:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:47 INFO - 'MOZ_NO_REMOTE': '1', 16:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:47 INFO - 'NO_EM_RESTART': '1', 16:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:47 INFO - 'PWD': '/builds/slave/test', 16:36:47 INFO - 'SHELL': '/bin/bash', 16:36:47 INFO - 'SHLVL': '1', 16:36:47 INFO - 'TERM': 'linux', 16:36:47 INFO - 'TMOUT': '86400', 16:36:47 INFO - 'USER': 'cltbld', 16:36:47 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:47 INFO - '_': '/tools/buildbot/bin/python'} 16:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:36:47 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 16:36:47 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 16:36:47 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 16:36:47 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 16:36:47 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 16:36:47 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 16:36:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:36:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:36:49 INFO - Installing collected packages: mozsystemmonitor 16:36:49 INFO - Running setup.py install for mozsystemmonitor 16:36:49 INFO - Successfully installed mozsystemmonitor 16:36:49 INFO - Cleaning up... 16:36:49 INFO - Return code: 0 16:36:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:49 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:49 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 16:36:49 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 16:36:49 INFO - Using env: {'DISPLAY': ':0', 16:36:49 INFO - 'HOME': '/home/cltbld', 16:36:49 INFO - 'LANG': 'en_US.UTF-8', 16:36:49 INFO - 'LANGUAGE': 'en_US:en', 16:36:49 INFO - 'LOGNAME': 'cltbld', 16:36:49 INFO - 'MAIL': '/var/mail/cltbld', 16:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:49 INFO - 'MOZ_NO_REMOTE': '1', 16:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:49 INFO - 'NO_EM_RESTART': '1', 16:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:49 INFO - 'PWD': '/builds/slave/test', 16:36:49 INFO - 'SHELL': '/bin/bash', 16:36:49 INFO - 'SHLVL': '1', 16:36:49 INFO - 'TERM': 'linux', 16:36:49 INFO - 'TMOUT': '86400', 16:36:49 INFO - 'USER': 'cltbld', 16:36:49 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:49 INFO - '_': '/tools/buildbot/bin/python'} 16:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:49 INFO - Downloading/unpacking blobuploader==1.2.4 16:36:49 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 16:36:49 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 16:36:49 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 16:36:49 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 16:36:49 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 16:36:49 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 16:36:51 INFO - Downloading blobuploader-1.2.4.tar.gz 16:36:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:36:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:36:51 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 16:36:51 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 16:36:51 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 16:36:51 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 16:36:51 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 16:36:51 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 16:36:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:36:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:36:52 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 16:36:52 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 16:36:52 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 16:36:52 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 16:36:52 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 16:36:52 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 16:36:52 INFO - Downloading docopt-0.6.1.tar.gz 16:36:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:36:52 INFO - Installing collected packages: blobuploader, requests, docopt 16:36:52 INFO - Running setup.py install for blobuploader 16:36:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:36:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:36:52 INFO - Running setup.py install for requests 16:36:52 INFO - Running setup.py install for docopt 16:36:52 INFO - Successfully installed blobuploader requests docopt 16:36:52 INFO - Cleaning up... 16:36:53 INFO - Return code: 0 16:36:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:36: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')]} 16:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36: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')]} 16:36:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:53 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:53 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 16:36:53 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 16:36:53 INFO - Using env: {'DISPLAY': ':0', 16:36:53 INFO - 'HOME': '/home/cltbld', 16:36:53 INFO - 'LANG': 'en_US.UTF-8', 16:36:53 INFO - 'LANGUAGE': 'en_US:en', 16:36:53 INFO - 'LOGNAME': 'cltbld', 16:36:53 INFO - 'MAIL': '/var/mail/cltbld', 16:36:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:53 INFO - 'MOZ_NO_REMOTE': '1', 16:36:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:53 INFO - 'NO_EM_RESTART': '1', 16:36:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:53 INFO - 'PWD': '/builds/slave/test', 16:36:53 INFO - 'SHELL': '/bin/bash', 16:36:53 INFO - 'SHLVL': '1', 16:36:53 INFO - 'TERM': 'linux', 16:36:53 INFO - 'TMOUT': '86400', 16:36:53 INFO - 'USER': 'cltbld', 16:36:53 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:53 INFO - '_': '/tools/buildbot/bin/python'} 16:36:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:36:53 INFO - Running setup.py (path:/tmp/pip-BJZxGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:36:53 INFO - Running setup.py (path:/tmp/pip-LtbGjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:36:53 INFO - Running setup.py (path:/tmp/pip-7wD_2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:36:53 INFO - Running setup.py (path:/tmp/pip-AP_DMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:36:53 INFO - Running setup.py (path:/tmp/pip-3xzOvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:36:53 INFO - Running setup.py (path:/tmp/pip-GVIxiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:36:53 INFO - Running setup.py (path:/tmp/pip-wgTiJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:36:53 INFO - Running setup.py (path:/tmp/pip-uPTlI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:36:53 INFO - Running setup.py (path:/tmp/pip-VdNrmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:36:53 INFO - Running setup.py (path:/tmp/pip-mct8cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:36:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:36:53 INFO - Running setup.py (path:/tmp/pip-rL3A02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:36:54 INFO - Running setup.py (path:/tmp/pip-LhAYfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:36:54 INFO - Running setup.py (path:/tmp/pip-sDL3UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:36:54 INFO - Running setup.py (path:/tmp/pip-ykl1rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:54 INFO - Running setup.py (path:/tmp/pip-fLmQoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:36:54 INFO - Running setup.py (path:/tmp/pip-w1zgdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:36:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:36:54 INFO - Running setup.py (path:/tmp/pip-M_X4pN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:36:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:36:54 INFO - Running setup.py install for manifestparser 16:36:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:36:54 INFO - Running setup.py install for mozcrash 16:36:54 INFO - Running setup.py install for mozdebug 16:36:54 INFO - Running setup.py install for mozdevice 16:36:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:36:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:36:54 INFO - Running setup.py install for mozfile 16:36:54 INFO - Running setup.py install for mozhttpd 16:36:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for mozinfo 16:36:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for mozInstall 16:36:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for mozleak 16:36:55 INFO - Running setup.py install for mozlog 16:36:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for moznetwork 16:36:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for mozprocess 16:36:55 INFO - Running setup.py install for mozprofile 16:36:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for mozrunner 16:36:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:36:55 INFO - Running setup.py install for mozscreenshot 16:36:56 INFO - Running setup.py install for moztest 16:36:56 INFO - Running setup.py install for mozversion 16:36:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:36:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:36:56 INFO - Cleaning up... 16:36:56 INFO - Return code: 0 16:36:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:36:56 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')]} 16:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:56 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')]} 16:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:56 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:56 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 16:36:56 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 16:36:56 INFO - Using env: {'DISPLAY': ':0', 16:36:56 INFO - 'HOME': '/home/cltbld', 16:36:56 INFO - 'LANG': 'en_US.UTF-8', 16:36:56 INFO - 'LANGUAGE': 'en_US:en', 16:36:56 INFO - 'LOGNAME': 'cltbld', 16:36:56 INFO - 'MAIL': '/var/mail/cltbld', 16:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:56 INFO - 'MOZ_NO_REMOTE': '1', 16:36:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:56 INFO - 'NO_EM_RESTART': '1', 16:36:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:56 INFO - 'PWD': '/builds/slave/test', 16:36:56 INFO - 'SHELL': '/bin/bash', 16:36:56 INFO - 'SHLVL': '1', 16:36:56 INFO - 'TERM': 'linux', 16:36:56 INFO - 'TMOUT': '86400', 16:36:56 INFO - 'USER': 'cltbld', 16:36:56 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:56 INFO - '_': '/tools/buildbot/bin/python'} 16:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:36:56 INFO - Running setup.py (path:/tmp/pip-xzKelY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:36:56 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:36:56 INFO - Running setup.py (path:/tmp/pip-LSVfBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:36:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:36:56 INFO - Running setup.py (path:/tmp/pip-SrsM8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:36:56 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:36:56 INFO - Running setup.py (path:/tmp/pip-FKoD8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:36:56 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:36:56 INFO - Running setup.py (path:/tmp/pip-3QAIvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:36:56 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:36:56 INFO - Running setup.py (path:/tmp/pip-fILAXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:36:56 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:36:56 INFO - Running setup.py (path:/tmp/pip-6iOh5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:36:56 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)) 16:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:36:56 INFO - Running setup.py (path:/tmp/pip-hX5fiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:36:57 INFO - Running setup.py (path:/tmp/pip-7lwXx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:36:57 INFO - Running setup.py (path:/tmp/pip-lIS9os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:36:57 INFO - Running setup.py (path:/tmp/pip-5iT_7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:36:57 INFO - Running setup.py (path:/tmp/pip-1F5STL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:36:57 INFO - Running setup.py (path:/tmp/pip-JOYd88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:36:57 INFO - Running setup.py (path:/tmp/pip-397D2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:57 INFO - Running setup.py (path:/tmp/pip-0OpvZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:36:57 INFO - Running setup.py (path:/tmp/pip-Y2Dg8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:36:57 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)) 16:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:36:57 INFO - Running setup.py (path:/tmp/pip-1UNf8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:36:57 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)) 16:36:57 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:36:57 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:36:57 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)) 16:36:57 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)) 16:36:57 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)) 16:36:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:36:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:36:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:36:59 INFO - Downloading blessings-1.6.tar.gz 16:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:36:59 INFO - Installing collected packages: blessings 16:36:59 INFO - Running setup.py install for blessings 16:36:59 INFO - Successfully installed blessings 16:36:59 INFO - Cleaning up... 16:36:59 INFO - Return code: 0 16:36:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:36:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:36:59 INFO - Reading from file tmpfile_stdout 16:36:59 INFO - Current package versions: 16:36:59 INFO - argparse == 1.2.1 16:36:59 INFO - blessings == 1.6 16:36:59 INFO - blobuploader == 1.2.4 16:36:59 INFO - docopt == 0.6.1 16:36:59 INFO - manifestparser == 1.1 16:36:59 INFO - mozInstall == 1.12 16:36:59 INFO - mozcrash == 0.16 16:36:59 INFO - mozdebug == 0.1 16:36:59 INFO - mozdevice == 0.48 16:36:59 INFO - mozfile == 1.2 16:36:59 INFO - mozhttpd == 0.7 16:36:59 INFO - mozinfo == 0.9 16:36:59 INFO - mozleak == 0.1 16:36:59 INFO - mozlog == 3.1 16:36:59 INFO - moznetwork == 0.27 16:36:59 INFO - mozprocess == 0.22 16:36:59 INFO - mozprofile == 0.28 16:36:59 INFO - mozrunner == 6.11 16:36:59 INFO - mozscreenshot == 0.1 16:36:59 INFO - mozsystemmonitor == 0.0 16:36:59 INFO - moztest == 0.7 16:36:59 INFO - mozversion == 1.4 16:36:59 INFO - psutil == 3.1.1 16:36:59 INFO - requests == 1.2.3 16:36:59 INFO - wsgiref == 0.1.2 16:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:36:59 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 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:36:59 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 16:36:59 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 16:36:59 INFO - Using env: {'DISPLAY': ':0', 16:36:59 INFO - 'HOME': '/home/cltbld', 16:36:59 INFO - 'LANG': 'en_US.UTF-8', 16:36:59 INFO - 'LANGUAGE': 'en_US:en', 16:36:59 INFO - 'LOGNAME': 'cltbld', 16:36:59 INFO - 'MAIL': '/var/mail/cltbld', 16:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:59 INFO - 'MOZ_NO_REMOTE': '1', 16:36:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:36:59 INFO - 'NO_EM_RESTART': '1', 16:36:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:59 INFO - 'PWD': '/builds/slave/test', 16:36:59 INFO - 'SHELL': '/bin/bash', 16:36:59 INFO - 'SHLVL': '1', 16:36:59 INFO - 'TERM': 'linux', 16:36:59 INFO - 'TMOUT': '86400', 16:36:59 INFO - 'USER': 'cltbld', 16:36:59 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:59 INFO - '_': '/tools/buildbot/bin/python'} 16:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 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)) 16:37:00 INFO - Cleaning up... 16:37:00 INFO - Return code: 0 16:37:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:37:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:37:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:37:00 INFO - Using env: {'DISPLAY': ':0', 16:37:00 INFO - 'HOME': '/home/cltbld', 16:37:00 INFO - 'LANG': 'en_US.UTF-8', 16:37:00 INFO - 'LANGUAGE': 'en_US:en', 16:37:00 INFO - 'LOGNAME': 'cltbld', 16:37:00 INFO - 'MAIL': '/var/mail/cltbld', 16:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:00 INFO - 'MOZ_NO_REMOTE': '1', 16:37:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:00 INFO - 'NO_EM_RESTART': '1', 16:37:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:00 INFO - 'PWD': '/builds/slave/test', 16:37:00 INFO - 'SHELL': '/bin/bash', 16:37:00 INFO - 'SHLVL': '1', 16:37:00 INFO - 'TERM': 'linux', 16:37:00 INFO - 'TMOUT': '86400', 16:37:00 INFO - 'USER': 'cltbld', 16:37:00 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:00 INFO - '_': '/tools/buildbot/bin/python'} 16:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:00 INFO - Downloading/unpacking jsonschema 16:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:37:02 INFO - Downloading/unpacking functools32 (from jsonschema) 16:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:02 INFO - Downloading functools32-3.2.3-2.tar.gz 16:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:37:02 INFO - warning: no files found matching '*.txt' 16:37:02 INFO - no previously-included directories found matching 'build' 16:37:02 INFO - no previously-included directories found matching 'dist' 16:37:02 INFO - no previously-included directories found matching '.git*' 16:37:02 INFO - Installing collected packages: jsonschema, functools32 16:37:02 INFO - Running setup.py install for functools32 16:37:02 INFO - warning: no files found matching '*.txt' 16:37:02 INFO - no previously-included directories found matching 'build' 16:37:02 INFO - no previously-included directories found matching 'dist' 16:37:02 INFO - no previously-included directories found matching '.git*' 16:37:02 INFO - Successfully installed jsonschema functools32 16:37:02 INFO - Cleaning up... 16:37:02 INFO - Return code: 0 16:37:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:37: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')]} 16:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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')]} 16:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4ae4bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24dc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25abdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, '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': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', '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 16:37:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:37:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:37:02 INFO - Using env: {'DISPLAY': ':0', 16:37:02 INFO - 'HOME': '/home/cltbld', 16:37:02 INFO - 'LANG': 'en_US.UTF-8', 16:37:02 INFO - 'LANGUAGE': 'en_US:en', 16:37:02 INFO - 'LOGNAME': 'cltbld', 16:37:02 INFO - 'MAIL': '/var/mail/cltbld', 16:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:02 INFO - 'MOZ_NO_REMOTE': '1', 16:37:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:02 INFO - 'NO_EM_RESTART': '1', 16:37:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:02 INFO - 'PWD': '/builds/slave/test', 16:37:02 INFO - 'SHELL': '/bin/bash', 16:37:02 INFO - 'SHLVL': '1', 16:37:02 INFO - 'TERM': 'linux', 16:37:02 INFO - 'TMOUT': '86400', 16:37:02 INFO - 'USER': 'cltbld', 16:37:02 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:02 INFO - '_': '/tools/buildbot/bin/python'} 16:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:02 INFO - Downloading/unpacking flake8 16:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:37:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:37:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:37:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:37:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:37:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:37:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:37:05 INFO - no previously-included directories found matching 'docs/_build' 16:37:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:37:05 INFO - Running setup.py install for pep8 16:37:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:37:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:37:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:37:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:37:05 INFO - no previously-included directories found matching 'docs/_build' 16:37:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:37:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:37:05 INFO - Cleaning up... 16:37:05 INFO - Return code: 0 16:37:05 INFO - Running post-action listener: _resource_record_post_action 16:37:05 INFO - Running post-action listener: _start_resource_monitoring 16:37:05 INFO - Starting resource monitoring. 16:37:05 INFO - ##### 16:37:05 INFO - ##### Running install step. 16:37:05 INFO - ##### 16:37:05 INFO - Running pre-action listener: _resource_record_pre_action 16:37:05 INFO - Running main action method: install 16:37:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:37:05 INFO - Reading from file tmpfile_stdout 16:37:05 INFO - Detecting whether we're running mozinstall >=1.0... 16:37:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:37:05 INFO - Reading from file tmpfile_stdout 16:37:05 INFO - Output received: 16:37:05 INFO - Usage: mozinstall [options] installer 16:37:05 INFO - Options: 16:37:05 INFO - -h, --help show this help message and exit 16:37:05 INFO - -d DEST, --destination=DEST 16:37:05 INFO - Directory to install application into. [default: 16:37:05 INFO - "/builds/slave/test"] 16:37:05 INFO - --app=APP Application being installed. [default: firefox] 16:37:05 INFO - mkdir: /builds/slave/test/build/application 16:37:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:37:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:37:21 INFO - Reading from file tmpfile_stdout 16:37:21 INFO - Output received: 16:37:21 INFO - /builds/slave/test/build/application/firefox/firefox 16:37:21 INFO - Running post-action listener: _resource_record_post_action 16:37:21 INFO - ##### 16:37:21 INFO - ##### Running run-tests step. 16:37:21 INFO - ##### 16:37:21 INFO - Running pre-action listener: _resource_record_pre_action 16:37:21 INFO - Running main action method: run_tests 16:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:37:21 INFO - Python 2.7.3 16:37:21 INFO - Return code: 0 16:37:21 INFO - grabbing minidump binary from tooltool 16:37:21 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')]} 16:37:21 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 0x263e3d0>, '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 0x25a9ed0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25aa360>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:37:21 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 16:37:21 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 16:37:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:37:21 INFO - Return code: 0 16:37:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:37:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:37:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:37:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:37:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:37:22 INFO - Return code: 0 16:37:22 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:37:22 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:37:22 INFO - Using env: {'DISPLAY': ':0', 16:37:22 INFO - 'HOME': '/home/cltbld', 16:37:22 INFO - 'LANG': 'en_US.UTF-8', 16:37:22 INFO - 'LANGUAGE': 'en_US:en', 16:37:22 INFO - 'LOGNAME': 'cltbld', 16:37:22 INFO - 'MAIL': '/var/mail/cltbld', 16:37:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:37:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:22 INFO - 'MOZ_NO_REMOTE': '1', 16:37:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:37:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:37:22 INFO - 'NO_EM_RESTART': '1', 16:37:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:22 INFO - 'PWD': '/builds/slave/test', 16:37:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:37:22 INFO - 'SHELL': '/bin/bash', 16:37:22 INFO - 'SHLVL': '1', 16:37:22 INFO - 'TERM': 'linux', 16:37:22 INFO - 'TMOUT': '86400', 16:37:22 INFO - 'USER': 'cltbld', 16:37:22 INFO - 'XDG_SESSION_COOKIE': 'f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502', 16:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:22 INFO - '_': '/tools/buildbot/bin/python'} 16:37:22 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:37:23 INFO - mozversion application_buildid: 20160120155520 16:37:23 INFO - mozversion application_changeset: 493d6820745e4e3ed22e48a5f9229ea033a64a3a 16:37:23 INFO - mozversion application_display_name: Nightly 16:37:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:37:23 INFO - mozversion application_name: Firefox 16:37:23 INFO - mozversion application_remotingname: firefox 16:37:23 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:37:23 INFO - mozversion application_vendor: Mozilla 16:37:23 INFO - mozversion application_version: 46.0a1 16:37:23 INFO - mozversion platform_buildid: 20160120155520 16:37:23 INFO - mozversion platform_changeset: 493d6820745e4e3ed22e48a5f9229ea033a64a3a 16:37:23 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:37:23 INFO - mozversion platform_version: 46.0a1 16:37:23 INFO - using testdate: 1453336643 16:37:23 INFO - actual date: 1453336643 16:37:23 INFO - starting webserver on 'localhost:59553' 16:37:23 INFO - SUITE-START | Running 3 tests 16:37:23 INFO - TEST-START | dromaeo_css 16:37:23 INFO - operating with platform_type : linux_ 16:37:23 INFO - Initialising browser for dromaeo_css test... 16:37:23 INFO - TEST-INFO | started process 3309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpThmk63/profile http://localhost:59553/getInfo.html) 16:37:29 INFO - PROCESS | 3309 | -- checkerboarding starts below -- 16:37:29 INFO - PROCESS | 3309 | RENDERTRACE 0.036544 rect red 0 0 1024 768 // viewport 16:37:29 INFO - PROCESS | 3309 | RENDERTRACE 325.639 rect brown 0 0 1024 768 // page 16:37:29 INFO - PROCESS | 3309 | RENDERTRACE 325.66 rect lightgreen 0 0 1024 768 // painted displayport (this layertree updated) 16:37:29 INFO - PROCESS | 3309 | Checkerboarded for 1 frames (332.948 ms), 786432 peak, 12319 severity. 16:37:29 INFO - PROCESS | 3309 | 16:37:29 INFO - PROCESS | 3309 | (process:3392): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:37:30 INFO - PROCESS | 3309 | __metrics Screen width/height:1600/1200 16:37:30 INFO - PROCESS | 3309 | colorDepth:24 16:37:30 INFO - PROCESS | 3309 | Browser inner width/height: 1024/697 16:37:30 INFO - PROCESS | 3309 | __metrics 16:37:30 INFO - PROCESS | 3309 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:37:31 INFO - TEST-INFO | 3309: exit 0 16:37:31 INFO - Browser initialized. 16:37:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:37:31 INFO - TEST-INFO | started process 3441 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpThmk63/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:32 INFO - PROCESS | 3441 | -- checkerboarding starts below -- 16:37:32 INFO - PROCESS | 3441 | RENDERTRACE 0.034997 rect red 0 0 10 10 // viewport 16:37:32 INFO - PROCESS | 3441 | RENDERTRACE 37.6087 rect brown 0 0 10 10 // page 16:37:32 INFO - PROCESS | 3441 | RENDERTRACE 37.6308 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 16:37:32 INFO - PROCESS | 3441 | Checkerboarded for 1 frames (49.1954 ms), 100 peak, 1 severity. 16:37:32 INFO - PROCESS | 3441 | 16:37:32 INFO - PROCESS | 3441 | (process:3497): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:37:43 INFO - PROCESS | 3441 | RSS: Main: 150269952 16:37:43 INFO - PROCESS | 3441 | 16:38:32 INFO - PROCESS | 3441 | 1453336712810 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] 16:40:44 INFO - PROCESS | 3441 | Cycle 1(1): loaded http://localhost:59553/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59553/tests/dromaeo/cssquery-ext.html) 16:40:45 INFO - PROCESS | 3441 | RSS: Main: 155181056 16:40:45 INFO - PROCESS | 3441 | 16:43:47 INFO - PROCESS | 3441 | Cycle 1(1): loaded http://localhost:59553/tests/dromaeo/cssquery-ext.html (next: http://localhost:59553/tests/dromaeo/cssquery-jquery.html) 16:43:48 INFO - PROCESS | 3441 | RSS: Main: 152227840 16:43:48 INFO - PROCESS | 3441 | 16:46:50 INFO - PROCESS | 3441 | Cycle 1(1): loaded http://localhost:59553/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59553/tests/dromaeo/cssquery-mootools.html) 16:46:51 INFO - PROCESS | 3441 | RSS: Main: 152014848 16:46:51 INFO - PROCESS | 3441 | 16:49:52 INFO - PROCESS | 3441 | Cycle 1(1): loaded http://localhost:59553/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59553/tests/dromaeo/cssquery-prototype.html) 16:49:53 INFO - PROCESS | 3441 | RSS: Main: 150523904 16:49:53 INFO - PROCESS | 3441 | 16:52:56 INFO - PROCESS | 3441 | Cycle 1(1): loaded http://localhost:59553/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59553/tests/dromaeo/cssquery-yui.html) 16:52:56 INFO - PROCESS | 3441 | RSS: Main: 150290432 16:52:56 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | Cycle 1(1): loaded http://localhost:59553/tests/dromaeo/cssquery-yui.html (next: http://localhost:59553/tests/dromaeo/cssquery-dojo.html) 16:55:59 INFO - PROCESS | 3441 | RSS: Main: 152670208 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | __start_tp_report 16:55:59 INFO - PROCESS | 3441 | _x_x_mozilla_page_load 16:55:59 INFO - PROCESS | 3441 | _x_x_mozilla_page_load_details 16:55:59 INFO - PROCESS | 3441 | |i|pagename|runs| 16:55:59 INFO - PROCESS | 3441 | |0;dojo.html;2574.4722331921957;2588.4434846507997;2618.1621880998077;2638.564636834922;2645.64283821684;4459.487158976711;4497.325401189815;4509.797059132335;4532.932006019908;4568.314752787083;4512.368268442421;4534.659900507467;4575.908481830362;4625.4680711718165;4632.606228470584;4033.240997229917;4229.111886503831;4249.808758605855;4300.935485967713;4435.667324950641;4140.648044916171;4323.048929235563;4359.28071868143;4364.301711726124;4376.146651402978;4164.583541645811;4177.603127702879;4208.473940757405;4213.262679031157;4238.131183107455;4186.91626167478;4190.497140343178;4209.600087991666;4216.198922204771;4247.256730072256;4076.979615101935;4121.979390103061;4191.245575796355;4193.580641935812;4206.684498662582;4389.582989615981;4462.973516091285;4471.3293006049025;4489.21438748221;4492.84275050375;4792.257200133985;4852.660313778037;4894.290327902434;4901;4942.950570494285;4748.264019077049;4771.6421268404665;4821.710697358135;4862.80548778052;4875.609951203931;4542.772861356985;4543.250363689954;4551.226291530448;4565.178267911741;4589.219832628494;5036.370453693288;5048.596112311006;5049.116404629175;5051.191809310566;5061.089003979319;4702.764861756898;4742.454617718938;4789.640776941744;4852.757362131879;4895.534924182197;4931.827386041471;4942.085714142895;4942.950570494249;4964.478729733398;4970.130227210224;4899.559039686445;4901.705897646153;4902.534259245366;4905.828296009622;4920.975395123001;4693.78877950493;4741.2651039089;4760.547747964007;4762.476127625959;4911.778969946361;4050.311447054008;4050.513938327361;4073.551909289976;4087.1212689271356;4157.501099868035;3523.8766643167364;3533.3993221152464;3539.94690079649;3566.6433356664643;3577.856885724542;4167.895802604899;4181.832726690959;4193.790310484464;4225.112726327444;4226.323788193874;4787.54518320759;4827.541383568555;4968.453470118284;4987.925181122286;5019.899602008012;4222.641075508554;4264.0832221072;4292.227399068198;4316.568343165721;4326.697131200785;4269.551697071763;4414.293713005904;4414.613003095975;4415.845445409395;4432.047109871329;4625.213713668684;4694.061187762393;4705.647076469347;4719.669623126349;4751.714897106184;4910.386201724785;4953.356063711839;4991.376077990251;4994.300797888226;5010.498950105106;4579.771011449481;4641.234196357564;4690.249560070372;4730.2431610942085;4736.336912832137;4719.669623126349;4854.320395144753;4874.7562621868055;4883.145449546386;4911.9754401227765;4869.415670119608;4877.756112194305;4880.41435027799;4882.145624515767;4883.291922671264;4612.700174488782;4676.321933319718;4686.789094490688;4743.501932297059;4757.785899634456;4681;4744.572988430919;4754.881127971828;4788.353572267699;4810.6151507878585;4552.613027892667;4566.063956888764;4592.977035114803;4617.076584683144;4652.046330502171;4637.907241855077;4707.905841883075;4738.8341408050555;4739.360186374934;4762.285657151317;4638.605718513829;4641.814327426865;4645.163870503342;4668.766561671835;4695.342652028787 16:55:59 INFO - PROCESS | 3441 | |1;ext.html;481.92289233722613;503.192376236141;504.0574126383663;515.5875299760191;519.470140456734;31250.68749312509;31551.211219719462;31592.68407315929;31622;31685.524717129232;35493.29013419736;35701.64298357016;36157.27685446296;36159.63840361596;36177.27645447089;7540.886886696704;7704.19105993871;7754.961225193866;7770.339521140681;7778.494397864149;7585.81035474112;7653.31120199182;7723.304902558769;7737.961310193469;7742.8451430971345;7910.050793904739;7984.960075199645;7994.3204827589725;8035;8037.95981020097;7845.607719614025;7931.643076061591;7947.960260198691;7952.880706789403;7970.043594768606;7633.236676332378;7761.8447631047065;7818.6090695465;7819.687212511492;7824.452288339819;35723.82138089319;35823;36032.63967360345;36040.819795901116;36321.09197270063;2769.62610047643;2844.3742376677096;2866.5986761853355;2887.22045047838;2887.956680649792;27429.725702742915;27434.725652743615;27729.722702772917;27975.440491190267;28098.297542561395;3370.2053159318834;3453.326601312745;3489.70337521311;3544.5746510418917;3604.7837129772033;160206.19896900558;162377;162685;163060.18469907573;163859;43545.782271089076;44420.55579444229;51781.482185177665;52070.47929520656;52120;54007.459925400246;55607;55620.72189639107;55674.443255567734;55923.72038139865;12554.686132846751;12787;12998.935005324913;13098.672533186556;13108.934455327662;9061.501617410973;9073.31950103745;9151.816963660822;9199.448033118035;9271.95364023189;17478.30086796514;17732.11339433023;17750.91124544395;17807;17890.194941227677;6852.828679282959;7004.089468369181;7132.393746531499;7155;7193.244709305448;822.5311572403673;865.5845194306642;874.50289852205;945.1965829045394;973.9026097390205;25659.615105773428;25799.484010319688;25952;25971;26032.219033429028;990.7325022243954;1049.634855798985;1078.6710053433778;1084.7125511739396;1089.54239219528;13782.448702051806;14837.554873353816;14933;14935.775963360558;15075;252124;254362;256083;256330;257392;40874.591254087674;40888.591114089075;41031.79484102561;41169.58830411657;41261.174776504304;1184.1829137894824;1195.3306148556835;1213.2659740856832;1214.0833670578834;1322.7751282282036;25446.854891529554;26055.39446055455;26226.344341391617;26267.343316417242;26269;37996;38091.61908380881;38311;38423.2315353697;38469.807650961564;197044;198844;199839;242223;242479;26529;26762;27100.72899270982;27320.59019114675;27418;36260;36354.45468317925;36788.44817327796;36873.81563092167;37497.25005499929;27643.861780690968;27984.72015279821;28232.29419264535;28425.147245582666;28542;27014;27339.86330068337;27671.169864904085;27869;27979.74091165943;28217;28438.289042774097;28562.57156142618;28621.141365758227;28735.557477613802;26321.341966450993;26927.192184234504;28152.577711333935;28969.420611588073;28970.13089607315;20862.895685521475;21358.572828543653;22006.339809805733;22217.333479995625;22371.44071398228 16:55:59 INFO - PROCESS | 3441 | |2;jquery.html;329.55510061417084;329.95380646709464;332.06689203338607;334.51328317298334;336.12774849266145;11766.529338826436;12199.939000305008;13898.166110033406;14494.637634059141;15064.472743453982;13981.650458738537;14540.272986350694;14548.563543093698;15193.164375959317;15255.008424452431;4765.499622539635;4772.403449568804;4931.753412329387;4962.280469331945;4962.776675049631;4824.6863953842885;4881.365422495071;5013.473585273548;5023.773930173151;5025.623078269126;4959.330490383788;4995.275685025669;5021.774020169083;5481.808136715215;5485.753141108639;5489.670619762827;5611.046122159243;5649.1243857202;5655.349634791969;5655.886882262353;5122.615803814691;5123.666961647481;5125.333706618153;5130.51260130287;5135.4094279157625;11031.172662050298;11061.778764424747;11215.327080375204;11225.326480411202;11329.490172942238;1725.2667616263038;1741.1381366223673;1756.9912150439166;1809.6109336492586;1836.7061270196705;8179.100298967109;8668.133186681345;9114.496108142148;9592.66425675098;12061.216020958698;1696.236693487923;1732.4196394207956;1761.1018380625844;1765.48800847754;1771.6102457459467;46291.76854115741;48567.51432485665;48770;48868.02263954701;49037.019259614965;9007.684731034513;9286.164245217962;9321.487318197564;9348.532573371303;9364.578593963288;11977.401129943468;11997.100217483723;12028.819567706492;12088.153829232046;12170.148089633818;6387.457066149336;6541.378569035934;6552.213734351908;6571.080048793173;6624.86750264992;6194.101855231056;6195.070739389128;6213.1922850029205;6222.253329600477;6223.564350495421;3868.6905047596124;4015.297322968469;4058.289799285113;4082.4896887889013;4113.218488487178;1665.6585399992973;1693.2211182856024;1695.7965044194775;1703.1824724132487;1717.3302412059306;381.5078548672237;830.7466222802103;844.4004756622744;870.5951732444444;884.8628462588181;8619.598536537105;9100.954495227614;9343.672971445967;9485.430874147572;10058.245631577662;404.56711318888506;405.12088767374865;410.34140204970595;421.5994804935324;421.83126749300527;5440.727963601883;5499.1476321170285;5501.642393244426;5578.191162281528;5586.972065139648;433594;433737;437638;438572;448081;62441;64004.67997659982;65298.041058768315;65366;66372;416.9228692691801;425.29613489674637;425.9190753756714;426.3902619254434;428.45372150508354;8566.143385661633;8580.914190858011;8793.692220772242;8814.647414103363;8868.911310886808;27399.726002739717;27733.30666733348;28092.297692557033;28499.572506412835;28504.57243141313;39261.41107883441;40680.59319406768;40745.79627101846;41196.588034119275;42631.147377052905;3813.542374915028;6504.1219435375615;6536.281009089092;6634.800955971328;6670.533062685566;6836.872345740694;7804.726834560764;8966.89071446353;9149.268058555166;9430.481323527272;8432.53621050848;8496.70261540843;8559.144085591392;8560.14398560139;8694.91305086941;7867.347856950105;8223.424360294723;8565.871511927451;8628.050914399537;8869.334799889904;7740.574268415066;8606.483610983361;8698.91301086981;8730.519821409884;8937.642494300155;6479.643619600779;6481.449076828522;6613.43785778195;6670.566413183127;6683.298253683293;7704.730334438492;8562.828743425222;8649.308055355683;8836.469811811312;8867.068957759342 16:55:59 INFO - PROCESS | 3441 | |3;mootools.html;1346;1365.5288925320765;1454.3382760843817;1635.2314412226249;1650.422352176739;6574.769883054094;6584.473242140629;6741.865162696749;6782.2200446948555;6802.081718967938;6759.864802703955;6767.424768894638;6864.24493305736;6891.483138764587;6896.344847239517;2009.9497512562157;2050.8256798172174;2056.969145462819;2057.701633263176;2057.969130463044;2058.7632422271404;2063.7626672932656;2071.099071903727;2081.396395045435;2089.9791002090014;1762.427211156373;1811.628616133696;1944.6985717213854;2044.6728523436252;2076.3355726167633;1947.2405761752927;1948.6687263165225;1949.2592814730453;1956.1197461142542;1960.2551030608277;1929.3150931419284;1949.4736421166347;1994.3618042226349;2005.5788284460134;2006.107282259395;6817.386435220805;6857.657117144123;6880.759173428956;6882.311768823128;6896.724131034753;3951.3480275754464;4039.4748682671357;4076.653484453825;4085.9182816343805;4096.651784598313;2663.587143992684;2709.322669332667;2720.3743139077924;2723;2745.437185376993;855.7732200966749;865.3726048614767;865.5282870835409;880.1374652840188;905.9320550958638;8801.295896328278;8883.333749968779;8889.822203555892;8945.597448114786;8991.865122023175;3469.5058673265626;3497.108237399447;3502.6322236165347;3538;3612;7453.105627324648;7457.5898325591525;7461.328480436787;7465.7013719450615;7490.363319117936;3953.2291203215095;3955.208958208312;3960.485136932238;3984.2828290907337;3984.9003774905277;3648.7628304160144;3660.048387419237;3664.029032306441;3669.3945498992366;3684.060564556021;3014.3971205758935;3032.818030918152;3040.467918114321;3063.938721225563;3064.7241748242764;932.3240650528448;933.0622724162174;936.3352020065694;946.1012038563392;946.2524605561667;776.4176867349488;780.4458834227762;917.9082091790767;1100.4497751124438;1128.210252823027;6038.215032045806;6060.575759696779;6083.8174854754425;6129.356417576179;6136.8772624548155;564.8418442836013;565.4684596479378;597.0685730260807;605.4339192854695;707.8301207710216;4890.975545122323;4948.628852836052;4984.8753781155765;4985.5762260208285;4996.4254110777;7441.441891858132;7609.847803043908;7932.405069619802;7964.761057168178;7972.242636949481;7734.342580880688;7827.530348179127;7844.137144914055;7880.960595196987;7884.763457096296;6108.6029408089225;6196.47329976948;6266.028765541349;6288.402601752826;6329.968350158312;6001.969990150022;6007.219061521974;6009.429104235091;6017.217761690952;6018.729157188025;6932.584044957319;7079.929200708133;7137.571745695275;7143.178534468602;7202.89195662076;7079.39825114871;7223.205447400886;7254.927450725425;7263.16473605521;7328.633568321669;5779.162021506942;5876.118582212703;5881.235439392852;5885.440883116097;5885.499732522783;6147.415995480422;6148.78479253224;6166.81499555014;6198.597091189058;6423.389777971085;5985.528393531828;6062.575619706578;6118.051701986199;6205.348438413902;6231.657258850807;6053.4551890330085;6085.482733967485;6123.785667501616;6169.2288463942;6303.558750887577;6272.18461599989;6276.435120839146;6340.239171299474;6342.175517182921;6361.777337793155;5536;5652.802151924663;5669.603127781016;5670.177824215548;5689.857753556194;5567.359753628384;5590.440955904377;5603.0474819281635;5615.6630602164005;5618.157276408571 16:55:59 INFO - PROCESS | 3441 | |4;prototype.html;278.6099460754943;287.43805859544597;291.64710700052933;300.936803271313;300.9458297506448;5541.196526503654;5556.833295001146;5635.182898479718;5647.5764317676185;5655.4910058094765;5460.918086228699;5467.9453205467935;5468.890622187554;5507.173923911415;5524.116141417374;4254.638355739766;4262.318029115342;4267.701260911737;4273.188094262096;4273.529911709709;4098.938515922264;4198.748075115487;4216.7680777557225;4237.300845360512;4246.596573325529;4199.097194103268;4199.370094485821;4200.663946884257;4202.768847713374;4209.179210054041;4195.391668208093;4197.832086716543;4200.684948628865;4216.831326746927;4217.810198541073;3647.252313275772;3650.2881938022097;3652.196516766307;3655.1775850433705;3660.267946410702;5195.376554813409;5214.243934629458;5344.625876188668;5358.678479291216;5374.3013408256675;4851.684640498356;4855.708657480563;4857.052874689437;4857.222844344888;4862.222044472867;5742.54059675225;5750.683712395817;5774.307083150049;5781.942180578182;5790.218320526717;3933.449317095609;3938.7833669148185;3943.211357728466;3947.723659343847;3948.881533553998;5900.498457631106;6150.569460137792;6152.292486364081;6171;6174;4091.0590564170407;4097.426360309559;4113.506379234451;4114.485689288839;4118.814653340607;4143.81352839123;4152.647025002848;4155.168966206771;4157.563455837154;4160.708750387444;4816.927746083811;4823.397075365579;4825.10735513931;4829.034193161311;4847.297141914402;5734.62724922887;5795.362510123883;5851.239338885917;5869.354371019184;5874.853128671817;1453.5276035288362;1476.1290838405178;1476.512750792236;1479.3860547872512;1480.2820631993475;954.3033585482636;955.1833182628752;960.6253561111172;963.0706368354571;970.9951450242703;5038.093143234253;5055.595552355802;5073.34046573943;5083.186690129635;5097.235414687752;6056.273247210363;6057.454829065406;6059.36376680451;6059.939400605937;6066.423689749467;5065.062963351792;5083.161278389024;5091.3126727892;5097.566706829961;5103.234514822806;3204.951925721116;3259.7066264036353;3266.5426840242385;3275.819829909378;3284.868605255763;5699.173619825102;5700;5703.315602127771;5720.77116915327;5725.227094342294;6035.547333949971;6115.663638499836;6173.938260617337;6215.96892015537;6272.870883240969;5706.543476521785;5720.427957204412;5727.0550359190265;5740.827775166752;5744.0522313817755;6000.909986350121;6083.391660833881;6083.634981900923;6132.08018797184;6132.754689812357;5751.4536119068625;5934.821955341346;5977.163197152315;5997.340292567901;6000.159977603052;5762.5389968801555;5764.855878603068;5767.134929760569;5767.711614419346;5771.68255745938;4005.2790497710694;4032.798360082043;4044.3326851069246;4044.939325910055;4053.8986525337104;5942.10868369748;6090.056041313604;6093.908591371044;6101.572889897665;6103.328633850362;5804.926088673609;5806.071028635398;5872.794452194153;5873.618214816023;5876.08920617305;5826.796062137805;5844.977129002323;5864.179014937998;5877.764889404547;5885.764569417347;5700.686462244616;5739.167820666064;5745.511631511368;5752.798652047159;5757.7121143941795;5699.658020518782;5870.412958704095;5903.645781253139;5906;5922.2597175353085;5722.399148089391;5760.884782304247;5764.855878603068;5768.394318596655;5769.0481070624555 16:55:59 INFO - PROCESS | 3441 | |5;yui.html;601.6450294326349;619.2909119058678;650.031453134829;684.0730809752785;686.204003356107;11011.779764404708;11028.669139925794;11094.223404361697;11130.944345278282;11132.944335278322;11141.777164456706;11702.765944681101;11732.413379331021;11733.178677492579;11746.53013879446;587.2395248153651;598.4643743849252;711.8896571031498;742.6286856571714;749.2507492507492;737.4469148138896;745.1765798792338;745.5601195294774;747.6523416611275;751.887216917464;791.4064451661254;792.7542461836849;792.9088154862206;793.1949071692237;794.3446656508404;754.5812074298761;784.3254800871246;785.140271402813;787.248177990021;787.3032366355759;578.3349148479241;582.8280657206134;583.4194975998865;584.4476969264048;585.7949717598849;11390.829137562943;11405.771884562346;11442;11465.08279337651;11523.827142592867;11685.415729213506;11850.644480665595;11936.641900742992;12022.398880055962;12053.276803391736;9485.620575176985;9646.662366817127;9650.420974741468;9695.22438204949;9932.05645463687;3995.6603688686496;4078.1028173801715;4082.4488694026522;4179.791010449496;4195.307774217281;40569.797151014354;40604.98487537805;40933.79533102345;41059.58940410588;41066;8676.522791246542;9309.674161404319;9368.110029547319;9380.718578442658;9628.229741620653;15731.842681573038;15761.605959851095;15784.13187274711;15798.7630185545;15813.68372632541;6398.648074355955;6484.967575162188;6495.577787443801;6685.130932978682;6779.966100169566;3671.7246206534082;3769.378052621342;3774.830132644038;3781.111438811877;3825.1202223488754;2543.923682289534;2547.7197508274076;2548.7833534149436;2553.527597394488;2561.6669832921602;450.794888325811;456.77161419290354;459.1116190171961;464.7118786352483;465.1510992438745;510.22445882258756;567.4706665536422;594.5362617158623;602.3584882100637;604.1243680082837;6779.254282028973;6921.377076063179;6975.755848545276;7003.54476958996;7090.716371345192;530.5993974549191;554.4012466536132;558.6731761919323;565.8302509247292;583.3146053387253;3365.3101114271517;3366.208940898887;3406.982965085159;3446.0867870014467;3503.8072905989907;48041.519584803704;48534.51465485371;48630.75684621554;48810.5118948806;48828.5117148824;40817.59182408138;40857.79571102125;41642.167156657306;41909.161816764106;41979;38298.42552361717;38486.61513384886;38649.22701545953;38952.80523597364;39014.219715605526;6769.849125648553;6882.621455819778;6906.827329316607;6953.235144134243;7017.824554386181;30419.391612168074;30684;30743;30822.383552329276;31767.682323176472;36548.26903461969;36876;37665;37670.8116459416;37995.2400951985;9750.80498390014;9773.462459564793;9979.750506237402;10164.186865050926;10347.43089130105;10472.371657700562;10753.193510486588;10939.234253602172;11128.998390144601;11904.880951190376;9862;10619.15046796239;10894.291871028357;10959.287646302964;11197.888021119685;9876.901230987598;9961.252906032221;10093.142082923034;10291.022352876465;10709.732256693645;9161.954190229006;9341.80482477895;10224.182065434896;10225.539850706587;10297.588096476056;8632.78418039554;8737.519436430803;8802.427842190236;9072.092790721139;9347.626094956126;8640.26557742599;8667.306615470621;9176.908230917605;9199.126083022102;9376.517095499114 16:55:59 INFO - PROCESS | 3441 | __end_tp_report 16:55:59 INFO - PROCESS | 3441 | __start_cc_report 16:55:59 INFO - PROCESS | 3441 | _x_x_mozilla_cycle_collect,371 16:55:59 INFO - PROCESS | 3441 | __end_cc_report 16:55:59 INFO - PROCESS | 3441 | __startTimestamp1453337759618__endTimestamp 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | ------- Summary: start ------- 16:55:59 INFO - PROCESS | 3441 | Number of tests: 6 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | [#0] dojo.html Cycles:165 Average:4513.40 Median:4637.91 stddev:470.47 (10.1%) stddev-sans-first:446.65 16:55:59 INFO - PROCESS | 3441 | Values: 2574.5 2588.4 2618.2 2638.6 2645.6 4459.5 4497.3 4509.8 4532.9 4568.3 4512.4 4534.7 4575.9 4625.5 4632.6 4033.2 4229.1 4249.8 4300.9 4435.7 4140.6 4323.0 4359.3 4364.3 4376.1 4164.6 4177.6 4208.5 4213.3 4238.1 4186.9 4190.5 4209.6 4216.2 4247.3 4077.0 4122.0 4191.2 4193.6 4206.7 4389.6 4463.0 4471.3 4489.2 4492.8 4792.3 4852.7 4894.3 4901.0 4943.0 4748.3 4771.6 4821.7 4862.8 4875.6 4542.8 4543.3 4551.2 4565.2 4589.2 5036.4 5048.6 5049.1 5051.2 5061.1 4702.8 4742.5 4789.6 4852.8 4895.5 4931.8 4942.1 4943.0 4964.5 4970.1 4899.6 4901.7 4902.5 4905.8 4921.0 4693.8 4741.3 4760.5 4762.5 4911.8 4050.3 4050.5 4073.6 4087.1 4157.5 3523.9 3533.4 3539.9 3566.6 3577.9 4167.9 4181.8 4193.8 4225.1 4226.3 4787.5 4827.5 4968.5 4987.9 5019.9 4222.6 4264.1 4292.2 4316.6 4326.7 4269.6 4414.3 4414.6 4415.8 4432.0 4625.2 4694.1 4705.6 4719.7 4751.7 4910.4 4953.4 4991.4 4994.3 5010.5 4579.8 4641.2 4690.2 4730.2 4736.3 4719.7 4854.3 4874.8 4883.1 4912.0 4869.4 4877.8 4880.4 4882.1 4883.3 4612.7 4676.3 4686.8 4743.5 4757.8 4681.0 4744.6 4754.9 4788.4 4810.6 4552.6 4566.1 4593.0 4617.1 4652.0 4637.9 4707.9 4738.8 4739.4 4762.3 4638.6 4641.8 4645.2 4668.8 4695.3 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | [#1] ext.html Cycles:180 Average:36353.82 Median:31571.95 stddev:56006.59 (177.4%) stddev-sans-first:56098.94 16:55:59 INFO - PROCESS | 3441 | Values: 481.9 503.2 504.1 515.6 519.5 31250.7 31551.2 31592.7 31622.0 31685.5 35493.3 35701.6 36157.3 36159.6 36177.3 7540.9 7704.2 7755.0 7770.3 7778.5 7585.8 7653.3 7723.3 7738.0 7742.8 7910.1 7985.0 7994.3 8035.0 8038.0 7845.6 7931.6 7948.0 7952.9 7970.0 7633.2 7761.8 7818.6 7819.7 7824.5 35723.8 35823.0 36032.6 36040.8 36321.1 2769.6 2844.4 2866.6 2887.2 2888.0 27429.7 27434.7 27729.7 27975.4 28098.3 3370.2 3453.3 3489.7 3544.6 3604.8 160206.2 162377.0 162685.0 163060.2 163859.0 43545.8 44420.6 51781.5 52070.5 52120.0 54007.5 55607.0 55620.7 55674.4 55923.7 12554.7 12787.0 12998.9 13098.7 13108.9 9061.5 9073.3 9151.8 9199.4 9272.0 17478.3 17732.1 17750.9 17807.0 17890.2 6852.8 7004.1 7132.4 7155.0 7193.2 822.5 865.6 874.5 945.2 973.9 25659.6 25799.5 25952.0 25971.0 26032.2 990.7 1049.6 1078.7 1084.7 1089.5 13782.4 14837.6 14933.0 14935.8 15075.0 252124.0 254362.0 256083.0 256330.0 257392.0 40874.6 40888.6 41031.8 41169.6 41261.2 1184.2 1195.3 1213.3 1214.1 1322.8 25446.9 26055.4 26226.3 26267.3 26269.0 37996.0 38091.6 38311.0 38423.2 38469.8 197044.0 198844.0 199839.0 242223.0 242479.0 26529.0 26762.0 27100.7 27320.6 27418.0 36260.0 36354.5 36788.4 36873.8 37497.3 27643.9 27984.7 28232.3 28425.1 28542.0 27014.0 27339.9 27671.2 27869.0 27979.7 28217.0 28438.3 28562.6 28621.1 28735.6 26321.3 26927.2 28152.6 28969.4 28970.1 20862.9 21358.6 22006.3 22217.3 22371.4 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | [#2] jquery.html Cycles:180 Average:22745.24 Median:5024.70 stddev:71738.40 (1427.7%) stddev-sans-first:71919.90 16:55:59 INFO - PROCESS | 3441 | Values: 329.6 330.0 332.1 334.5 336.1 11766.5 12199.9 13898.2 14494.6 15064.5 13981.7 14540.3 14548.6 15193.2 15255.0 4765.5 4772.4 4931.8 4962.3 4962.8 4824.7 4881.4 5013.5 5023.8 5025.6 4959.3 4995.3 5021.8 5481.8 5485.8 5489.7 5611.0 5649.1 5655.3 5655.9 5122.6 5123.7 5125.3 5130.5 5135.4 11031.2 11061.8 11215.3 11225.3 11329.5 1725.3 1741.1 1757.0 1809.6 1836.7 8179.1 8668.1 9114.5 9592.7 12061.2 1696.2 1732.4 1761.1 1765.5 1771.6 46291.8 48567.5 48770.0 48868.0 49037.0 9007.7 9286.2 9321.5 9348.5 9364.6 11977.4 11997.1 12028.8 12088.2 12170.1 6387.5 6541.4 6552.2 6571.1 6624.9 6194.1 6195.1 6213.2 6222.3 6223.6 3868.7 4015.3 4058.3 4082.5 4113.2 1665.7 1693.2 1695.8 1703.2 1717.3 381.5 830.7 844.4 870.6 884.9 8619.6 9101.0 9343.7 9485.4 10058.2 404.6 405.1 410.3 421.6 421.8 5440.7 5499.1 5501.6 5578.2 5587.0 433594.0 433737.0 437638.0 438572.0 448081.0 62441.0 64004.7 65298.0 65366.0 66372.0 416.9 425.3 425.9 426.4 428.5 8566.1 8580.9 8793.7 8814.6 8868.9 27399.7 27733.3 28092.3 28499.6 28504.6 39261.4 40680.6 40745.8 41196.6 42631.1 3813.5 6504.1 6536.3 6634.8 6670.5 6836.9 7804.7 8966.9 9149.3 9430.5 8432.5 8496.7 8559.1 8560.1 8694.9 7867.3 8223.4 8565.9 8628.1 8869.3 7740.6 8606.5 8698.9 8730.5 8937.6 6479.6 6481.4 6613.4 6670.6 6683.3 7704.7 8562.8 8649.3 8836.5 8867.1 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | [#3] mootools.html Cycles:180 Average:4658.74 Median:6028.47 stddev:2392.96 (39.7%) stddev-sans-first:2386.72 16:55:59 INFO - PROCESS | 3441 | Values: 1346.0 1365.5 1454.3 1635.2 1650.4 6574.8 6584.5 6741.9 6782.2 6802.1 6759.9 6767.4 6864.2 6891.5 6896.3 2009.9 2050.8 2057.0 2057.7 2058.0 2058.8 2063.8 2071.1 2081.4 2090.0 1762.4 1811.6 1944.7 2044.7 2076.3 1947.2 1948.7 1949.3 1956.1 1960.3 1929.3 1949.5 1994.4 2005.6 2006.1 6817.4 6857.7 6880.8 6882.3 6896.7 3951.3 4039.5 4076.7 4085.9 4096.7 2663.6 2709.3 2720.4 2723.0 2745.4 855.8 865.4 865.5 880.1 905.9 8801.3 8883.3 8889.8 8945.6 8991.9 3469.5 3497.1 3502.6 3538.0 3612.0 7453.1 7457.6 7461.3 7465.7 7490.4 3953.2 3955.2 3960.5 3984.3 3984.9 3648.8 3660.0 3664.0 3669.4 3684.1 3014.4 3032.8 3040.5 3063.9 3064.7 932.3 933.1 936.3 946.1 946.3 776.4 780.4 917.9 1100.4 1128.2 6038.2 6060.6 6083.8 6129.4 6136.9 564.8 565.5 597.1 605.4 707.8 4891.0 4948.6 4984.9 4985.6 4996.4 7441.4 7609.8 7932.4 7964.8 7972.2 7734.3 7827.5 7844.1 7881.0 7884.8 6108.6 6196.5 6266.0 6288.4 6330.0 6002.0 6007.2 6009.4 6017.2 6018.7 6932.6 7079.9 7137.6 7143.2 7202.9 7079.4 7223.2 7254.9 7263.2 7328.6 5779.2 5876.1 5881.2 5885.4 5885.5 6147.4 6148.8 6166.8 6198.6 6423.4 5985.5 6062.6 6118.1 6205.3 6231.7 6053.5 6085.5 6123.8 6169.2 6303.6 6272.2 6276.4 6340.2 6342.2 6361.8 5536.0 5652.8 5669.6 5670.2 5689.9 5567.4 5590.4 5603.0 5615.7 5618.2 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | [#4] prototype.html Cycles:180 Average:4812.83 Median:5549.01 stddev:1441.88 (26.0%) stddev-sans-first:1405.19 16:55:59 INFO - PROCESS | 3441 | Values: 278.6 287.4 291.6 300.9 300.9 5541.2 5556.8 5635.2 5647.6 5655.5 5460.9 5467.9 5468.9 5507.2 5524.1 4254.6 4262.3 4267.7 4273.2 4273.5 4098.9 4198.7 4216.8 4237.3 4246.6 4199.1 4199.4 4200.7 4202.8 4209.2 4195.4 4197.8 4200.7 4216.8 4217.8 3647.3 3650.3 3652.2 3655.2 3660.3 5195.4 5214.2 5344.6 5358.7 5374.3 4851.7 4855.7 4857.1 4857.2 4862.2 5742.5 5750.7 5774.3 5781.9 5790.2 3933.4 3938.8 3943.2 3947.7 3948.9 5900.5 6150.6 6152.3 6171.0 6174.0 4091.1 4097.4 4113.5 4114.5 4118.8 4143.8 4152.6 4155.2 4157.6 4160.7 4816.9 4823.4 4825.1 4829.0 4847.3 5734.6 5795.4 5851.2 5869.4 5874.9 1453.5 1476.1 1476.5 1479.4 1480.3 954.3 955.2 960.6 963.1 971.0 5038.1 5055.6 5073.3 5083.2 5097.2 6056.3 6057.5 6059.4 6059.9 6066.4 5065.1 5083.2 5091.3 5097.6 5103.2 3205.0 3259.7 3266.5 3275.8 3284.9 5699.2 5700.0 5703.3 5720.8 5725.2 6035.5 6115.7 6173.9 6216.0 6272.9 5706.5 5720.4 5727.1 5740.8 5744.1 6000.9 6083.4 6083.6 6132.1 6132.8 5751.5 5934.8 5977.2 5997.3 6000.2 5762.5 5764.9 5767.1 5767.7 5771.7 4005.3 4032.8 4044.3 4044.9 4053.9 5942.1 6090.1 6093.9 6101.6 6103.3 5804.9 5806.1 5872.8 5873.6 5876.1 5826.8 5845.0 5864.2 5877.8 5885.8 5700.7 5739.2 5745.5 5752.8 5757.7 5699.7 5870.4 5903.6 5906.0 5922.3 5722.4 5760.9 5764.9 5768.4 5769.0 16:55:59 INFO - PROCESS | 3441 | 16:55:59 INFO - PROCESS | 3441 | [#5] yui.html Cycles:180 Average:11898.91 Median:464.93 stddev:13312.71 (2863.4%) stddev-sans-first:13323.02 16:55:59 INFO - PROCESS | 3441 | Values: 601.6 619.3 650.0 684.1 686.2 11011.8 11028.7 11094.2 11130.9 11132.9 11141.8 11702.8 11732.4 11733.2 11746.5 587.2 598.5 711.9 742.6 749.3 737.4 745.2 745.6 747.7 751.9 791.4 792.8 792.9 793.2 794.3 754.6 784.3 785.1 787.2 787.3 578.3 582.8 583.4 584.4 585.8 11390.8 11405.8 11442.0 11465.1 11523.8 11685.4 11850.6 11936.6 12022.4 12053.3 9485.6 9646.7 9650.4 9695.2 9932.1 3995.7 4078.1 4082.4 4179.8 4195.3 40569.8 40605.0 40933.8 41059.6 41066.0 8676.5 9309.7 9368.1 9380.7 9628.2 15731.8 15761.6 15784.1 15798.8 15813.7 6398.6 6485.0 6495.6 6685.1 6780.0 3671.7 3769.4 3774.8 3781.1 3825.1 2543.9 2547.7 2548.8 2553.5 2561.7 450.8 456.8 459.1 464.7 465.2 510.2 567.5 594.5 602.4 604.1 6779.3 6921.4 6975.8 7003.5 7090.7 530.6 554.4 558.7 565.8 583.3 3365.3 3366.2 3407.0 3446.1 3503.8 48041.5 48534.5 48630.8 48810.5 48828.5 40817.6 40857.8 41642.2 41909.2 41979.0 38298.4 38486.6 38649.2 38952.8 39014.2 6769.8 6882.6 6906.8 6953.2 7017.8 30419.4 30684.0 30743.0 30822.4 31767.7 36548.3 36876.0 37665.0 37670.8 37995.2 9750.8 9773.5 9979.8 10164.2 10347.4 10472.4 10753.2 10939.2 11129.0 11904.9 9862.0 10619.2 10894.3 10959.3 11197.9 9876.9 9961.3 10093.1 10291.0 10709.7 9162.0 9341.8 10224.2 10225.5 10297.6 8632.8 8737.5 8802.4 9072.1 9347.6 8640.3 8667.3 9176.9 9199.1 9376.5 16:55:59 INFO - PROCESS | 3441 | -------- Summary: end -------- 16:55:59 INFO - PROCESS | 3441 | 16:56:00 INFO - TEST-INFO | 3441: exit 0 16:56:00 INFO - TEST-OK | dromaeo_css | took 1116854ms 16:56:00 INFO - TEST-START | kraken 16:56:00 INFO - operating with platform_type : linux_ 16:56:00 INFO - Initialising browser for kraken test... 16:56:00 INFO - TEST-INFO | started process 3682 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4xpIZk/profile http://localhost:59553/getInfo.html) 16:56:04 INFO - PROCESS | 3682 | -- checkerboarding starts below -- 16:56:04 INFO - PROCESS | 3682 | RENDERTRACE 0.034406 rect red 0 0 1024 768 // viewport 16:56:04 INFO - PROCESS | 3682 | RENDERTRACE 294.265 rect brown 0 0 1024 768 // page 16:56:04 INFO - PROCESS | 3682 | RENDERTRACE 294.311 rect lightgreen 0 0 1024 768 // painted displayport (this layertree updated) 16:56:04 INFO - PROCESS | 3682 | Checkerboarded for 1 frames (300.459 ms), 786432 peak, 10956 severity. 16:56:04 INFO - PROCESS | 3682 | 16:56:04 INFO - PROCESS | 3682 | (process:3753): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:56:05 INFO - PROCESS | 3682 | __metrics Screen width/height:1600/1200 16:56:05 INFO - PROCESS | 3682 | colorDepth:24 16:56:05 INFO - PROCESS | 3682 | Browser inner width/height: 1024/697 16:56:05 INFO - PROCESS | 3682 | __metrics 16:56:05 INFO - PROCESS | 3682 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:56:06 INFO - TEST-INFO | 3682: exit 0 16:56:06 INFO - Browser initialized. 16:56:06 INFO - Running cycle 1/1 for kraken test... 16:56:06 INFO - TEST-INFO | started process 3803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4xpIZk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:56:07 INFO - PROCESS | 3803 | -- checkerboarding starts below -- 16:56:07 INFO - PROCESS | 3803 | RENDERTRACE 0.032196 rect red 0 0 10 10 // viewport 16:56:07 INFO - PROCESS | 3803 | RENDERTRACE 77.6929 rect brown 0 0 10 10 // page 16:56:07 INFO - PROCESS | 3803 | RENDERTRACE 77.7159 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 16:56:07 INFO - PROCESS | 3803 | Checkerboarded for 1 frames (82.6534 ms), 100 peak, 0 severity. 16:56:07 INFO - PROCESS | 3803 | 16:56:07 INFO - PROCESS | 3803 | (process:3860): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:56:18 INFO - PROCESS | 3803 | RSS: Main: 150585344 16:56:18 INFO - PROCESS | 3803 | 16:56:20 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59553/tests/kraken/driver.html?testName=audio-beat-detection) 16:56:21 INFO - PROCESS | 3803 | RSS: Main: 150241280 16:56:21 INFO - PROCESS | 3803 | 16:56:24 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59553/tests/kraken/driver.html?testName=audio-dft) 16:56:24 INFO - PROCESS | 3803 | RSS: Main: 153341952 16:56:24 INFO - PROCESS | 3803 | 16:56:27 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59553/tests/kraken/driver.html?testName=audio-fft) 16:56:28 INFO - PROCESS | 3803 | RSS: Main: 146776064 16:56:28 INFO - PROCESS | 3803 | 16:56:30 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59553/tests/kraken/driver.html?testName=audio-oscillator) 16:56:30 INFO - PROCESS | 3803 | RSS: Main: 146296832 16:56:30 INFO - PROCESS | 3803 | 16:56:33 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59553/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:56:33 INFO - PROCESS | 3803 | RSS: Main: 146919424 16:56:33 INFO - PROCESS | 3803 | 16:56:36 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59553/tests/kraken/driver.html?testName=imaging-darkroom) 16:56:37 INFO - PROCESS | 3803 | RSS: Main: 147480576 16:56:37 INFO - PROCESS | 3803 | 16:56:40 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59553/tests/kraken/driver.html?testName=imaging-desaturate) 16:56:41 INFO - PROCESS | 3803 | RSS: Main: 147636224 16:56:41 INFO - PROCESS | 3803 | 16:56:44 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59553/tests/kraken/driver.html?testName=json-parse-financial) 16:56:45 INFO - PROCESS | 3803 | RSS: Main: 147791872 16:56:45 INFO - PROCESS | 3803 | 16:56:46 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59553/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:56:47 INFO - PROCESS | 3803 | RSS: Main: 148774912 16:56:47 INFO - PROCESS | 3803 | 16:56:49 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:56:49 INFO - PROCESS | 3803 | RSS: Main: 147623936 16:56:49 INFO - PROCESS | 3803 | 16:56:52 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:56:53 INFO - PROCESS | 3803 | RSS: Main: 148553728 16:56:53 INFO - PROCESS | 3803 | 16:56:55 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:56:56 INFO - PROCESS | 3803 | RSS: Main: 148594688 16:56:56 INFO - PROCESS | 3803 | 16:56:58 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:56:59 INFO - PROCESS | 3803 | RSS: Main: 149561344 16:56:59 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | Cycle 1(1): loaded http://localhost:59553/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59553/tests/kraken/driver.html?testName=ai-astar) 16:57:01 INFO - PROCESS | 3803 | RSS: Main: 149831680 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | __start_tp_report 16:57:01 INFO - PROCESS | 3803 | _x_x_mozilla_page_load 16:57:01 INFO - PROCESS | 3803 | _x_x_mozilla_page_load_details 16:57:01 INFO - PROCESS | 3803 | |i|pagename|runs| 16:57:01 INFO - PROCESS | 3803 | |0;ai-astar;116;109;117;115;115;129;116;115;115;115 16:57:01 INFO - PROCESS | 3803 | |1;audio-beat-detection;155;137;157;166;135;154;169;138;174;143 16:57:01 INFO - PROCESS | 3803 | |2;audio-dft;162;164;164;164;165;168;168;166;163;163 16:57:01 INFO - PROCESS | 3803 | |3;audio-fft;74;76;74;78;73;124;74;75;74;73 16:57:01 INFO - PROCESS | 3803 | |4;audio-oscillator;98;99;130;97;98;98;135;98;99;137 16:57:01 INFO - PROCESS | 3803 | |5;imaging-gaussian-blur;110;110;110;110;110;110;110;109;111;110 16:57:01 INFO - PROCESS | 3803 | |6;imaging-darkroom;149;148;148;148;149;147;148;148;149;147 16:57:01 INFO - PROCESS | 3803 | |7;imaging-desaturate;101;96;96;96;101;97;96;97;96;96 16:57:01 INFO - PROCESS | 3803 | |8;json-parse-financial;71;69;69;70;71;77;70;69;77;70 16:57:01 INFO - PROCESS | 3803 | |9;json-stringify-tinderbox;68;68;69;69;70;69;69;69;68;68 16:57:01 INFO - PROCESS | 3803 | |10;stanford-crypto-aes;85;84;84;86;84;85;84;93;83;84 16:57:01 INFO - PROCESS | 3803 | |11;stanford-crypto-ccm;121;118;127;129;135;120;131;124;128;126 16:57:01 INFO - PROCESS | 3803 | |12;stanford-crypto-pbkdf2;146;139;139;145;139;142;151;141;151;138 16:57:01 INFO - PROCESS | 3803 | |13;stanford-crypto-sha256-iterative;74;73;72;74;73;72;72;72;78;71 16:57:01 INFO - PROCESS | 3803 | __end_tp_report 16:57:01 INFO - PROCESS | 3803 | __start_cc_report 16:57:01 INFO - PROCESS | 3803 | _x_x_mozilla_cycle_collect,775 16:57:01 INFO - PROCESS | 3803 | __end_cc_report 16:57:01 INFO - PROCESS | 3803 | __startTimestamp1453337821570__endTimestamp 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | ------- Summary: start ------- 16:57:01 INFO - PROCESS | 3803 | Number of tests: 14 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#0] ai-astar Cycles:10 Average:116.20 Median:115.50 stddev:4.98 (4.3%) stddev-sans-first:5.29 16:57:01 INFO - PROCESS | 3803 | Values: 116.0 109.0 117.0 115.0 115.0 129.0 116.0 115.0 115.0 115.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#1] audio-beat-detection Cycles:10 Average:152.80 Median:156.00 stddev:14.11 (9.0%) stddev-sans-first:14.94 16:57:01 INFO - PROCESS | 3803 | Values: 155.0 137.0 157.0 166.0 135.0 154.0 169.0 138.0 174.0 143.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#2] audio-dft Cycles:10 Average:164.70 Median:164.50 stddev:2.06 (1.3%) stddev-sans-first:1.94 16:57:01 INFO - PROCESS | 3803 | Values: 162.0 164.0 164.0 164.0 165.0 168.0 168.0 166.0 163.0 163.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#3] audio-fft Cycles:10 Average:79.50 Median:74.00 stddev:15.71 (21.2%) stddev-sans-first:16.53 16:57:01 INFO - PROCESS | 3803 | Values: 74.0 76.0 74.0 78.0 73.0 124.0 74.0 75.0 74.0 73.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:98.00 stddev:17.41 (17.8%) stddev-sans-first:18.02 16:57:01 INFO - PROCESS | 3803 | Values: 98.0 99.0 130.0 97.0 98.0 98.0 135.0 98.0 99.0 137.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:57:01 INFO - PROCESS | 3803 | Values: 110.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 111.0 110.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 16:57:01 INFO - PROCESS | 3803 | Values: 149.0 148.0 148.0 148.0 149.0 147.0 148.0 148.0 149.0 147.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:96.00 stddev:2.04 (2.1%) stddev-sans-first:1.64 16:57:01 INFO - PROCESS | 3803 | Values: 101.0 96.0 96.0 96.0 101.0 97.0 96.0 97.0 96.0 96.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#8] json-parse-financial Cycles:10 Average:71.30 Median:70.50 stddev:3.09 (4.4%) stddev-sans-first:3.28 16:57:01 INFO - PROCESS | 3803 | Values: 71.0 69.0 69.0 70.0 71.0 77.0 70.0 69.0 77.0 70.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 16:57:01 INFO - PROCESS | 3803 | Values: 68.0 68.0 69.0 69.0 70.0 69.0 69.0 69.0 68.0 68.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.50 stddev:2.86 (3.4%) stddev-sans-first:3.03 16:57:01 INFO - PROCESS | 3803 | Values: 85.0 84.0 84.0 86.0 84.0 85.0 84.0 93.0 83.0 84.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#11] stanford-crypto-ccm Cycles:10 Average:125.90 Median:127.50 stddev:5.26 (4.1%) stddev-sans-first:5.27 16:57:01 INFO - PROCESS | 3803 | Values: 121.0 118.0 127.0 129.0 135.0 120.0 131.0 124.0 128.0 126.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.10 Median:143.50 stddev:4.93 (3.4%) stddev-sans-first:5.12 16:57:01 INFO - PROCESS | 3803 | Values: 146.0 139.0 139.0 145.0 139.0 142.0 151.0 141.0 151.0 138.0 16:57:01 INFO - PROCESS | 3803 | 16:57:01 INFO - PROCESS | 3803 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:1.97 (2.7%) stddev-sans-first:2.06 16:57:01 INFO - PROCESS | 3803 | Values: 74.0 73.0 72.0 74.0 73.0 72.0 72.0 72.0 78.0 71.0 16:57:01 INFO - PROCESS | 3803 | -------- Summary: end -------- 16:57:01 INFO - PROCESS | 3803 | 16:57:02 INFO - TEST-INFO | 3803: exit 0 16:57:02 INFO - TEST-OK | kraken | took 62216ms 16:57:02 INFO - TEST-START | v8_7 16:57:02 INFO - operating with platform_type : linux_ 16:57:02 INFO - Initialising browser for v8_7 test... 16:57:02 INFO - TEST-INFO | started process 4089 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu__VMm/profile http://localhost:59553/getInfo.html) 16:57:06 INFO - PROCESS | 4089 | 16:57:06 INFO - PROCESS | 4089 | (process:4159): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:57:07 INFO - PROCESS | 4089 | __metrics Screen width/height:1600/1200 16:57:07 INFO - PROCESS | 4089 | colorDepth:24 16:57:07 INFO - PROCESS | 4089 | Browser inner width/height: 1024/697 16:57:07 INFO - PROCESS | 4089 | __metrics 16:57:07 INFO - PROCESS | 4089 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:57:08 INFO - TEST-INFO | 4089: exit 0 16:57:08 INFO - Browser initialized. 16:57:08 INFO - Running cycle 1/1 for v8_7 test... 16:57:08 INFO - TEST-INFO | started process 4208 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu__VMm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:09 INFO - PROCESS | 4208 | -- checkerboarding starts below -- 16:57:09 INFO - PROCESS | 4208 | RENDERTRACE 0.034652 rect red 0 0 10 10 // viewport 16:57:09 INFO - PROCESS | 4208 | RENDERTRACE 23.8438 rect brown 0 0 10 10 // page 16:57:09 INFO - PROCESS | 4208 | RENDERTRACE 23.865 rect lightgreen 0 0 10 10 // painted displayport (this layertree updated) 16:57:09 INFO - PROCESS | 4208 | Checkerboarded for 1 frames (33.0962 ms), 100 peak, 1 severity. 16:57:10 INFO - PROCESS | 4208 | 16:57:10 INFO - PROCESS | 4208 | (process:4264): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 16:57:20 INFO - PROCESS | 4208 | RSS: Main: 149815296 16:57:20 INFO - PROCESS | 4208 | 16:57:42 INFO - PROCESS | 4208 | Cycle 1(1): loaded http://localhost:59553/tests/v8_7/run.html (next: http://localhost:59553/tests/v8_7/run.html) 16:57:43 INFO - PROCESS | 4208 | RSS: Main: 145612800 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | __start_tp_report 16:57:43 INFO - PROCESS | 4208 | _x_x_mozilla_page_load 16:57:43 INFO - PROCESS | 4208 | _x_x_mozilla_page_load_details 16:57:43 INFO - PROCESS | 4208 | |i|pagename|runs| 16:57:43 INFO - PROCESS | 4208 | |0;Richards;172.1763085399449 16:57:43 INFO - PROCESS | 4208 | |1;DeltaBlue;186.74136321195144 16:57:43 INFO - PROCESS | 4208 | |2;Encrypt;279.2516056967328 16:57:43 INFO - PROCESS | 4208 | |3;Decrypt;5196.891191709845 16:57:43 INFO - PROCESS | 4208 | |4;RayTrace;1074.1138560687432 16:57:43 INFO - PROCESS | 4208 | |5;Earley;709.7232079488999 16:57:43 INFO - PROCESS | 4208 | |6;Boyer;12419.753086419752 16:57:43 INFO - PROCESS | 4208 | |7;RegExp;42893.617021276594 16:57:43 INFO - PROCESS | 4208 | |8;Splay;610.8735491753207 16:57:43 INFO - PROCESS | 4208 | |9;NavierStokes;4830.917874396136 16:57:43 INFO - PROCESS | 4208 | __end_tp_report 16:57:43 INFO - PROCESS | 4208 | __startTimestamp1453337863177__endTimestamp 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | ------- Summary: start ------- 16:57:43 INFO - PROCESS | 4208 | Number of tests: 10 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#0] Richards Cycles:1 Average:172.18 Median:172.18 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 172.2 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#1] DeltaBlue Cycles:1 Average:186.74 Median:186.74 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 186.7 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#2] Encrypt Cycles:1 Average:279.25 Median:279.25 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 279.3 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 5196.9 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#4] RayTrace Cycles:1 Average:1074.11 Median:1074.11 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 1074.1 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#5] Earley Cycles:1 Average:709.72 Median:709.72 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 709.7 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#6] Boyer Cycles:1 Average:12419.75 Median:12419.75 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 12419.8 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#7] RegExp Cycles:1 Average:42893.62 Median:42893.62 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 42893.6 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#8] Splay Cycles:1 Average:610.87 Median:610.87 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 610.9 16:57:43 INFO - PROCESS | 4208 | 16:57:43 INFO - PROCESS | 4208 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 16:57:43 INFO - PROCESS | 4208 | Values: 4830.9 16:57:43 INFO - PROCESS | 4208 | -------- Summary: end -------- 16:57:43 INFO - PROCESS | 4208 | 16:57:44 INFO - TEST-INFO | 4208: exit 0 16:57:44 INFO - TEST-OK | v8_7 | took 41546ms 16:57:44 INFO - SUITE-END | took 1220s 16:57:44 INFO - Completed test suite (00:20:20) 16:57:44 INFO - Working with test: dromaeo_css 16:57:44 INFO - Generating results file: dromaeo_css 16:57:44 INFO - Working with test: kraken 16:57:44 INFO - Generating results file: kraken 16:57:44 INFO - javascript benchmark 16:57:44 INFO - Working with test: v8_7 16:57:44 INFO - Generating results file: v8_7 16:57:44 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:57:44 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 16:57:44 INFO - process_Request line: dromaeo_css 5923.34 graph.html#tests=[[72,132,43]] 16:57:44 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:57:44 INFO - process_Request line: kraken 1544.70 graph.html#tests=[[232,132,43]] 16:57:44 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:57:44 INFO - process_Request line: v8_7 20203.29 graph.html#tests=[[230,132,43]] 16:57:44 INFO - RETURN: dromaeo_css: 5923.34 16:57:44 INFO - RETURN: kraken: 1544.70 16:57:44 INFO - RETURN: v8_7: 20203.29 16:57:44 INFO - javascript benchmark 16:57:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2574.4722331921957, 2588.4434846507997, 2618.1621880998077, 2638.564636834922, 2645.64283821684, 4459.487158976711, 4497.325401189815, 4509.797059132335, 4532.932006019908, 4568.314752787083, 4512.368268442421, 4534.659900507467, 4575.908481830362, 4625.4680711718165, 4632.606228470584, 4033.240997229917, 4229.111886503831, 4249.808758605855, 4300.935485967713, 4435.667324950641, 4140.648044916171, 4323.048929235563, 4359.28071868143, 4364.301711726124, 4376.146651402978, 4164.583541645811, 4177.603127702879, 4208.473940757405, 4213.262679031157, 4238.131183107455, 4186.91626167478, 4190.497140343178, 4209.600087991666, 4216.198922204771, 4247.256730072256, 4076.979615101935, 4121.979390103061, 4191.245575796355, 4193.580641935812, 4206.684498662582, 4389.582989615981, 4462.973516091285, 4471.3293006049025, 4489.21438748221, 4492.84275050375, 4792.257200133985, 4852.660313778037, 4894.290327902434, 4901.0, 4942.950570494285, 4748.264019077049, 4771.6421268404665, 4821.710697358135, 4862.80548778052, 4875.609951203931, 4542.772861356985, 4543.250363689954, 4551.226291530448, 4565.178267911741, 4589.219832628494, 5036.370453693288, 5048.596112311006, 5049.116404629175, 5051.191809310566, 5061.089003979319, 4702.764861756898, 4742.454617718938, 4789.640776941744, 4852.757362131879, 4895.534924182197, 4931.827386041471, 4942.085714142895, 4942.950570494249, 4964.478729733398, 4970.130227210224, 4899.559039686445, 4901.705897646153, 4902.534259245366, 4905.828296009622, 4920.975395123001, 4693.78877950493, 4741.2651039089, 4760.547747964007, 4762.476127625959, 4911.778969946361, 4050.311447054008, 4050.513938327361, 4073.551909289976, 4087.1212689271356, 4157.501099868035, 3523.8766643167364, 3533.3993221152464, 3539.94690079649, 3566.6433356664643, 3577.856885724542, 4167.895802604899, 4181.832726690959, 4193.790310484464, 4225.112726327444, 4226.323788193874, 4787.54518320759, 4827.541383568555, 4968.453470118284, 4987.925181122286, 5019.899602008012, 4222.641075508554, 4264.0832221072, 4292.227399068198, 4316.568343165721, 4326.697131200785, 4269.551697071763, 4414.293713005904, 4414.613003095975, 4415.845445409395, 4432.047109871329, 4625.213713668684, 4694.061187762393, 4705.647076469347, 4719.669623126349, 4751.714897106184, 4910.386201724785, 4953.356063711839, 4991.376077990251, 4994.300797888226, 5010.498950105106, 4579.771011449481, 4641.234196357564, 4690.249560070372, 4730.2431610942085, 4736.336912832137, 4719.669623126349, 4854.320395144753, 4874.7562621868055, 4883.145449546386, 4911.9754401227765, 4869.415670119608, 4877.756112194305, 4880.41435027799, 4882.145624515767, 4883.291922671264, 4612.700174488782, 4676.321933319718, 4686.789094490688, 4743.501932297059, 4757.785899634456, 4681.0, 4744.572988430919, 4754.881127971828, 4788.353572267699, 4810.6151507878585, 4552.613027892667, 4566.063956888764, 4592.977035114803, 4617.076584683144, 4652.046330502171, 4637.907241855077, 4707.905841883075, 4738.8341408050555, 4739.360186374934, 4762.285657151317, 4638.605718513829, 4641.814327426865, 4645.163870503342, 4668.766561671835, 4695.342652028787], "unit": "score", "name": "dojo.html", "value": 4484.044450234844}, {"lowerIsBetter": false, "replicates": [481.92289233722613, 503.192376236141, 504.0574126383663, 515.5875299760191, 519.470140456734, 31250.68749312509, 31551.211219719462, 31592.68407315929, 31622.0, 31685.524717129232, 35493.29013419736, 35701.64298357016, 36157.27685446296, 36159.63840361596, 36177.27645447089, 7540.886886696704, 7704.19105993871, 7754.961225193866, 7770.339521140681, 7778.494397864149, 7585.81035474112, 7653.31120199182, 7723.304902558769, 7737.961310193469, 7742.8451430971345, 7910.050793904739, 7984.960075199645, 7994.3204827589725, 8035.0, 8037.95981020097, 7845.607719614025, 7931.643076061591, 7947.960260198691, 7952.880706789403, 7970.043594768606, 7633.236676332378, 7761.8447631047065, 7818.6090695465, 7819.687212511492, 7824.452288339819, 35723.82138089319, 35823.0, 36032.63967360345, 36040.819795901116, 36321.09197270063, 2769.62610047643, 2844.3742376677096, 2866.5986761853355, 2887.22045047838, 2887.956680649792, 27429.725702742915, 27434.725652743615, 27729.722702772917, 27975.440491190267, 28098.297542561395, 3370.2053159318834, 3453.326601312745, 3489.70337521311, 3544.5746510418917, 3604.7837129772033, 160206.19896900558, 162377.0, 162685.0, 163060.18469907573, 163859.0, 43545.782271089076, 44420.55579444229, 51781.482185177665, 52070.47929520656, 52120.0, 54007.459925400246, 55607.0, 55620.72189639107, 55674.443255567734, 55923.72038139865, 12554.686132846751, 12787.0, 12998.935005324913, 13098.672533186556, 13108.934455327662, 9061.501617410973, 9073.31950103745, 9151.816963660822, 9199.448033118035, 9271.95364023189, 17478.30086796514, 17732.11339433023, 17750.91124544395, 17807.0, 17890.194941227677, 6852.828679282959, 7004.089468369181, 7132.393746531499, 7155.0, 7193.244709305448, 822.5311572403673, 865.5845194306642, 874.50289852205, 945.1965829045394, 973.9026097390205, 25659.615105773428, 25799.484010319688, 25952.0, 25971.0, 26032.219033429028, 990.7325022243954, 1049.634855798985, 1078.6710053433778, 1084.7125511739396, 1089.54239219528, 13782.448702051806, 14837.554873353816, 14933.0, 14935.775963360558, 15075.0, 252124.0, 254362.0, 256083.0, 256330.0, 257392.0, 40874.591254087674, 40888.591114089075, 41031.79484102561, 41169.58830411657, 41261.174776504304, 1184.1829137894824, 1195.3306148556835, 1213.2659740856832, 1214.0833670578834, 1322.7751282282036, 25446.854891529554, 26055.39446055455, 26226.344341391617, 26267.343316417242, 26269.0, 37996.0, 38091.61908380881, 38311.0, 38423.2315353697, 38469.807650961564, 197044.0, 198844.0, 199839.0, 242223.0, 242479.0, 26529.0, 26762.0, 27100.72899270982, 27320.59019114675, 27418.0, 36260.0, 36354.45468317925, 36788.44817327796, 36873.81563092167, 37497.25005499929, 27643.861780690968, 27984.72015279821, 28232.29419264535, 28425.147245582666, 28542.0, 27014.0, 27339.86330068337, 27671.169864904085, 27869.0, 27979.74091165943, 28217.0, 28438.289042774097, 28562.57156142618, 28621.141365758227, 28735.557477613802, 26321.341966450993, 26927.192184234504, 28152.577711333935, 28969.420611588073, 28970.13089607315, 20862.895685521475, 21358.572828543653, 22006.339809805733, 22217.333479995625, 22371.44071398228], "unit": "score", "name": "ext.html", "value": 15808.545231207929}, {"lowerIsBetter": false, "replicates": [329.55510061417084, 329.95380646709464, 332.06689203338607, 334.51328317298334, 336.12774849266145, 11766.529338826436, 12199.939000305008, 13898.166110033406, 14494.637634059141, 15064.472743453982, 13981.650458738537, 14540.272986350694, 14548.563543093698, 15193.164375959317, 15255.008424452431, 4765.499622539635, 4772.403449568804, 4931.753412329387, 4962.280469331945, 4962.776675049631, 4824.6863953842885, 4881.365422495071, 5013.473585273548, 5023.773930173151, 5025.623078269126, 4959.330490383788, 4995.275685025669, 5021.774020169083, 5481.808136715215, 5485.753141108639, 5489.670619762827, 5611.046122159243, 5649.1243857202, 5655.349634791969, 5655.886882262353, 5122.615803814691, 5123.666961647481, 5125.333706618153, 5130.51260130287, 5135.4094279157625, 11031.172662050298, 11061.778764424747, 11215.327080375204, 11225.326480411202, 11329.490172942238, 1725.2667616263038, 1741.1381366223673, 1756.9912150439166, 1809.6109336492586, 1836.7061270196705, 8179.100298967109, 8668.133186681345, 9114.496108142148, 9592.66425675098, 12061.216020958698, 1696.236693487923, 1732.4196394207956, 1761.1018380625844, 1765.48800847754, 1771.6102457459467, 46291.76854115741, 48567.51432485665, 48770.0, 48868.02263954701, 49037.019259614965, 9007.684731034513, 9286.164245217962, 9321.487318197564, 9348.532573371303, 9364.578593963288, 11977.401129943468, 11997.100217483723, 12028.819567706492, 12088.153829232046, 12170.148089633818, 6387.457066149336, 6541.378569035934, 6552.213734351908, 6571.080048793173, 6624.86750264992, 6194.101855231056, 6195.070739389128, 6213.1922850029205, 6222.253329600477, 6223.564350495421, 3868.6905047596124, 4015.297322968469, 4058.289799285113, 4082.4896887889013, 4113.218488487178, 1665.6585399992973, 1693.2211182856024, 1695.7965044194775, 1703.1824724132487, 1717.3302412059306, 381.5078548672237, 830.7466222802103, 844.4004756622744, 870.5951732444444, 884.8628462588181, 8619.598536537105, 9100.954495227614, 9343.672971445967, 9485.430874147572, 10058.245631577662, 404.56711318888506, 405.12088767374865, 410.34140204970595, 421.5994804935324, 421.83126749300527, 5440.727963601883, 5499.1476321170285, 5501.642393244426, 5578.191162281528, 5586.972065139648, 433594.0, 433737.0, 437638.0, 438572.0, 448081.0, 62441.0, 64004.67997659982, 65298.041058768315, 65366.0, 66372.0, 416.9228692691801, 425.29613489674637, 425.9190753756714, 426.3902619254434, 428.45372150508354, 8566.143385661633, 8580.914190858011, 8793.692220772242, 8814.647414103363, 8868.911310886808, 27399.726002739717, 27733.30666733348, 28092.297692557033, 28499.572506412835, 28504.57243141313, 39261.41107883441, 40680.59319406768, 40745.79627101846, 41196.588034119275, 42631.147377052905, 3813.542374915028, 6504.1219435375615, 6536.281009089092, 6634.800955971328, 6670.533062685566, 6836.872345740694, 7804.726834560764, 8966.89071446353, 9149.268058555166, 9430.481323527272, 8432.53621050848, 8496.70261540843, 8559.144085591392, 8560.14398560139, 8694.91305086941, 7867.347856950105, 8223.424360294723, 8565.871511927451, 8628.050914399537, 8869.334799889904, 7740.574268415066, 8606.483610983361, 8698.91301086981, 8730.519821409884, 8937.642494300155, 6479.643619600779, 6481.449076828522, 6613.43785778195, 6670.566413183127, 6683.298253683293, 7704.730334438492, 8562.828743425222, 8649.308055355683, 8836.469811811312, 8867.068957759342], "unit": "score", "name": "jquery.html", "value": 6644.300113286086}, {"lowerIsBetter": false, "replicates": [1346.0, 1365.5288925320765, 1454.3382760843817, 1635.2314412226249, 1650.422352176739, 6574.769883054094, 6584.473242140629, 6741.865162696749, 6782.2200446948555, 6802.081718967938, 6759.864802703955, 6767.424768894638, 6864.24493305736, 6891.483138764587, 6896.344847239517, 2009.9497512562157, 2050.8256798172174, 2056.969145462819, 2057.701633263176, 2057.969130463044, 2058.7632422271404, 2063.7626672932656, 2071.099071903727, 2081.396395045435, 2089.9791002090014, 1762.427211156373, 1811.628616133696, 1944.6985717213854, 2044.6728523436252, 2076.3355726167633, 1947.2405761752927, 1948.6687263165225, 1949.2592814730453, 1956.1197461142542, 1960.2551030608277, 1929.3150931419284, 1949.4736421166347, 1994.3618042226349, 2005.5788284460134, 2006.107282259395, 6817.386435220805, 6857.657117144123, 6880.759173428956, 6882.311768823128, 6896.724131034753, 3951.3480275754464, 4039.4748682671357, 4076.653484453825, 4085.9182816343805, 4096.651784598313, 2663.587143992684, 2709.322669332667, 2720.3743139077924, 2723.0, 2745.437185376993, 855.7732200966749, 865.3726048614767, 865.5282870835409, 880.1374652840188, 905.9320550958638, 8801.295896328278, 8883.333749968779, 8889.822203555892, 8945.597448114786, 8991.865122023175, 3469.5058673265626, 3497.108237399447, 3502.6322236165347, 3538.0, 3612.0, 7453.105627324648, 7457.5898325591525, 7461.328480436787, 7465.7013719450615, 7490.363319117936, 3953.2291203215095, 3955.208958208312, 3960.485136932238, 3984.2828290907337, 3984.9003774905277, 3648.7628304160144, 3660.048387419237, 3664.029032306441, 3669.3945498992366, 3684.060564556021, 3014.3971205758935, 3032.818030918152, 3040.467918114321, 3063.938721225563, 3064.7241748242764, 932.3240650528448, 933.0622724162174, 936.3352020065694, 946.1012038563392, 946.2524605561667, 776.4176867349488, 780.4458834227762, 917.9082091790767, 1100.4497751124438, 1128.210252823027, 6038.215032045806, 6060.575759696779, 6083.8174854754425, 6129.356417576179, 6136.8772624548155, 564.8418442836013, 565.4684596479378, 597.0685730260807, 605.4339192854695, 707.8301207710216, 4890.975545122323, 4948.628852836052, 4984.8753781155765, 4985.5762260208285, 4996.4254110777, 7441.441891858132, 7609.847803043908, 7932.405069619802, 7964.761057168178, 7972.242636949481, 7734.342580880688, 7827.530348179127, 7844.137144914055, 7880.960595196987, 7884.763457096296, 6108.6029408089225, 6196.47329976948, 6266.028765541349, 6288.402601752826, 6329.968350158312, 6001.969990150022, 6007.219061521974, 6009.429104235091, 6017.217761690952, 6018.729157188025, 6932.584044957319, 7079.929200708133, 7137.571745695275, 7143.178534468602, 7202.89195662076, 7079.39825114871, 7223.205447400886, 7254.927450725425, 7263.16473605521, 7328.633568321669, 5779.162021506942, 5876.118582212703, 5881.235439392852, 5885.440883116097, 5885.499732522783, 6147.415995480422, 6148.78479253224, 6166.81499555014, 6198.597091189058, 6423.389777971085, 5985.528393531828, 6062.575619706578, 6118.051701986199, 6205.348438413902, 6231.657258850807, 6053.4551890330085, 6085.482733967485, 6123.785667501616, 6169.2288463942, 6303.558750887577, 6272.18461599989, 6276.435120839146, 6340.239171299474, 6342.175517182921, 6361.777337793155, 5536.0, 5652.802151924663, 5669.603127781016, 5670.177824215548, 5689.857753556194, 5567.359753628384, 5590.440955904377, 5603.0474819281635, 5615.6630602164005, 5618.157276408571], "unit": "score", "name": "mootools.html", "value": 3803.538325283181}, {"lowerIsBetter": false, "replicates": [278.6099460754943, 287.43805859544597, 291.64710700052933, 300.936803271313, 300.9458297506448, 5541.196526503654, 5556.833295001146, 5635.182898479718, 5647.5764317676185, 5655.4910058094765, 5460.918086228699, 5467.9453205467935, 5468.890622187554, 5507.173923911415, 5524.116141417374, 4254.638355739766, 4262.318029115342, 4267.701260911737, 4273.188094262096, 4273.529911709709, 4098.938515922264, 4198.748075115487, 4216.7680777557225, 4237.300845360512, 4246.596573325529, 4199.097194103268, 4199.370094485821, 4200.663946884257, 4202.768847713374, 4209.179210054041, 4195.391668208093, 4197.832086716543, 4200.684948628865, 4216.831326746927, 4217.810198541073, 3647.252313275772, 3650.2881938022097, 3652.196516766307, 3655.1775850433705, 3660.267946410702, 5195.376554813409, 5214.243934629458, 5344.625876188668, 5358.678479291216, 5374.3013408256675, 4851.684640498356, 4855.708657480563, 4857.052874689437, 4857.222844344888, 4862.222044472867, 5742.54059675225, 5750.683712395817, 5774.307083150049, 5781.942180578182, 5790.218320526717, 3933.449317095609, 3938.7833669148185, 3943.211357728466, 3947.723659343847, 3948.881533553998, 5900.498457631106, 6150.569460137792, 6152.292486364081, 6171.0, 6174.0, 4091.0590564170407, 4097.426360309559, 4113.506379234451, 4114.485689288839, 4118.814653340607, 4143.81352839123, 4152.647025002848, 4155.168966206771, 4157.563455837154, 4160.708750387444, 4816.927746083811, 4823.397075365579, 4825.10735513931, 4829.034193161311, 4847.297141914402, 5734.62724922887, 5795.362510123883, 5851.239338885917, 5869.354371019184, 5874.853128671817, 1453.5276035288362, 1476.1290838405178, 1476.512750792236, 1479.3860547872512, 1480.2820631993475, 954.3033585482636, 955.1833182628752, 960.6253561111172, 963.0706368354571, 970.9951450242703, 5038.093143234253, 5055.595552355802, 5073.34046573943, 5083.186690129635, 5097.235414687752, 6056.273247210363, 6057.454829065406, 6059.36376680451, 6059.939400605937, 6066.423689749467, 5065.062963351792, 5083.161278389024, 5091.3126727892, 5097.566706829961, 5103.234514822806, 3204.951925721116, 3259.7066264036353, 3266.5426840242385, 3275.819829909378, 3284.868605255763, 5699.173619825102, 5700.0, 5703.315602127771, 5720.77116915327, 5725.227094342294, 6035.547333949971, 6115.663638499836, 6173.938260617337, 6215.96892015537, 6272.870883240969, 5706.543476521785, 5720.427957204412, 5727.0550359190265, 5740.827775166752, 5744.0522313817755, 6000.909986350121, 6083.391660833881, 6083.634981900923, 6132.08018797184, 6132.754689812357, 5751.4536119068625, 5934.821955341346, 5977.163197152315, 5997.340292567901, 6000.159977603052, 5762.5389968801555, 5764.855878603068, 5767.134929760569, 5767.711614419346, 5771.68255745938, 4005.2790497710694, 4032.798360082043, 4044.3326851069246, 4044.939325910055, 4053.8986525337104, 5942.10868369748, 6090.056041313604, 6093.908591371044, 6101.572889897665, 6103.328633850362, 5804.926088673609, 5806.071028635398, 5872.794452194153, 5873.618214816023, 5876.08920617305, 5826.796062137805, 5844.977129002323, 5864.179014937998, 5877.764889404547, 5885.764569417347, 5700.686462244616, 5739.167820666064, 5745.511631511368, 5752.798652047159, 5757.7121143941795, 5699.658020518782, 5870.412958704095, 5903.645781253139, 5906.0, 5922.2597175353085, 5722.399148089391, 5760.884782304247, 5764.855878603068, 5768.394318596655, 5769.0481070624555], "unit": "score", "name": "prototype.html", "value": 4338.3662381572785}, {"lowerIsBetter": false, "replicates": [601.6450294326349, 619.2909119058678, 650.031453134829, 684.0730809752785, 686.204003356107, 11011.779764404708, 11028.669139925794, 11094.223404361697, 11130.944345278282, 11132.944335278322, 11141.777164456706, 11702.765944681101, 11732.413379331021, 11733.178677492579, 11746.53013879446, 587.2395248153651, 598.4643743849252, 711.8896571031498, 742.6286856571714, 749.2507492507492, 737.4469148138896, 745.1765798792338, 745.5601195294774, 747.6523416611275, 751.887216917464, 791.4064451661254, 792.7542461836849, 792.9088154862206, 793.1949071692237, 794.3446656508404, 754.5812074298761, 784.3254800871246, 785.140271402813, 787.248177990021, 787.3032366355759, 578.3349148479241, 582.8280657206134, 583.4194975998865, 584.4476969264048, 585.7949717598849, 11390.829137562943, 11405.771884562346, 11442.0, 11465.08279337651, 11523.827142592867, 11685.415729213506, 11850.644480665595, 11936.641900742992, 12022.398880055962, 12053.276803391736, 9485.620575176985, 9646.662366817127, 9650.420974741468, 9695.22438204949, 9932.05645463687, 3995.6603688686496, 4078.1028173801715, 4082.4488694026522, 4179.791010449496, 4195.307774217281, 40569.797151014354, 40604.98487537805, 40933.79533102345, 41059.58940410588, 41066.0, 8676.522791246542, 9309.674161404319, 9368.110029547319, 9380.718578442658, 9628.229741620653, 15731.842681573038, 15761.605959851095, 15784.13187274711, 15798.7630185545, 15813.68372632541, 6398.648074355955, 6484.967575162188, 6495.577787443801, 6685.130932978682, 6779.966100169566, 3671.7246206534082, 3769.378052621342, 3774.830132644038, 3781.111438811877, 3825.1202223488754, 2543.923682289534, 2547.7197508274076, 2548.7833534149436, 2553.527597394488, 2561.6669832921602, 450.794888325811, 456.77161419290354, 459.1116190171961, 464.7118786352483, 465.1510992438745, 510.22445882258756, 567.4706665536422, 594.5362617158623, 602.3584882100637, 604.1243680082837, 6779.254282028973, 6921.377076063179, 6975.755848545276, 7003.54476958996, 7090.716371345192, 530.5993974549191, 554.4012466536132, 558.6731761919323, 565.8302509247292, 583.3146053387253, 3365.3101114271517, 3366.208940898887, 3406.982965085159, 3446.0867870014467, 3503.8072905989907, 48041.519584803704, 48534.51465485371, 48630.75684621554, 48810.5118948806, 48828.5117148824, 40817.59182408138, 40857.79571102125, 41642.167156657306, 41909.161816764106, 41979.0, 38298.42552361717, 38486.61513384886, 38649.22701545953, 38952.80523597364, 39014.219715605526, 6769.849125648553, 6882.621455819778, 6906.827329316607, 6953.235144134243, 7017.824554386181, 30419.391612168074, 30684.0, 30743.0, 30822.383552329276, 31767.682323176472, 36548.26903461969, 36876.0, 37665.0, 37670.8116459416, 37995.2400951985, 9750.80498390014, 9773.462459564793, 9979.750506237402, 10164.186865050926, 10347.43089130105, 10472.371657700562, 10753.193510486588, 10939.234253602172, 11128.998390144601, 11904.880951190376, 9862.0, 10619.15046796239, 10894.291871028357, 10959.287646302964, 11197.888021119685, 9876.901230987598, 9961.252906032221, 10093.142082923034, 10291.022352876465, 10709.732256693645, 9161.954190229006, 9341.80482477895, 10224.182065434896, 10225.539850706587, 10297.588096476056, 8632.78418039554, 8737.519436430803, 8802.427842190236, 9072.092790721139, 9347.626094956126, 8640.26557742599, 8667.306615470621, 9176.908230917605, 9199.126083022102, 9376.517095499114], "unit": "score", "name": "yui.html", "value": 5556.886777367112}], "name": "dromaeo_css", "value": 5923.337370175722}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 109.0, 117.0, 115.0, 115.0, 129.0, 116.0, 115.0, 115.0, 115.0], "unit": "score", "name": "ai-astar", "value": 116.2}, {"lowerIsBetter": true, "replicates": [155.0, 137.0, 157.0, 166.0, 135.0, 154.0, 169.0, 138.0, 174.0, 143.0], "unit": "score", "name": "audio-beat-detection", "value": 152.8}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 164.0, 164.0, 165.0, 168.0, 168.0, 166.0, 163.0, 163.0], "unit": "score", "name": "audio-dft", "value": 164.7}, {"lowerIsBetter": true, "replicates": [74.0, 76.0, 74.0, 78.0, 73.0, 124.0, 74.0, 75.0, 74.0, 73.0], "unit": "score", "name": "audio-fft", "value": 79.5}, {"lowerIsBetter": true, "replicates": [98.0, 99.0, 130.0, 97.0, 98.0, 98.0, 135.0, 98.0, 99.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 108.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [149.0, 148.0, 148.0, 148.0, 149.0, 147.0, 148.0, 148.0, 149.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 148.1}, {"lowerIsBetter": true, "replicates": [101.0, 96.0, 96.0, 96.0, 101.0, 97.0, 96.0, 97.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 97.2}, {"lowerIsBetter": true, "replicates": [71.0, 69.0, 69.0, 70.0, 71.0, 77.0, 70.0, 69.0, 77.0, 70.0], "unit": "score", "name": "json-parse-financial", "value": 71.3}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 69.0, 70.0, 69.0, 69.0, 69.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.7}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 84.0, 86.0, 84.0, 85.0, 84.0, 93.0, 83.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.2}, {"lowerIsBetter": true, "replicates": [121.0, 118.0, 127.0, 129.0, 135.0, 120.0, 131.0, 124.0, 128.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.9}, {"lowerIsBetter": true, "replicates": [146.0, 139.0, 139.0, 145.0, 139.0, 142.0, 151.0, 141.0, 151.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.1}, {"lowerIsBetter": true, "replicates": [74.0, 73.0, 72.0, 74.0, 73.0, 72.0, 72.0, 72.0, 78.0, 71.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 73.1}], "name": "kraken", "value": 1544.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [172.1763085399449], "unit": "score", "name": "Richards", "value": 205.03401599999995}, {"lowerIsBetter": false, "replicates": [186.74136321195144], "unit": "score", "name": "DeltaBlue", "value": 354.06189}, {"lowerIsBetter": false, "replicates": [279.2516056967328], "unit": "score", "name": "Encrypt", "value": 953.1941609999996}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [1074.1138560687432], "unit": "score", "name": "RayTrace", "value": 688.9297590000002}, {"lowerIsBetter": false, "replicates": [709.7232079488999], "unit": "score", "name": "Earley", "value": 939.0463669999997}, {"lowerIsBetter": false, "replicates": [12419.753086419752], "unit": "score", "name": "Boyer", "value": 53.66153379721673}, {"lowerIsBetter": false, "replicates": [42893.617021276594], "unit": "score", "name": "RegExp", "value": 21.238241567460335}, {"lowerIsBetter": false, "replicates": [610.8735491753207], "unit": "score", "name": "Splay", "value": 133.40076699999995}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20203.29133657242}]} 16:57:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "20203.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1544.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "5923.34"}}} 16:57:44 INFO - Return code: 0 16:57:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:57:44 INFO - # TBPL SUCCESS # 16:57:44 INFO - Running post-action listener: _resource_record_post_action 16:57:44 INFO - Running post-run listener: _resource_record_post_run 16:57:45 INFO - Total resource usage - Wall time: 1238s; CPU: 13.0%; Read bytes: 10276864; Write bytes: 449376256; Read time: 3140; Write time: 597404 16:57:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 13426688; Read time: 0; Write time: 2208 16:57:45 INFO - run-tests - Wall time: 1223s; CPU: 13.0%; Read bytes: 10276864; Write bytes: 433700864; Read time: 3140; Write time: 595028 16:57:45 INFO - Running post-run listener: _upload_blobber_files 16:57:45 INFO - Blob upload gear active. 16:57:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:57:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:57:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:46 INFO - (blobuploader) - INFO - Done attempting. 16:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:57:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:47 INFO - (blobuploader) - INFO - Done attempting. 16:57:47 INFO - (blobuploader) - INFO - Iteration through files over. 16:57:47 INFO - Return code: 0 16:57:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:57:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:57:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70ce2d2e2658a74ca45b9bfecf499da7eba0a67759e5c995405d8db8e7a7d69ab5b299c8c2657f06a7ea7e2bdec11398d1737b3c7d237cd8e7fb243943ede118", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/60d34bc462aa83a5709087283dde46bad1d3c58340b3f77e3fea0efd92b57d25fe50f36b53e667c95ebe7d610ea362cc40a3b8244485a3dd8788d770feef7160"} 16:57:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:57:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:57:47 INFO - Contents: 16:57:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70ce2d2e2658a74ca45b9bfecf499da7eba0a67759e5c995405d8db8e7a7d69ab5b299c8c2657f06a7ea7e2bdec11398d1737b3c7d237cd8e7fb243943ede118", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/60d34bc462aa83a5709087283dde46bad1d3c58340b3f77e3fea0efd92b57d25fe50f36b53e667c95ebe7d610ea362cc40a3b8244485a3dd8788d770feef7160"} 16:57:47 INFO - Running post-run listener: copy_logs_to_upload_dir 16:57:47 INFO - Copying logs to upload dir... 16:57:47 INFO - mkdir: /builds/slave/test/build/upload/logs 16:57:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.630080 ========= master_lag: 199.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 49 secs) (at 2016-01-20 17:01:07.028025) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-01-20 17:01:07.031128) ========= 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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70ce2d2e2658a74ca45b9bfecf499da7eba0a67759e5c995405d8db8e7a7d69ab5b299c8c2657f06a7ea7e2bdec11398d1737b3c7d237cd8e7fb243943ede118", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/60d34bc462aa83a5709087283dde46bad1d3c58340b3f77e3fea0efd92b57d25fe50f36b53e667c95ebe7d610ea362cc40a3b8244485a3dd8788d770feef7160"} build_url:https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/C71-tVqVTtupbr5NijlpEg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/70ce2d2e2658a74ca45b9bfecf499da7eba0a67759e5c995405d8db8e7a7d69ab5b299c8c2657f06a7ea7e2bdec11398d1737b3c7d237cd8e7fb243943ede118", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/60d34bc462aa83a5709087283dde46bad1d3c58340b3f77e3fea0efd92b57d25fe50f36b53e667c95ebe7d610ea362cc40a3b8244485a3dd8788d770feef7160"}' ========= master_lag: 3.96 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-01-20 17:01:10.998111) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 33 secs) (at 2016-01-20 17:01:10.998421) ========= 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=f919189b672bc2eeae365d21000001d6-1453333925.988226-303038502 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004709 ========= master_lag: 33.98 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 33 secs) (at 2016-01-20 17:01:44.980835) ========= ========= Started reboot skipped (results: 3, elapsed: 13 secs) (at 2016-01-20 17:01:44.981157) ========= ========= Finished reboot skipped (results: 3, elapsed: 13 secs) (at 2016-01-20 17:01:58.768142) ========= ========= Total master_lag: 237.37 =========