builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-053 starttime: 1449965853.38 results: success (0) buildid: 20151212153531 builduid: e5d27d8954c44246bc47276e4dfc5e49 revision: 2fb798379b1638d39228d0e3036812575872681d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.382994) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.383400) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.383691) ========= 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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006360 basedir: '/builds/slave/test' ========= master_lag: 0.07 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.455759) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.456170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.603029) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.603555) ========= 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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004593 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.661743) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.662195) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.662704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.663122) ========= 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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-12 16:17:35-- 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% 225M=0s 2015-12-12 16:17:35 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.192917 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.870032) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.870406) ========= 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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016133 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.920801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:33.921114) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2fb798379b1638d39228d0e3036812575872681d --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 2fb798379b1638d39228d0e3036812575872681d --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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-12 16:17:35,817 truncating revision to first 12 chars 2015-12-12 16:17:35,817 Setting DEBUG logging. 2015-12-12 16:17:35,817 attempt 1/10 2015-12-12 16:17:35,817 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2fb798379b16?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-12 16:17:36,068 unpacking tar archive at: fx-team-2fb798379b16/testing/mozharness/ program finished with exit code 0 elapsedTime=0.517552 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:34.451254) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:34.451617) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:34.458730) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:34.459047) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-12 16:17:34.459505) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-12 16:17:34.459851) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --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', '--system-bits', '64', '--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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:17:36 INFO - MultiFileLogger online at 20151212 16:17:36 in /builds/slave/test 16:17:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 16:17:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:36 INFO - {'append_to_log': False, 16:17:36 INFO - 'base_work_dir': '/builds/slave/test', 16:17:36 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 16:17:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:36 INFO - 'branch': 'Fx-Team-Non-PGO', 16:17:36 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:36 INFO - 'config_files': ('talos/linux_config.py',), 16:17:36 INFO - 'default_actions': ('clobber', 16:17:36 INFO - 'read-buildbot-config', 16:17:36 INFO - 'download-and-extract', 16:17:36 INFO - 'populate-webroot', 16:17:36 INFO - 'create-virtualenv', 16:17:36 INFO - 'install', 16:17:36 INFO - 'run-tests'), 16:17:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:36 INFO - 'download_minidump_stackwalk': True, 16:17:36 INFO - 'download_symbols': 'ondemand', 16:17:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:36 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:36 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:36 INFO - 'installer_path': 'installer.exe', 16:17:36 INFO - 'log_level': 'info', 16:17:36 INFO - 'log_name': 'talos', 16:17:36 INFO - 'log_to_console': True, 16:17:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:17:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:17:36 INFO - 'opt_config_files': (), 16:17:36 INFO - 'pip_index': False, 16:17:36 INFO - 'sps_profile': False, 16:17:36 INFO - 'sps_profile_interval': 0, 16:17:36 INFO - 'suite': 'dromaeojs-e10s', 16:17:36 INFO - 'system_bits': '64', 16:17:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:36 INFO - 'title': 'talos-linux64-ix-053', 16:17:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:36 INFO - 'use_talos_json': True, 16:17:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:36 INFO - 'work_dir': 'build'} 16:17:36 INFO - ##### 16:17:36 INFO - ##### Running clobber step. 16:17:36 INFO - ##### 16:17:36 INFO - Running pre-action listener: _resource_record_pre_action 16:17:36 INFO - Running main action method: clobber 16:17:36 INFO - rmtree: /builds/slave/test/build 16:17:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:36 INFO - Running post-action listener: _resource_record_post_action 16:17:36 INFO - ##### 16:17:36 INFO - ##### Running read-buildbot-config step. 16:17:36 INFO - ##### 16:17:36 INFO - Running pre-action listener: _resource_record_pre_action 16:17:36 INFO - Running main action method: read_buildbot_config 16:17:36 INFO - Using buildbot properties: 16:17:36 INFO - { 16:17:36 INFO - "properties": { 16:17:36 INFO - "buildnumber": 567, 16:17:36 INFO - "product": "firefox", 16:17:36 INFO - "script_repo_revision": "production", 16:17:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 16:17:36 INFO - "repository": "", 16:17:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 16:17:36 INFO - "buildid": "20151212153531", 16:17:36 INFO - "slavename": "talos-linux64-ix-053", 16:17:36 INFO - "pgo_build": "False", 16:17:36 INFO - "basedir": "/builds/slave/test", 16:17:36 INFO - "project": "", 16:17:36 INFO - "platform": "ubuntu64_hw", 16:17:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:17:36 INFO - "slavebuilddir": "test", 16:17:36 INFO - "scheduler": "tests-fx-team-linux64-talos", 16:17:36 INFO - "branch": "fx-team", 16:17:36 INFO - "repo_path": "integration/fx-team", 16:17:36 INFO - "stage_platform": "linux64", 16:17:36 INFO - "builduid": "e5d27d8954c44246bc47276e4dfc5e49", 16:17:36 INFO - "revision": "2fb798379b1638d39228d0e3036812575872681d" 16:17:36 INFO - }, 16:17:36 INFO - "sourcestamp": { 16:17:36 INFO - "repository": "", 16:17:36 INFO - "hasPatch": false, 16:17:36 INFO - "project": "", 16:17:36 INFO - "branch": "fx-team-linux64-talos", 16:17:36 INFO - "changes": [ 16:17:36 INFO - { 16:17:36 INFO - "category": null, 16:17:36 INFO - "files": [ 16:17:36 INFO - { 16:17:36 INFO - "url": null, 16:17:36 INFO - "name": "https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 16:17:36 INFO - } 16:17:36 INFO - ], 16:17:36 INFO - "repository": "", 16:17:36 INFO - "rev": "2fb798379b1638d39228d0e3036812575872681d", 16:17:36 INFO - "who": "mbanner@mozilla.com", 16:17:36 INFO - "when": 1449965808, 16:17:36 INFO - "number": 7355257, 16:17:36 INFO - "comments": "Bug 1231553 - Inset shadow makes most of the notification bar dark. r=mikedeboer", 16:17:36 INFO - "project": "", 16:17:36 INFO - "at": "Sat 12 Dec 2015 16:16:48", 16:17:36 INFO - "branch": "fx-team-linux64-talos", 16:17:36 INFO - "revlink": "", 16:17:36 INFO - "properties": [ 16:17:36 INFO - [ 16:17:36 INFO - "buildid", 16:17:36 INFO - "20151212153531", 16:17:36 INFO - "Change" 16:17:36 INFO - ], 16:17:36 INFO - [ 16:17:36 INFO - "builduid", 16:17:36 INFO - "e5d27d8954c44246bc47276e4dfc5e49", 16:17:36 INFO - "Change" 16:17:36 INFO - ], 16:17:36 INFO - [ 16:17:36 INFO - "pgo_build", 16:17:36 INFO - "False", 16:17:36 INFO - "Change" 16:17:36 INFO - ] 16:17:36 INFO - ], 16:17:36 INFO - "revision": "2fb798379b1638d39228d0e3036812575872681d" 16:17:36 INFO - } 16:17:36 INFO - ], 16:17:36 INFO - "revision": "2fb798379b1638d39228d0e3036812575872681d" 16:17:36 INFO - } 16:17:36 INFO - } 16:17:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 16:17:36 INFO - Running post-action listener: _resource_record_post_action 16:17:36 INFO - ##### 16:17:36 INFO - ##### Running download-and-extract step. 16:17:36 INFO - ##### 16:17:36 INFO - Running pre-action listener: _resource_record_pre_action 16:17:36 INFO - Running main action method: download_and_extract 16:17:36 INFO - mkdir: /builds/slave/test/build/tests 16:17: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:17:36 INFO - https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:17:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/test_packages.json 16:17:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/test_packages.json 16:17:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 16:17:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 16:17:38 INFO - Downloaded 1302 bytes. 16:17:38 INFO - Reading from file /builds/slave/test/build/test_packages.json 16:17:38 INFO - Using the following test package requirements: 16:17:38 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:17:38 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:17:38 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'jsshell-linux-x86_64.zip'], 16:17:38 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:17:38 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:17:38 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:17:38 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:17:38 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:17:38 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:17:38 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:17:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:17:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:38 INFO - https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:17:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:17:39 INFO - Downloaded 21869834 bytes. 16:17:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 16:17:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:17:40 INFO - Return code: 0 16:17:40 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:17:40 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:17:40 INFO - https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:17:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:17:41 INFO - Downloaded 21869834 bytes. 16:17:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 16:17:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:17:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:17:42 INFO - Return code: 0 16:17: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:17:42 INFO - https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:17:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:17:44 INFO - Downloaded 11132038 bytes. 16:17:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 16:17:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 16:17:44 INFO - Return code: 0 16:17:44 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:17:44 INFO - https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:17:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:17:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:17:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:17:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:17:48 INFO - Downloaded 57735340 bytes. 16:17:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:17:48 INFO - mkdir: /builds/slave/test/properties 16:17:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:48 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:48 INFO - Contents: 16:17:48 INFO - build_url:https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:17:48 INFO - Running post-action listener: _resource_record_post_action 16:17:48 INFO - Running post-action listener: set_extra_try_arguments 16:17:48 INFO - ##### 16:17:48 INFO - ##### Running populate-webroot step. 16:17:48 INFO - ##### 16:17:48 INFO - Running pre-action listener: _resource_record_pre_action 16:17:48 INFO - Running main action method: populate_webroot 16:17:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:48 INFO - '--tppagecycles', 16:17:48 INFO - '7'], 16:17:48 INFO - 'tests': ['tsvgm']}}, 16:17:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:17:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:17:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:17:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'tests': ['damp', 'tps']}, 16:17:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['damp', 'tps']}, 16:17:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:17:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:48 INFO - 'other': {'tests': ['a11yr', 16:17:48 INFO - 'ts_paint', 16:17:48 INFO - 'tpaint', 16:17:48 INFO - 'sessionrestore', 16:17:48 INFO - 'sessionrestore_no_auto_restore']}, 16:17:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['a11yr', 16:17:48 INFO - 'ts_paint', 16:17:48 INFO - 'tpaint', 16:17:48 INFO - 'sessionrestore', 16:17:48 INFO - 'sessionrestore_no_auto_restore']}, 16:17:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['a11yr', 16:17:48 INFO - 'ts_paint', 16:17:48 INFO - 'tpaint', 16:17:48 INFO - 'sessionrestore', 16:17:48 INFO - 'sessionrestore_no_auto_restore']}, 16:17:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['a11yr', 16:17:48 INFO - 'ts_paint', 16:17:48 INFO - 'tpaint', 16:17:48 INFO - 'sessionrestore', 16:17:48 INFO - 'sessionrestore_no_auto_restore']}, 16:17:48 INFO - 'other_l64': {'tests': ['a11yr', 16:17:48 INFO - 'ts_paint', 16:17:48 INFO - 'tpaint', 16:17:48 INFO - 'sessionrestore', 16:17:48 INFO - 'sessionrestore_no_auto_restore']}, 16:17:48 INFO - 'other_nol64': {'tests': ['a11yr', 16:17:48 INFO - 'ts_paint', 16:17:48 INFO - 'tpaint', 16:17:48 INFO - 'sessionrestore', 16:17:48 INFO - 'sessionrestore_no_auto_restore']}, 16:17:48 INFO - 'svgr': {'tests': ['tsvgx', 16:17:48 INFO - 'tsvgr_opacity', 16:17:48 INFO - 'tart', 16:17:48 INFO - 'tscrollx', 16:17:48 INFO - 'cart']}, 16:17:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['tsvgx', 16:17:48 INFO - 'tsvgr_opacity', 16:17:48 INFO - 'tart', 16:17:48 INFO - 'tscrollx', 16:17:48 INFO - 'cart']}, 16:17:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'tests': ['tp5o']}, 16:17:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'talos_options': ['--e10s'], 16:17:48 INFO - 'tests': ['tp5o']}, 16:17:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'talos_options': ['--xperf_path', 16:17:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:48 INFO - 'tests': ['tp5n']}, 16:17:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:48 INFO - 'talos_options': ['--e10s', 16:17:48 INFO - '--xperf_path', 16:17:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:48 INFO - 'tests': ['tp5n']}}, 16:17:48 INFO - 'talos.zip': {'path': '', 16:17:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:48 INFO - Running post-action listener: _resource_record_post_action 16:17:48 INFO - ##### 16:17:48 INFO - ##### Running create-virtualenv step. 16:17:48 INFO - ##### 16:17:48 INFO - Running pre-action listener: _resource_record_pre_action 16:17:48 INFO - Running main action method: create_virtualenv 16:17:48 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:48 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:17:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:48 INFO - Using real prefix '/usr' 16:17:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:48 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:50 INFO - Installing pip.................done. 16:17:50 INFO - Return code: 0 16:17:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17:50 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:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:50 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:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:17:50 INFO - Using env: {'DISPLAY': ':0', 16:17:50 INFO - 'HOME': '/home/cltbld', 16:17:50 INFO - 'LANG': 'en_US.UTF-8', 16:17:50 INFO - 'LANGUAGE': 'en_US:en', 16:17:50 INFO - 'LOGNAME': 'cltbld', 16:17:50 INFO - 'MAIL': '/var/mail/cltbld', 16:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:50 INFO - 'MOZ_NO_REMOTE': '1', 16:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:50 INFO - 'NO_EM_RESTART': '1', 16:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:50 INFO - 'PWD': '/builds/slave/test', 16:17:50 INFO - 'SHELL': '/bin/bash', 16:17:50 INFO - 'SHLVL': '1', 16:17:50 INFO - 'TERM': 'linux', 16:17:50 INFO - 'TMOUT': '86400', 16:17:50 INFO - 'USER': 'cltbld', 16:17:50 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:50 INFO - '_': '/tools/buildbot/bin/python'} 16:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:50 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:17:50 INFO - Cleaning up... 16:17:50 INFO - Return code: 0 16:17:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:17:50 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:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:50 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:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:17:50 INFO - Using env: {'DISPLAY': ':0', 16:17:50 INFO - 'HOME': '/home/cltbld', 16:17:50 INFO - 'LANG': 'en_US.UTF-8', 16:17:50 INFO - 'LANGUAGE': 'en_US:en', 16:17:50 INFO - 'LOGNAME': 'cltbld', 16:17:50 INFO - 'MAIL': '/var/mail/cltbld', 16:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:50 INFO - 'MOZ_NO_REMOTE': '1', 16:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:50 INFO - 'NO_EM_RESTART': '1', 16:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:50 INFO - 'PWD': '/builds/slave/test', 16:17:50 INFO - 'SHELL': '/bin/bash', 16:17:50 INFO - 'SHLVL': '1', 16:17:50 INFO - 'TERM': 'linux', 16:17:50 INFO - 'TMOUT': '86400', 16:17:50 INFO - 'USER': 'cltbld', 16:17:50 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:50 INFO - '_': '/tools/buildbot/bin/python'} 16:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:51 INFO - Downloading/unpacking psutil>=0.7.1 16:17: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:17: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:17: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:17: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:17: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:17: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:17:52 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 16:17:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:52 INFO - Installing collected packages: psutil 16:17:52 INFO - Running setup.py install for psutil 16:17:52 INFO - building 'psutil._psutil_linux' extension 16:17:52 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:17:53 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:17:53 INFO - building 'psutil._psutil_posix' extension 16:17:53 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:17:53 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:17:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:53 INFO - Successfully installed psutil 16:17:53 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:17: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:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:17:53 INFO - Using env: {'DISPLAY': ':0', 16:17:53 INFO - 'HOME': '/home/cltbld', 16:17:53 INFO - 'LANG': 'en_US.UTF-8', 16:17:53 INFO - 'LANGUAGE': 'en_US:en', 16:17:53 INFO - 'LOGNAME': 'cltbld', 16:17:53 INFO - 'MAIL': '/var/mail/cltbld', 16:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:53 INFO - 'MOZ_NO_REMOTE': '1', 16:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:53 INFO - 'NO_EM_RESTART': '1', 16:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:53 INFO - 'PWD': '/builds/slave/test', 16:17:53 INFO - 'SHELL': '/bin/bash', 16:17:53 INFO - 'SHLVL': '1', 16:17:53 INFO - 'TERM': 'linux', 16:17:53 INFO - 'TMOUT': '86400', 16:17:53 INFO - 'USER': 'cltbld', 16:17:53 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:53 INFO - '_': '/tools/buildbot/bin/python'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:17:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:55 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:17:55 INFO - Installing collected packages: mozsystemmonitor 16:17:55 INFO - Running setup.py install for mozsystemmonitor 16:17:55 INFO - Successfully installed mozsystemmonitor 16:17:55 INFO - Cleaning up... 16:17:55 INFO - Return code: 0 16:17:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:17:55 INFO - Using env: {'DISPLAY': ':0', 16:17:55 INFO - 'HOME': '/home/cltbld', 16:17:55 INFO - 'LANG': 'en_US.UTF-8', 16:17:55 INFO - 'LANGUAGE': 'en_US:en', 16:17:55 INFO - 'LOGNAME': 'cltbld', 16:17:55 INFO - 'MAIL': '/var/mail/cltbld', 16:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:55 INFO - 'MOZ_NO_REMOTE': '1', 16:17:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:55 INFO - 'NO_EM_RESTART': '1', 16:17:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:55 INFO - 'PWD': '/builds/slave/test', 16:17:55 INFO - 'SHELL': '/bin/bash', 16:17:55 INFO - 'SHLVL': '1', 16:17:55 INFO - 'TERM': 'linux', 16:17:55 INFO - 'TMOUT': '86400', 16:17:55 INFO - 'USER': 'cltbld', 16:17:55 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:55 INFO - '_': '/tools/buildbot/bin/python'} 16:17:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:55 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:57 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17: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:17: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:17: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:17: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:17: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:17: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:17:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:17:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17: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:17: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:17: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:17: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:17: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:17: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:17:58 INFO - Downloading docopt-0.6.1.tar.gz 16:17:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:58 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:58 INFO - Running setup.py install for blobuploader 16:17:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:58 INFO - Running setup.py install for requests 16:17:58 INFO - Running setup.py install for docopt 16:17:58 INFO - Successfully installed blobuploader requests docopt 16:17:58 INFO - Cleaning up... 16:17:58 INFO - Return code: 0 16:17:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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:17:58 INFO - Using env: {'DISPLAY': ':0', 16:17:58 INFO - 'HOME': '/home/cltbld', 16:17:58 INFO - 'LANG': 'en_US.UTF-8', 16:17:58 INFO - 'LANGUAGE': 'en_US:en', 16:17:58 INFO - 'LOGNAME': 'cltbld', 16:17:58 INFO - 'MAIL': '/var/mail/cltbld', 16:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:58 INFO - 'MOZ_NO_REMOTE': '1', 16:17:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:58 INFO - 'NO_EM_RESTART': '1', 16:17:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:58 INFO - 'PWD': '/builds/slave/test', 16:17:58 INFO - 'SHELL': '/bin/bash', 16:17:58 INFO - 'SHLVL': '1', 16:17:58 INFO - 'TERM': 'linux', 16:17:58 INFO - 'TMOUT': '86400', 16:17:58 INFO - 'USER': 'cltbld', 16:17:58 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:58 INFO - '_': '/tools/buildbot/bin/python'} 16:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:58 INFO - Running setup.py (path:/tmp/pip-WVrSXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:59 INFO - Running setup.py (path:/tmp/pip-8oJj8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:59 INFO - Running setup.py (path:/tmp/pip-4rlvqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:59 INFO - Running setup.py (path:/tmp/pip-KZxZIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:59 INFO - Running setup.py (path:/tmp/pip-nKL2jZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:59 INFO - Running setup.py (path:/tmp/pip-CnwxxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:59 INFO - Running setup.py (path:/tmp/pip-1mz3yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:59 INFO - Running setup.py (path:/tmp/pip-B4CQcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:59 INFO - Running setup.py (path:/tmp/pip-1aX_Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:59 INFO - Running setup.py (path:/tmp/pip-k8TO3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:59 INFO - Running setup.py (path:/tmp/pip-FBSOjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:59 INFO - Running setup.py (path:/tmp/pip-zkB6aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:59 INFO - Running setup.py (path:/tmp/pip-TZZIC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:59 INFO - Running setup.py (path:/tmp/pip-pbVrXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:59 INFO - Running setup.py (path:/tmp/pip-1Yz7ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:00 INFO - Running setup.py (path:/tmp/pip-uQ198I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:00 INFO - Running setup.py (path:/tmp/pip-DtJI82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:00 INFO - Running setup.py install for manifestparser 16:18:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozcrash 16:18:00 INFO - Running setup.py install for mozdebug 16:18:00 INFO - Running setup.py install for mozdevice 16:18:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozfile 16:18:00 INFO - Running setup.py install for mozhttpd 16:18:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozinfo 16:18:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozInstall 16:18:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozleak 16:18:01 INFO - Running setup.py install for mozlog 16:18:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for moznetwork 16:18:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for mozprocess 16:18:01 INFO - Running setup.py install for mozprofile 16:18:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for mozrunner 16:18:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for mozscreenshot 16:18:01 INFO - Running setup.py install for moztest 16:18:01 INFO - Running setup.py install for mozversion 16:18:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:01 INFO - Cleaning up... 16:18:01 INFO - Return code: 0 16:18:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:18:01 INFO - Using env: {'DISPLAY': ':0', 16:18:01 INFO - 'HOME': '/home/cltbld', 16:18:01 INFO - 'LANG': 'en_US.UTF-8', 16:18:01 INFO - 'LANGUAGE': 'en_US:en', 16:18:01 INFO - 'LOGNAME': 'cltbld', 16:18:01 INFO - 'MAIL': '/var/mail/cltbld', 16:18:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:01 INFO - 'MOZ_NO_REMOTE': '1', 16:18:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:01 INFO - 'NO_EM_RESTART': '1', 16:18:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:01 INFO - 'PWD': '/builds/slave/test', 16:18:01 INFO - 'SHELL': '/bin/bash', 16:18:01 INFO - 'SHLVL': '1', 16:18:01 INFO - 'TERM': 'linux', 16:18:01 INFO - 'TMOUT': '86400', 16:18:01 INFO - 'USER': 'cltbld', 16:18:01 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:18:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:01 INFO - '_': '/tools/buildbot/bin/python'} 16:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:02 INFO - Running setup.py (path:/tmp/pip-Jklh5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:02 INFO - Running setup.py (path:/tmp/pip-tOQvKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:02 INFO - Running setup.py (path:/tmp/pip-3jWTbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:02 INFO - Running setup.py (path:/tmp/pip-Z_giQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:02 INFO - Running setup.py (path:/tmp/pip-GF3EAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:02 INFO - Running setup.py (path:/tmp/pip-B7pglQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:02 INFO - Running setup.py (path:/tmp/pip-3O2Lfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:02 INFO - Running setup.py (path:/tmp/pip-1YuAIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:02 INFO - Running setup.py (path:/tmp/pip-Fin1q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:02 INFO - Running setup.py (path:/tmp/pip-ldvg2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:02 INFO - Running setup.py (path:/tmp/pip-IBRRjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:02 INFO - Running setup.py (path:/tmp/pip-CB4knh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:03 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:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:03 INFO - Running setup.py (path:/tmp/pip-I9lgsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:03 INFO - Running setup.py (path:/tmp/pip-jLXVZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:03 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:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:03 INFO - Running setup.py (path:/tmp/pip-OcUnr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:03 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:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:03 INFO - Running setup.py (path:/tmp/pip-9e_XBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:03 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:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:03 INFO - Running setup.py (path:/tmp/pip-ibQUjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:03 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:18:03 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:18:03 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:18:03 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:18:03 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:03 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:03 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:18:03 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:18:03 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:18:03 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:18:03 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:18:03 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:18:05 INFO - Downloading blessings-1.5.1.tar.gz 16:18:05 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:05 INFO - Installing collected packages: blessings 16:18:05 INFO - Running setup.py install for blessings 16:18:05 INFO - Successfully installed blessings 16:18:05 INFO - Cleaning up... 16:18:05 INFO - Return code: 0 16:18:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:05 INFO - Reading from file tmpfile_stdout 16:18:05 INFO - Current package versions: 16:18:05 INFO - argparse == 1.2.1 16:18:05 INFO - blessings == 1.5.1 16:18:05 INFO - blobuploader == 1.2.4 16:18:05 INFO - docopt == 0.6.1 16:18:05 INFO - manifestparser == 1.1 16:18:05 INFO - mozInstall == 1.12 16:18:05 INFO - mozcrash == 0.16 16:18:05 INFO - mozdebug == 0.1 16:18:05 INFO - mozdevice == 0.47 16:18:05 INFO - mozfile == 1.2 16:18:05 INFO - mozhttpd == 0.7 16:18:05 INFO - mozinfo == 0.9 16:18:05 INFO - mozleak == 0.1 16:18:05 INFO - mozlog == 3.1 16:18:05 INFO - moznetwork == 0.27 16:18:05 INFO - mozprocess == 0.22 16:18:05 INFO - mozprofile == 0.27 16:18:05 INFO - mozrunner == 6.11 16:18:05 INFO - mozscreenshot == 0.1 16:18:05 INFO - mozsystemmonitor == 0.0 16:18:05 INFO - moztest == 0.7 16:18:05 INFO - mozversion == 1.4 16:18:05 INFO - psutil == 3.1.1 16:18:05 INFO - requests == 1.2.3 16:18:05 INFO - wsgiref == 0.1.2 16:18:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:18:05 INFO - Using env: {'DISPLAY': ':0', 16:18:05 INFO - 'HOME': '/home/cltbld', 16:18:05 INFO - 'LANG': 'en_US.UTF-8', 16:18:05 INFO - 'LANGUAGE': 'en_US:en', 16:18:05 INFO - 'LOGNAME': 'cltbld', 16:18:05 INFO - 'MAIL': '/var/mail/cltbld', 16:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:05 INFO - 'MOZ_NO_REMOTE': '1', 16:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:05 INFO - 'NO_EM_RESTART': '1', 16:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:05 INFO - 'PWD': '/builds/slave/test', 16:18:05 INFO - 'SHELL': '/bin/bash', 16:18:05 INFO - 'SHLVL': '1', 16:18:05 INFO - 'TERM': 'linux', 16:18:05 INFO - 'TMOUT': '86400', 16:18:05 INFO - 'USER': 'cltbld', 16:18:05 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:05 INFO - '_': '/tools/buildbot/bin/python'} 16:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:05 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 1)) 16:18:05 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 2)) 16:18:05 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 3)) 16:18:05 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 4)) 16:18:05 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 5)) 16:18:05 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 6)) 16:18:05 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 7)) 16:18:05 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 8)) 16:18:05 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.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:18:05 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 3)) 16:18:05 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:18:05 INFO - Cleaning up... 16:18:05 INFO - Return code: 0 16:18:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:18:05 INFO - Using env: {'DISPLAY': ':0', 16:18:05 INFO - 'HOME': '/home/cltbld', 16:18:05 INFO - 'LANG': 'en_US.UTF-8', 16:18:05 INFO - 'LANGUAGE': 'en_US:en', 16:18:05 INFO - 'LOGNAME': 'cltbld', 16:18:05 INFO - 'MAIL': '/var/mail/cltbld', 16:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:05 INFO - 'MOZ_NO_REMOTE': '1', 16:18:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:05 INFO - 'NO_EM_RESTART': '1', 16:18:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:05 INFO - 'PWD': '/builds/slave/test', 16:18:05 INFO - 'SHELL': '/bin/bash', 16:18:05 INFO - 'SHLVL': '1', 16:18:05 INFO - 'TERM': 'linux', 16:18:05 INFO - 'TMOUT': '86400', 16:18:05 INFO - 'USER': 'cltbld', 16:18:05 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:05 INFO - '_': '/tools/buildbot/bin/python'} 16:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:05 INFO - Downloading/unpacking jsonschema 16:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 16:18:07 INFO - Downloading/unpacking functools32 (from jsonschema) 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:07 INFO - warning: no files found matching '*.txt' 16:18:07 INFO - no previously-included directories found matching 'build' 16:18:07 INFO - no previously-included directories found matching 'dist' 16:18:07 INFO - no previously-included directories found matching '.git*' 16:18:07 INFO - Installing collected packages: jsonschema, functools32 16:18:08 INFO - Running setup.py install for functools32 16:18:08 INFO - warning: no files found matching '*.txt' 16:18:08 INFO - no previously-included directories found matching 'build' 16:18:08 INFO - no previously-included directories found matching 'dist' 16:18:08 INFO - no previously-included directories found matching '.git*' 16:18:08 INFO - Successfully installed jsonschema functools32 16:18:08 INFO - Cleaning up... 16:18:08 INFO - Return code: 0 16:18:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:18:08 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:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:08 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:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f75fdd6af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1424240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, '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': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', '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:18:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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:18:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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:18:08 INFO - Using env: {'DISPLAY': ':0', 16:18:08 INFO - 'HOME': '/home/cltbld', 16:18:08 INFO - 'LANG': 'en_US.UTF-8', 16:18:08 INFO - 'LANGUAGE': 'en_US:en', 16:18:08 INFO - 'LOGNAME': 'cltbld', 16:18:08 INFO - 'MAIL': '/var/mail/cltbld', 16:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:08 INFO - 'MOZ_NO_REMOTE': '1', 16:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:08 INFO - 'NO_EM_RESTART': '1', 16:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:08 INFO - 'PWD': '/builds/slave/test', 16:18:08 INFO - 'SHELL': '/bin/bash', 16:18:08 INFO - 'SHLVL': '1', 16:18:08 INFO - 'TERM': 'linux', 16:18:08 INFO - 'TMOUT': '86400', 16:18:08 INFO - 'USER': 'cltbld', 16:18:08 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:08 INFO - '_': '/tools/buildbot/bin/python'} 16:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:08 INFO - Downloading/unpacking flake8 16:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:18:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 16:18:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:18:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 16:18:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 16:18:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:18:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:18:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:18:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:18:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:18:10 INFO - no previously-included directories found matching 'docs/_build' 16:18:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:18:10 INFO - Running setup.py install for pep8 16:18:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:18:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:18:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:18:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:18:10 INFO - no previously-included directories found matching 'docs/_build' 16:18:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:18:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:18:10 INFO - Cleaning up... 16:18:10 INFO - Return code: 0 16:18:10 INFO - Running post-action listener: _resource_record_post_action 16:18:10 INFO - Running post-action listener: _start_resource_monitoring 16:18:10 INFO - Starting resource monitoring. 16:18:10 INFO - ##### 16:18:10 INFO - ##### Running install step. 16:18:10 INFO - ##### 16:18:10 INFO - Running pre-action listener: _resource_record_pre_action 16:18:10 INFO - Running main action method: install 16:18:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:11 INFO - Reading from file tmpfile_stdout 16:18:11 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:11 INFO - Reading from file tmpfile_stdout 16:18:11 INFO - Output received: 16:18:11 INFO - Usage: mozinstall [options] installer 16:18:11 INFO - Options: 16:18:11 INFO - -h, --help show this help message and exit 16:18:11 INFO - -d DEST, --destination=DEST 16:18:11 INFO - Directory to install application into. [default: 16:18:11 INFO - "/builds/slave/test"] 16:18:11 INFO - --app=APP Application being installed. [default: firefox] 16:18:11 INFO - mkdir: /builds/slave/test/build/application 16:18:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:18:25 INFO - Reading from file tmpfile_stdout 16:18:25 INFO - Output received: 16:18:25 INFO - /builds/slave/test/build/application/firefox/firefox 16:18:25 INFO - Running post-action listener: _resource_record_post_action 16:18:25 INFO - ##### 16:18:25 INFO - ##### Running run-tests step. 16:18:25 INFO - ##### 16:18:25 INFO - Running pre-action listener: _resource_record_pre_action 16:18:25 INFO - Running main action method: run_tests 16:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:25 INFO - Python 2.7.3 16:18:25 INFO - Return code: 0 16:18:25 INFO - grabbing minidump binary from tooltool 16:18:25 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:18:25 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 0x14b30b0>, '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 0x14b3fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14b4440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:25 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:18:25 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:18:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:25 INFO - Return code: 0 16:18:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:18:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:18:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:18:26 INFO - Return code: 0 16:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost'] in /builds/slave/test/build 16:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --webServer localhost 16:18:26 INFO - Using env: {'DISPLAY': ':0', 16:18:26 INFO - 'HOME': '/home/cltbld', 16:18:26 INFO - 'LANG': 'en_US.UTF-8', 16:18:26 INFO - 'LANGUAGE': 'en_US:en', 16:18:26 INFO - 'LOGNAME': 'cltbld', 16:18:26 INFO - 'MAIL': '/var/mail/cltbld', 16:18:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:26 INFO - 'MOZ_NO_REMOTE': '1', 16:18:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:26 INFO - 'NO_EM_RESTART': '1', 16:18:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:26 INFO - 'PWD': '/builds/slave/test', 16:18:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:26 INFO - 'SHELL': '/bin/bash', 16:18:26 INFO - 'SHLVL': '1', 16:18:26 INFO - 'TERM': 'linux', 16:18:26 INFO - 'TMOUT': '86400', 16:18:26 INFO - 'USER': 'cltbld', 16:18:26 INFO - 'XDG_SESSION_COOKIE': '919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728', 16:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:26 INFO - '_': '/tools/buildbot/bin/python'} 16:18:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--webServer', 'localhost'] with output_timeout 3600 16:18:26 INFO - mozversion INFO | application_buildid: 20151212153531 16:18:26 INFO - mozversion INFO | application_changeset: 2fb798379b1638d39228d0e3036812575872681d 16:18:26 INFO - mozversion INFO | application_display_name: Nightly 16:18:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:26 INFO - mozversion INFO | application_name: Firefox 16:18:26 INFO - mozversion INFO | application_remotingname: firefox 16:18:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 16:18:26 INFO - mozversion INFO | application_vendor: Mozilla 16:18:26 INFO - mozversion INFO | application_version: 45.0a1 16:18:26 INFO - mozversion INFO | platform_buildid: 20151212153531 16:18:26 INFO - mozversion INFO | platform_changeset: 2fb798379b1638d39228d0e3036812575872681d 16:18:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 16:18:26 INFO - mozversion INFO | platform_version: 45.0a1 16:18:26 INFO - 2015-12-12 16:18:26,976 DEBUG : using testdate: 1449965906 16:18:26 INFO - 2015-12-12 16:18:26,976 DEBUG : actual date: 1449965906 16:18:27 INFO - 2015-12-12 16:18:27,054 INFO : starting webserver on 'localhost:35380' 16:18:27 INFO - 2015-12-12 16:18:27,055 INFO : Starting test suite talos-linux64-ix-053.e 16:18:27 INFO - 2015-12-12 16:18:27,055 INFO : Starting test dromaeo_css 16:18:27 INFO - 2015-12-12 16:18:27,055 DEBUG : operating with platform_type : linux_ 16:18:27 INFO - 2015-12-12 16:18:27,056 INFO : Initialising browser for dromaeo_css test... 16:18:27 INFO - 2015-12-12 16:18:27,068 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptfULia/profile http://localhost:35380/getInfo.html 16:18:31 INFO - 2015-12-12 16:18:31,353 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:18:31 INFO - 2015-12-12 16:18:31,353 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:18:31 INFO - 2015-12-12 16:18:31,353 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:18:31 INFO - 2015-12-12 16:18:31,353 DEBUG : BROWSER_OUTPUT: __metrics 16:18:31 INFO - 2015-12-12 16:18:31,455 DEBUG : BROWSER_OUTPUT: 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:18:32 INFO - 2015-12-12 16:18:32,353 INFO : Browser initialized. 16:18:32 INFO - 2015-12-12 16:18:32,353 INFO : Running cycle 1/1 for dromaeo_css test... 16:18:32 INFO - 2015-12-12 16:18:32,353 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptfULia/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:18:44 INFO - 2015-12-12 16:18:44,331 DEBUG : BROWSER_OUTPUT: RSS: Main: 143810560 16:18:44 INFO - 2015-12-12 16:18:44,331 DEBUG : BROWSER_OUTPUT: 16:19:33 INFO - 2015-12-12 16:19:33,970 DEBUG : BROWSER_OUTPUT: 1449965973969 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:21:45 INFO - 2015-12-12 16:21:45,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35380/tests/dromaeo/cssquery-ext.html) 16:21:47 INFO - 2015-12-12 16:21:47,090 DEBUG : BROWSER_OUTPUT: RSS: Main: 146993152 16:21:47 INFO - 2015-12-12 16:21:47,090 DEBUG : BROWSER_OUTPUT: 16:24:49 INFO - 2015-12-12 16:24:49,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/dromaeo/cssquery-ext.html (next: http://localhost:35380/tests/dromaeo/cssquery-jquery.html) 16:24:49 INFO - 2015-12-12 16:24:49,756 DEBUG : BROWSER_OUTPUT: RSS: Main: 145272832 16:24:49 INFO - 2015-12-12 16:24:49,756 DEBUG : BROWSER_OUTPUT: 16:27:51 INFO - 2015-12-12 16:27:51,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35380/tests/dromaeo/cssquery-mootools.html) 16:27:52 INFO - 2015-12-12 16:27:52,200 DEBUG : BROWSER_OUTPUT: RSS: Main: 146030592 16:27:52 INFO - 2015-12-12 16:27:52,200 DEBUG : BROWSER_OUTPUT: 16:30:53 INFO - 2015-12-12 16:30:53,952 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35380/tests/dromaeo/cssquery-prototype.html) 16:30:54 INFO - 2015-12-12 16:30:54,558 DEBUG : BROWSER_OUTPUT: RSS: Main: 143118336 16:30:54 INFO - 2015-12-12 16:30:54,559 DEBUG : BROWSER_OUTPUT: 16:33:57 INFO - 2015-12-12 16:33:57,162 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35380/tests/dromaeo/cssquery-yui.html) 16:33:57 INFO - 2015-12-12 16:33:57,796 DEBUG : BROWSER_OUTPUT: RSS: Main: 144257024 16:33:57 INFO - 2015-12-12 16:33:57,796 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,178 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/dromaeo/cssquery-yui.html (next: http://localhost:35380/tests/dromaeo/cssquery-dojo.html) 16:37:00 INFO - 2015-12-12 16:37:00,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 147017728 16:37:00 INFO - 2015-12-12 16:37:00,428 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,429 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:37:00 INFO - 2015-12-12 16:37:00,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:37:00 INFO - 2015-12-12 16:37:00,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:37:00 INFO - 2015-12-12 16:37:00,429 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:37:00 INFO - 2015-12-12 16:37:00,430 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2679.5712685970248;2722.659667541557;2756.2420334408034;2762.2403838944288;2774.6115543823867;4612;4812.542808433193;4842.104210721019;4847.345608342873;4856.295837103622;4721.7639118044135;4761.000189960113;4789.784459699314;4868.269759536062;4873.756312184394;4375.5843194896515;4399.05420334628;4492.842750503733;4496.010877606937;4544.613707834838;4396.582324679151;4417.558244175589;4457.821687132511;4490.079533695601;4556.020455602062;4288.742675439484;4328.696991210617;4384.386185933972;4424.380586717862;4452.599266066037;4441.111777644484;4474.127545128701;4479.664025198091;4494.37078808967;4508.774561271924;4429.49060857999;4437.112577484516;4446;4455.331700244989;4476.664250181217;4521.073179998092;4627.444706635191;4662;4722.386089808338;4735.052989402099;5152.9484705152845;5188.740562971874;5223.738813059332;5228.973855130738;5245.396779370383;5235.39792923811;5248.3701955765555;5259.710715910624;5263.210518422229;5299.311089558333;4637.188492013884;4736;4738.928916066297;4809.639277054235;4859.416869975626;5292.5501332387175;5311.362636483569;5318.574514038867;5342.9732851336275;5383.67697938125;4891.9755401222765;5104.438511763703;5107.157319042316;5121.078205922895;5123.718195499283;5095.184770436712;5122;5170;5195.792168313225;5250.291210686585;5126.077306084941;5132.307138536325;5143.639945203875;5147.073526765218;5178.404483484373;4893.314935908976;5046.571041461517;5116.488351164854;5120.462351453119;5122.513361230677;4202.306619407825;4237.6821738369745;4248.511421186541;4294.818924795643;4300.053988122608;3735.327641024587;3753.8310776015146;3759.8908322045063;3789.242151569697;3804.9429258561145;4318.913621727548;4421.911561768811;4443.666724995638;4487.125010622963;4505.324201369821;5202.24567437727;5209.53114219722;5224.138017227191;5239.240310155083;5245.606579506552;4519.932201016987;4554.954450455453;4558.0655965526985;4562.338460923148;4594.287885377772;4430.246858034142;4548.840790572314;4550.021745324705;4603;4656.813727450932;4936.753162341868;4937.506249375034;4945.23348880924;4959.950400496021;5010.423801262756;5203.661761985459;5229.895402092013;5243.528082472596;5249.08141075318;5264.236685680478;4673.042026384651;4742.549457801503;4756.7145971241835;4801.855944321676;4815.061063092803;5093.487106517857;5146.099432599355;5150.948490515047;5152.3044389007;5165.354330708661;5199.168133098686;5207.3230480037355;5211.687298762086;5213.08770965072;5262.315898933114;4790.353302304145;4901.166801643658;4905.583025442735;5048.167052436307;5055.5702765263895;5049.545540901189;5054.1155297822;5063.696178229318;5067.695938243717;5083.771230294572;4936.827211047596;4965.230389289666;4968.3044373788425;4982.078315511642;5027.824026159067;4848.854534363974;4960.801567937242;4965.155923492943;5091.130740637069;5112.948870511396;4938.135826230324;4969.577585905238;4987.875303117451;4991.2513123030385;5033.5721463676 16:37:00 INFO - 2015-12-12 16:37:00,433 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.518191945886;579.6319337220868;580.6138917619783;584.783630056879;585.756910881984;32800.835995820016;33474.49788253172;33661.15847103821;33715.49426758594;33753;38486.230275394475;38923.805380973055;39052.60947390525;39093.80453097737;39179;7807.765767026998;7904.999999999971;7963.203679632019;7986.161453047404;8018.158093400197;7741.419393545479;7811.76564703057;7814.218578142169;7836.686532538692;7861.056673199196;7884.093329267122;7945.880811787799;7954.085280192765;7994.2405471480115;8017.358611311081;7837.451378403514;7912.208779122099;7917.247861453153;7940.841183176304;7958.602069896511;7720.3051725344985;7796.064472263308;7847.6468558914985;7849;7863.410244231648;36891;37343.25313493715;37494.62505374938;37584.624153758385;37653.43519847204;3020.139260310823;3052.8168309901475;3063.9234019149476;3095.6749541298063;3330.400527904976;26434.339141521425;27105;27155.45689086227;27907.302317442023;27973.720262797317;3347.1799409144674;3377.814220217887;3387.0854869185428;3446.827658617059;3535.876244331436;217398;218426;219583.9999999984;219792;220573;52449.737751311;53146;55968.72015639896;55992.44007559954;56421;62428;63264.68367658132;63269.367306327265;63529.682351587944;64006.35993640097;14299.78550321746;14314.928425358015;14609.853901461061;14611.634709132144;14623.415063397344;10428.426436546062;10514.579416823395;10557.683269501773;10562.471876406149;10605.681829545127;19434.611307773765;19827;19883.801161988486;19931.701024484646;19992.90003549973;7983.361331093499;8111.553864537507;8115.431919765678;8118;8138.267555919995;883.739296907401;948.184561277301;955.3408148377597;1022.3522018180566;1026.7279171019686;25981.74018259831;26004.21987340383;26039.349016274744;26118.477630447283;26325.473490530083;921.9124183202586;985.7239972807625;1040.0119886108228;1082.5858335910805;1132.8753837077916;13608.251546164858;13656.453741850413;13871.653208669864;13901.79147312791;13972.580822575339;247878;250914;252340;252764;253213;45320.32019519644;45788.5421145791;45812.54187458083;45833;45917.31124033142;1222.0651201830428;1225.7977433723336;1241.2614494375832;1255.8932490738207;1266.3347697777392;25178.24465266045;25499.490010199323;26122.608160877222;26379.340516486474;26440.8677956609;40239.597604023584;40332;40404.3939340916;40616.79691601523;40665.1866962665;241607;242550;242815;244844;245076;29529.409411811215;34067;35034.474482882266;40089;44034.55965440305;38376.232475350895;38517.42223866588;38857;39067.609323907534;39306.803465983634;32175;32487;32998.67001330052;33475.995720128434;33701;33022.17444563905;38072.61927380691;39238.607613924636;39441.21117577575;40235.59764402318;32464.51303230406;32957.67042329546;33220.16949576183;33223.50823688185;33287.667123329426;29060.27349316284;29157.85421072966;30244.84877575598;30258.54612180775;30554.236144096576;28622.42755144927;31245.658659444227;31656.36687266288;31834.198536929103;32083.518747218342 16:37:00 INFO - 2015-12-12 16:37:00,433 DEBUG : BROWSER_OUTPUT: |2;jquery.html;332.2524320279371;332.99371287989845;333.8314151353567;334.6987711060044;338.29296769751215;14823.332950017248;15318.617034574127;16029.679406411864;16572.254248558813;16654.583635409104;15251;15660.921695391507;15795.210239488008;15916.602084947883;16072.276747546359;4602.884927876797;4709.599684026862;4732.574068333849;4732.929006064912;4736.715797052188;4664.230401983668;4718.127146477896;4724.267738500521;4725.480197178307;4742.075295317414;4661.731144997927;4923.7291948942975;4993.425756038066;5092.592592592602;5102.540771330579;4961.677490963076;5053.974730126362;5054.1660625996665;5056.595472362221;5081.390233172006;5061.670991385548;5072.67027643202;5096.235564665293;5104.744762761847;5120.974395128038;11432.48553815072;11674.649760507198;14201.431942722382;18282.725759113622;18386.63226735471;1835.7062869940746;1855.5268406556377;1875.784284807251;1894.7726272847208;1895.1661269041715;9255.537223138816;9810.46042467664;12488.687782805411;12662.923651489635;12765.61703148907;1706.2748331958992;1725.7325114607256;1745.729411941151;1757.0599729144974;1771.3800169940546;48154.75922620364;49329.260061099114;49515.50484495181;53071;53744.73127634376;8872.334574906909;9081.863772043425;9250.121238482334;9255.12076352759;9261.67584134552;10953.56185752561;11129.610463633895;11153.609623663295;11154.944225278821;11169.83245251322;6704.664766761643;6759.729610815611;6821.488388370892;6960.164780226405;6967.651617419108;6782.1861376635115;6795.38841504267;6807.319268073252;6808.489363297674;6842.4183944364295;3770.547534295902;3820.7134464915243;3867.439221312894;4050.8177132029127;4224.725392849455;1604.4063696432395;1633.6242664187066;1644.9588760281088;1677.2053902324396;1694.8474637282457;749.6551586270268;844.1347618690891;868.0668281597182;873.8732883731823;960.6829746183743;15101.924490377478;15357.769633455508;15566.922165389327;15804.525864224091;16003;866.4757821518017;866.913308669128;878.6178012564554;897.8294124116397;964.9951750241204;8628.611712472954;8941.508217047995;9116.1339672731;9166.312526560534;9329.486878221764;473160;476911;499194;505599;523535;72356.63821680963;73014;75259.62370188223;76317.23682763102;76661.6166919173;1094.3816743540033;1101.3997371432588;1104.8784633690289;1109.4397329348628;1117.6814607836727;14749.778753318924;14775;14836.62908427298;14928.402863885758;15267.312970916113;33474;33587.83206083954;33598.82404115942;34137.82931085329;34234.65765342415;42897.35653965255;45189.54810451985;46962.530374695816;47529;48470.1122565406;8023.077346105039;8056.154103819014;8126.268635822805;8179.509229446252;8550.273226775793;15591.844081559038;15633.609159771097;15733.763993540335;15774.290156942736;15946.122963237134;12704.428300726304;13154.671133221746;13205.273709946045;13409.463621455032;13415.195088294733;12652.493900243888;12741.745165096832;13264.734705306033;13607;14065.296735163405;13318.866811331764;13502;13571.457141714616;13596.796048059485;14067.437302508195;8837.248833849195;8878.689245876363;8944.5080520572;8944.731658050268;9020.639174432948;10409.583616655249;13999.650008749863;14070.507532236323;14134.010619256964;14161.362738676578 16:37:00 INFO - 2015-12-12 16:37:00,434 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1479.5561331600518;1635.9754603680942;1699.6090899093208;1719.802222744384;1722.7690141267058;6734.663266836663;6790.354916282951;6917.965410172942;7076.964615176923;7077.964610176949;7064.9646751766295;7095.184053833811;7149.213586505481;7166.60583667898;7184.67668954897;2020.3534868841975;2044.9693254601193;2058.094438447086;2061.2064355223274;2070.2650559051544;1964.037621565437;1964.9705254421194;1967.7343558619546;2006.47831563793;2012.1347820437206;2037.2869495676468;2065.2461851424273;2095.413284280404;2097.5490269592033;2100.3488918435332;2013.1041686449537;2031.2992017753852;2031.471817327498;2036.307655397172;2038.0421202035093;2041.4079916824103;2054.5377290109604;2060.4333808202714;2061.8865962372065;2077.8233850122756;6971.093757811452;7054.153501579792;7057.788266352018;7062.894056589155;7069.540479868843;4137;4193.91612167758;4221.936670949938;4225.746455212697;4240.660747140221;2743.7393447622444;2784.9443011139665;2808.705085965985;2815.0850973433717;2819.9013034543896;884.2307192742292;885.4642941020654;898.7932775461616;898.9190972812413;908.62746274028;9094.818103637956;9130.634774609007;9142.542872856331;9153.267738580898;9168.26653867689;3654.3604869147794;3685.520882285339;3686.9262614747554;3688.280785246851;3759.699224062123;7247.818804529824;7275.199728029913;7279.235680253603;7314.268573142643;7324.853502930018;4067.654249388776;4075.612816782401;4081.1225586499045;4085.264652362544;4097.836086556505;3147.1660010097344;3224.1778346521487;3711.2206436648066;3776.66010059096;3790.2798468290935;3073.30850558627;3123.921901952424;3124.0315502194394;3139.073973177918;3162.76279279055;941.1247539788063;952.3476418653245;956.5743244256379;961.0629636104882;961.956711947964;1022.2997246885907;1030.160419258302;1038.5015192707392;1042.5204405973336;1055.5883205549842;6500.512461565401;6517.837054073591;6528.934710652833;6548.1814773153355;6552.770653027121;414;414.22126402363364;416.0244227287007;427.9101388708406;442.48450555103585;5197.8440646780655;5200.97399513;5215.765290561933;5226.529612334832;5278.630495865329;7918.920810791934;8270.090290068089;8293.95853020731;8307.37694672902;8329.167083291622;8121.309688676529;8134.837303253902;8136.633851476698;8146.67413303473;8170.754877353689;6394.1687580614225;6550.672466376662;6552.672366381662;6560;6576.704048317837;6566.967165164144;6661.16735408074;6663.933360666525;6672.866542669217;6673.232578253369;7311.305425984523;7544.47188696786;7591.202923692933;7593.278638529331;7613.581253031137;7661.195574464601;7731.845363092594;7792.181820908723;7799.805004874924;7809.1409944907155;5299.49654782795;5527.474889885454;6269.996800511896;6296.5592408530965;6337.510684988932;6299.96850015747;6373.458256048288;6391.680415979089;6391.93608063932;6424.196975378078;5885.61743486672;6121.571489995657;6130.264368275835;6145.477634400947;6214.844628884314;6096.908546371719;6139.3246742857355;6168.537359697951;6181.876362472635;6241.095041219089;6217.316095229611;6294.905576416266;6302;6302.527310451643;6302.968485157545;5814.476697097112;5833.183354330312;5857.414258574108;5868.882622347443;5877.118432235199;5876.1773351731645;5908.05471124618;5911.113333000085;5928.347881733092;5932.673702946379 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: |4;prototype.html;297.15163209038195;298.21569272812513;305.2292960275303;308.4725120044723;310.88808029109504;5524.723763811813;5611.130274807401;5655.26481557398;5686.516646085088;5692.57305702072;5558;5558.888822223553;5584.636998595099;5590.385057643666;5600.971995140018;4181.937270940924;4324.74051556906;4330.047389574289;4330.372096046071;4336.284513055342;4241.8303267869405;4253.829846806139;4258.233517966764;4262;4275.080857615612;4210.536840947493;4246.511651160125;4252.489701235856;4258.44640196774;4259.190753756792;4255.255330317182;4258.659307255428;4259.551752404197;4260.147970405916;4262.445882035347;3713.832877520518;3717;3718.1819999600043;3719.6466335698065;3720.051386896324;5279.128943724281;5292.311999440087;5403.054465468527;5428.592855535811;5442.591805614595;4884.877878053042;4891.8777030574165;4893.947801222755;4894.877628059327;4896.363472748555;5904.734286957054;5906.143609176645;5906.675132867691;5920.230370051866;5935.970320148372;3937.03542632057;4004.9199016019807;4015.297322968469;4018.4374187613757;4030;6146.815595532141;6217.253929528486;6225.62646241227;6231.968840155816;6242.937570624281;4146.1914926589025;4148.170365926827;4148.336266197394;4151.398047283127;4152.335626299778;4211.473565804275;4217.030083080908;4218.093109981369;4218.493780746268;4218.514870789837;4694.155052090657;4862.586680132228;4869.36698229235;4872.025594881038;4874.634402419832;5911.822645320647;5913.142594323799;5923.674197919156;5928.584999050025;5929.436703513159;1448.9565313040625;1451.0713143588112;1452.7748199029168;1454.985450145506;1456.992715036418;995.6813819577666;996.0934613508732;997.5012493753123;998.7053819123377;1010.4796030044386;5165.483451654804;5169.06956747789;5176.81881134166;5180.300659411007;5181.663191892515;6087.482563982111;6101;6114.57197996136;6137.754489820357;6139.570230083851;5131.692098474104;5155.845324640267;5166.199239117943;5168.948310516922;5191.844244672666;3205.7275131614074;3294.456414691549;3323;3344.4481660526226;3344.548485954365;5658.688772117491;5809.186713860063;5826.533877289807;5848.0350742127075;5852.385499522489;6342.270638876496;6371.649559274192;6376.904346434807;6379.585326953733;6385.584936979081;5801.622894511843;5802.100674395476;5810.50610698087;5819.243498345188;5821.650700957956;6150.261968563801;6169.691515424301;6173.104899789595;6177.876442471215;6178.783742568989;5804.158397032322;6014;6022.397760223943;6027.367126451835;6032.849178770566;5775.971120144541;5795.971020144872;5803.564732644984;5809.912851307318;5823;4064.837406503707;4086.1010577672864;4097.282975479339;4099.9795001026;4101.1592623511515;6025.879482410415;6120.969395152996;6185.8144255672405;6186.350433204629;6187.288461826767;5877.676727780014;5938.6436813791315;5940.198073260228;5940.524758019262;5947.762089516371;5875.764969401347;5926.585139040399;5929.733162007634;5937.168796368425;5943.167956486009;5651.378348381757;5793.478586927028;5796.884062318646;5799.94200057994;5808.94191058084;5962.55280853929;5964.970175149097;5985.790497332572;5993.220881285405;5995.2206213192;5785.103308987113;5799.82600521985;5819.214406055128;5820.5925585210325;5825.271841019872 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: |5;yui.html;670.4267850987405;769.4998251136764;787.9803004924876;792.3106896999611;793.7063286583965;11597.768044639102;11779;11800.645980620573;11836.644900652984;11883.821742673856;11774.82337764932;11794.528218871257;11888.940555297233;11894;11903.64289071327;613.1875265273508;622.8318354044405;800.2917418085008;801.2588355770918;818.6275244763648;813.0527934955769;815.8980127484065;816.510093943635;817.4727300890921;822.0669540072031;806.4878801960739;846.3652260804397;846.8687353460224;852.377764232111;854.4531499840107;838.9538575378351;839.3578912132162;839.8152406470597;841.9200175983271;844.2106630300689;626.6960524145786;629.9244090709144;631.3181763695244;632.5034847644623;632.6520413772407;11501.022413094897;11574.53701851925;11582.942085289604;11611.477483513178;11677.29936203822;10716.517756700967;11207.950231233188;11212.27120237182;11514.136439766968;11654.650360489199;8952.776180595472;9005.909940900638;9082.727518174466;9198.448093114435;9365.110314520178;3137.733292670103;3176.6664500227375;3176.6664500227607;3186.7928584642154;3220.3237320162793;40442;40470.19059618791;40550;40579;40621.79689101565;8733.694320698873;8928.419652722552;8969.14793094655;8988.10118988096;9073.954630226806;16612.916935415247;16631.6673666526;16635.584110397336;16650.3339866404;16658.16709164537;6284.685765711696;6342.4926005919415;6392.968035159887;6455.7740479083;6549.508786840911;3457.0665920201404;3512.841922113511;3519.5776506819348;3572.464130380412;3592.515010473605;2652.416468376954;2679.1025006623;2692.528807458687;2699.7300269972848;2703.405250844811;478.1966296621709;486.248745687913;490.03707714340777;497.8207845175734;499.0692358750976;453.76631035016993;511.6520765879163;576.8290370547795;612.963222206669;621.627023785725;7812.062552493623;7849.843003140019;8009.479383840032;8051.355891528664;8175.305099066646;554.9250851135126;562.7552014873461;578.531389574446;587.0343285295664;592.6740292838921;3374.7806392584403;3501;3542.220711443478;3651.9817400912825;3678.31951089044;50509;50632.49367506352;50671;51204;51324.48675513272;44522.77738611286;44602;44901.32648010283;45064.09871802545;45166.32250516245;40393.798031009654;41355;41681.999999999396;41980;42264.78867605704;7558.58427786477;7613.314801667876;7616.69533218665;7938.960305198437;8324;29997.700022999492;30243.243918902204;30778.846105769328;30956.380872382877;32258.677413225567;36308.27383452263;40010.19979600333;40112.59887401089;40858.1828363437;41035.51198148191;10255.58977640886;10312.262222686391;10327.32872363294;10438.53026613789;10493.895061049292;10937.179711521827;11434;11657.825132622847;11725.88274117248;11783.941080294544;11015.28400653987;11081.889181108085;11101.38942358178;11279.661610151708;11435.142364322875;11056.115510758958;11087.390193539433;11396.145289103517;11424.48589813477;11648.534058637559;10799.082078023142;10946.671599852018;11585.710357241136;11608.30350178992;11638.476268568103;9437.814148734953;9920.851187232052;10004.599816007278;10033.598656053677;10049.397036177852;9301.356199663545;9336.079044809241;9390.577424015799;9436.528173591156;9989.750256243651 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,334 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: __startTimestamp1449967020426__endTimestamp 16:37:00 INFO - 2015-12-12 16:37:00,436 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,437 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:37:00 INFO - 2015-12-12 16:37:00,437 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:37:00 INFO - 2015-12-12 16:37:00,437 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,437 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4766.39 Median:4891.98 stddev:509.37 (10.4%) stddev-sans-first:483.91 16:37:00 INFO - 2015-12-12 16:37:00,438 DEBUG : BROWSER_OUTPUT: Values: 2679.6 2722.7 2756.2 2762.2 2774.6 4612.0 4812.5 4842.1 4847.3 4856.3 4721.8 4761.0 4789.8 4868.3 4873.8 4375.6 4399.1 4492.8 4496.0 4544.6 4396.6 4417.6 4457.8 4490.1 4556.0 4288.7 4328.7 4384.4 4424.4 4452.6 4441.1 4474.1 4479.7 4494.4 4508.8 4429.5 4437.1 4446.0 4455.3 4476.7 4521.1 4627.4 4662.0 4722.4 4735.1 5152.9 5188.7 5223.7 5229.0 5245.4 5235.4 5248.4 5259.7 5263.2 5299.3 4637.2 4736.0 4738.9 4809.6 4859.4 5292.6 5311.4 5318.6 5343.0 5383.7 4892.0 5104.4 5107.2 5121.1 5123.7 5095.2 5122.0 5170.0 5195.8 5250.3 5126.1 5132.3 5143.6 5147.1 5178.4 4893.3 5046.6 5116.5 5120.5 5122.5 4202.3 4237.7 4248.5 4294.8 4300.1 3735.3 3753.8 3759.9 3789.2 3804.9 4318.9 4421.9 4443.7 4487.1 4505.3 5202.2 5209.5 5224.1 5239.2 5245.6 4519.9 4555.0 4558.1 4562.3 4594.3 4430.2 4548.8 4550.0 4603.0 4656.8 4936.8 4937.5 4945.2 4960.0 5010.4 5203.7 5229.9 5243.5 5249.1 5264.2 4673.0 4742.5 4756.7 4801.9 4815.1 5093.5 5146.1 5150.9 5152.3 5165.4 5199.2 5207.3 5211.7 5213.1 5262.3 4790.4 4901.2 4905.6 5048.2 5055.6 5049.5 5054.1 5063.7 5067.7 5083.8 4936.8 4965.2 4968.3 4982.1 5027.8 4848.9 4960.8 4965.2 5091.1 5112.9 4938.1 4969.6 4987.9 4991.3 5033.6 16:37:00 INFO - 2015-12-12 16:37:00,438 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,438 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40673.19 Median:33708.25 stddev:61966.51 (183.8%) stddev-sans-first:62067.16 16:37:00 INFO - 2015-12-12 16:37:00,439 DEBUG : BROWSER_OUTPUT: Values: 563.5 579.6 580.6 584.8 585.8 32800.8 33474.5 33661.2 33715.5 33753.0 38486.2 38923.8 39052.6 39093.8 39179.0 7807.8 7905.0 7963.2 7986.2 8018.2 7741.4 7811.8 7814.2 7836.7 7861.1 7884.1 7945.9 7954.1 7994.2 8017.4 7837.5 7912.2 7917.2 7940.8 7958.6 7720.3 7796.1 7847.6 7849.0 7863.4 36891.0 37343.3 37494.6 37584.6 37653.4 3020.1 3052.8 3063.9 3095.7 3330.4 26434.3 27105.0 27155.5 27907.3 27973.7 3347.2 3377.8 3387.1 3446.8 3535.9 217398.0 218426.0 219584.0 219792.0 220573.0 52449.7 53146.0 55968.7 55992.4 56421.0 62428.0 63264.7 63269.4 63529.7 64006.4 14299.8 14314.9 14609.9 14611.6 14623.4 10428.4 10514.6 10557.7 10562.5 10605.7 19434.6 19827.0 19883.8 19931.7 19992.9 7983.4 8111.6 8115.4 8118.0 8138.3 883.7 948.2 955.3 1022.4 1026.7 25981.7 26004.2 26039.3 26118.5 26325.5 921.9 985.7 1040.0 1082.6 1132.9 13608.3 13656.5 13871.7 13901.8 13972.6 247878.0 250914.0 252340.0 252764.0 253213.0 45320.3 45788.5 45812.5 45833.0 45917.3 1222.1 1225.8 1241.3 1255.9 1266.3 25178.2 25499.5 26122.6 26379.3 26440.9 40239.6 40332.0 40404.4 40616.8 40665.2 241607.0 242550.0 242815.0 244844.0 245076.0 29529.4 34067.0 35034.5 40089.0 44034.6 38376.2 38517.4 38857.0 39067.6 39306.8 32175.0 32487.0 32998.7 33476.0 33701.0 33022.2 38072.6 39238.6 39441.2 40235.6 32464.5 32957.7 33220.2 33223.5 33287.7 29060.3 29157.9 30244.8 30258.5 30554.2 28622.4 31245.7 31656.4 31834.2 32083.5 16:37:00 INFO - 2015-12-12 16:37:00,439 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,439 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26543.94 Median:3959.13 stddev:81037.12 (2046.8%) stddev-sans-first:81240.55 16:37:00 INFO - 2015-12-12 16:37:00,439 DEBUG : BROWSER_OUTPUT: Values: 332.3 333.0 333.8 334.7 338.3 14823.3 15318.6 16029.7 16572.3 16654.6 15251.0 15660.9 15795.2 15916.6 16072.3 4602.9 4709.6 4732.6 4732.9 4736.7 4664.2 4718.1 4724.3 4725.5 4742.1 4661.7 4923.7 4993.4 5092.6 5102.5 4961.7 5054.0 5054.2 5056.6 5081.4 5061.7 5072.7 5096.2 5104.7 5121.0 11432.5 11674.6 14201.4 18282.7 18386.6 1835.7 1855.5 1875.8 1894.8 1895.2 9255.5 9810.5 12488.7 12662.9 12765.6 1706.3 1725.7 1745.7 1757.1 1771.4 48154.8 49329.3 49515.5 53071.0 53744.7 8872.3 9081.9 9250.1 9255.1 9261.7 10953.6 11129.6 11153.6 11154.9 11169.8 6704.7 6759.7 6821.5 6960.2 6967.7 6782.2 6795.4 6807.3 6808.5 6842.4 3770.5 3820.7 3867.4 4050.8 4224.7 1604.4 1633.6 1645.0 1677.2 1694.8 749.7 844.1 868.1 873.9 960.7 15101.9 15357.8 15566.9 15804.5 16003.0 866.5 866.9 878.6 897.8 965.0 8628.6 8941.5 9116.1 9166.3 9329.5 473160.0 476911.0 499194.0 505599.0 523535.0 72356.6 73014.0 75259.6 76317.2 76661.6 1094.4 1101.4 1104.9 1109.4 1117.7 14749.8 14775.0 14836.6 14928.4 15267.3 33474.0 33587.8 33598.8 34137.8 34234.7 42897.4 45189.5 46962.5 47529.0 48470.1 8023.1 8056.2 8126.3 8179.5 8550.3 15591.8 15633.6 15733.8 15774.3 15946.1 12704.4 13154.7 13205.3 13409.5 13415.2 12652.5 12741.7 13264.7 13607.0 14065.3 13318.9 13502.0 13571.5 13596.8 14067.4 8837.2 8878.7 8944.5 8944.7 9020.6 10409.6 13999.7 14070.5 14134.0 14161.4 16:37:00 INFO - 2015-12-12 16:37:00,439 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,439 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4814.76 Median:6134.79 stddev:2494.59 (40.7%) stddev-sans-first:2488.99 16:37:00 INFO - 2015-12-12 16:37:00,440 DEBUG : BROWSER_OUTPUT: Values: 1479.6 1636.0 1699.6 1719.8 1722.8 6734.7 6790.4 6918.0 7077.0 7078.0 7065.0 7095.2 7149.2 7166.6 7184.7 2020.4 2045.0 2058.1 2061.2 2070.3 1964.0 1965.0 1967.7 2006.5 2012.1 2037.3 2065.2 2095.4 2097.5 2100.3 2013.1 2031.3 2031.5 2036.3 2038.0 2041.4 2054.5 2060.4 2061.9 2077.8 6971.1 7054.2 7057.8 7062.9 7069.5 4137.0 4193.9 4221.9 4225.7 4240.7 2743.7 2784.9 2808.7 2815.1 2819.9 884.2 885.5 898.8 898.9 908.6 9094.8 9130.6 9142.5 9153.3 9168.3 3654.4 3685.5 3686.9 3688.3 3759.7 7247.8 7275.2 7279.2 7314.3 7324.9 4067.7 4075.6 4081.1 4085.3 4097.8 3147.2 3224.2 3711.2 3776.7 3790.3 3073.3 3123.9 3124.0 3139.1 3162.8 941.1 952.3 956.6 961.1 962.0 1022.3 1030.2 1038.5 1042.5 1055.6 6500.5 6517.8 6528.9 6548.2 6552.8 414.0 414.2 416.0 427.9 442.5 5197.8 5201.0 5215.8 5226.5 5278.6 7918.9 8270.1 8294.0 8307.4 8329.2 8121.3 8134.8 8136.6 8146.7 8170.8 6394.2 6550.7 6552.7 6560.0 6576.7 6567.0 6661.2 6663.9 6672.9 6673.2 7311.3 7544.5 7591.2 7593.3 7613.6 7661.2 7731.8 7792.2 7799.8 7809.1 5299.5 5527.5 6270.0 6296.6 6337.5 6300.0 6373.5 6391.7 6391.9 6424.2 5885.6 6121.6 6130.3 6145.5 6214.8 6096.9 6139.3 6168.5 6181.9 6241.1 6217.3 6294.9 6302.0 6302.5 6303.0 5814.5 5833.2 5857.4 5868.9 5877.1 5876.2 5908.1 5911.1 5928.3 5932.7 16:37:00 INFO - 2015-12-12 16:37:00,441 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,441 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4877.63 Median:5595.68 stddev:1464.07 (26.2%) stddev-sans-first:1427.24 16:37:00 INFO - 2015-12-12 16:37:00,441 DEBUG : BROWSER_OUTPUT: Values: 297.2 298.2 305.2 308.5 310.9 5524.7 5611.1 5655.3 5686.5 5692.6 5558.0 5558.9 5584.6 5590.4 5601.0 4181.9 4324.7 4330.0 4330.4 4336.3 4241.8 4253.8 4258.2 4262.0 4275.1 4210.5 4246.5 4252.5 4258.4 4259.2 4255.3 4258.7 4259.6 4260.1 4262.4 3713.8 3717.0 3718.2 3719.6 3720.1 5279.1 5292.3 5403.1 5428.6 5442.6 4884.9 4891.9 4893.9 4894.9 4896.4 5904.7 5906.1 5906.7 5920.2 5936.0 3937.0 4004.9 4015.3 4018.4 4030.0 6146.8 6217.3 6225.6 6232.0 6242.9 4146.2 4148.2 4148.3 4151.4 4152.3 4211.5 4217.0 4218.1 4218.5 4218.5 4694.2 4862.6 4869.4 4872.0 4874.6 5911.8 5913.1 5923.7 5928.6 5929.4 1449.0 1451.1 1452.8 1455.0 1457.0 995.7 996.1 997.5 998.7 1010.5 5165.5 5169.1 5176.8 5180.3 5181.7 6087.5 6101.0 6114.6 6137.8 6139.6 5131.7 5155.8 5166.2 5168.9 5191.8 3205.7 3294.5 3323.0 3344.4 3344.5 5658.7 5809.2 5826.5 5848.0 5852.4 6342.3 6371.6 6376.9 6379.6 6385.6 5801.6 5802.1 5810.5 5819.2 5821.7 6150.3 6169.7 6173.1 6177.9 6178.8 5804.2 6014.0 6022.4 6027.4 6032.8 5776.0 5796.0 5803.6 5809.9 5823.0 4064.8 4086.1 4097.3 4100.0 4101.2 6025.9 6121.0 6185.8 6186.4 6187.3 5877.7 5938.6 5940.2 5940.5 5947.8 5875.8 5926.6 5929.7 5937.2 5943.2 5651.4 5793.5 5796.9 5799.9 5808.9 5962.6 5965.0 5985.8 5993.2 5995.2 5785.1 5799.8 5819.2 5820.6 5825.3 16:37:00 INFO - 2015-12-12 16:37:00,441 DEBUG : BROWSER_OUTPUT: 16:37:00 INFO - 2015-12-12 16:37:00,441 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12387.15 Median:41831.00 stddev:13958.22 (33.4%) stddev-sans-first:13969.65 16:37:00 INFO - 2015-12-12 16:37:00,441 DEBUG : BROWSER_OUTPUT: Values: 670.4 769.5 788.0 792.3 793.7 11597.8 11779.0 11800.6 11836.6 11883.8 11774.8 11794.5 11888.9 11894.0 11903.6 613.2 622.8 800.3 801.3 818.6 813.1 815.9 816.5 817.5 822.1 806.5 846.4 846.9 852.4 854.5 839.0 839.4 839.8 841.9 844.2 626.7 629.9 631.3 632.5 632.7 11501.0 11574.5 11582.9 11611.5 11677.3 10716.5 11208.0 11212.3 11514.1 11654.7 8952.8 9005.9 9082.7 9198.4 9365.1 3137.7 3176.7 3176.7 3186.8 3220.3 40442.0 40470.2 40550.0 40579.0 40621.8 8733.7 8928.4 8969.1 8988.1 9074.0 16612.9 16631.7 16635.6 16650.3 16658.2 6284.7 6342.5 6393.0 6455.8 6549.5 3457.1 3512.8 3519.6 3572.5 3592.5 2652.4 2679.1 2692.5 2699.7 2703.4 478.2 486.2 490.0 497.8 499.1 453.8 511.7 576.8 613.0 621.6 7812.1 7849.8 8009.5 8051.4 8175.3 554.9 562.8 578.5 587.0 592.7 3374.8 3501.0 3542.2 3652.0 3678.3 50509.0 50632.5 50671.0 51204.0 51324.5 44522.8 44602.0 44901.3 45064.1 45166.3 40393.8 41355.0 41682.0 41980.0 42264.8 7558.6 7613.3 7616.7 7939.0 8324.0 29997.7 30243.2 30778.8 30956.4 32258.7 36308.3 40010.2 40112.6 40858.2 41035.5 10255.6 10312.3 10327.3 10438.5 10493.9 10937.2 11434.0 11657.8 11725.9 11783.9 11015.3 11081.9 11101.4 11279.7 11435.1 11056.1 11087.4 11396.1 11424.5 11648.5 10799.1 10946.7 11585.7 11608.3 11638.5 9437.8 9920.9 10004.6 10033.6 10049.4 9301.4 9336.1 9390.6 9436.5 9989.8 16:37:00 INFO - 2015-12-12 16:37:00,442 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:37:00 INFO - 2015-12-12 16:37:00,443 DEBUG : BROWSER_OUTPUT: 16:37:01 INFO - 2015-12-12 16:37:01,129 INFO : Browser exited with error code: 0 16:37:01 INFO - 2015-12-12 16:37:01,147 INFO : Completed test dromaeo_css (00:18:34) 16:37:01 INFO - 2015-12-12 16:37:01,147 INFO : Starting test kraken 16:37:01 INFO - 2015-12-12 16:37:01,148 DEBUG : operating with platform_type : linux_ 16:37:01 INFO - 2015-12-12 16:37:01,148 INFO : Initialising browser for kraken test... 16:37:01 INFO - 2015-12-12 16:37:01,154 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpauVb48/profile http://localhost:35380/getInfo.html 16:37:05 INFO - 2015-12-12 16:37:05,289 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:37:05 INFO - 2015-12-12 16:37:05,289 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:37:05 INFO - 2015-12-12 16:37:05,289 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:37:05 INFO - 2015-12-12 16:37:05,289 DEBUG : BROWSER_OUTPUT: __metrics 16:37:05 INFO - 2015-12-12 16:37:05,406 DEBUG : BROWSER_OUTPUT: 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:06 INFO - 2015-12-12 16:37:06,257 INFO : Browser initialized. 16:37:06 INFO - 2015-12-12 16:37:06,257 INFO : Running cycle 1/1 for kraken test... 16:37:06 INFO - 2015-12-12 16:37:06,257 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpauVb48/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:37:18 INFO - 2015-12-12 16:37:18,242 DEBUG : BROWSER_OUTPUT: RSS: Main: 143564800 16:37:18 INFO - 2015-12-12 16:37:18,242 DEBUG : BROWSER_OUTPUT: 16:37:20 INFO - 2015-12-12 16:37:20,849 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35380/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:21 INFO - 2015-12-12 16:37:21,470 DEBUG : BROWSER_OUTPUT: RSS: Main: 144580608 16:37:21 INFO - 2015-12-12 16:37:21,470 DEBUG : BROWSER_OUTPUT: 16:37:24 INFO - 2015-12-12 16:37:24,238 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35380/tests/kraken/driver.html?testName=audio-dft) 16:37:24 INFO - 2015-12-12 16:37:24,839 DEBUG : BROWSER_OUTPUT: RSS: Main: 143704064 16:37:24 INFO - 2015-12-12 16:37:24,840 DEBUG : BROWSER_OUTPUT: 16:37:27 INFO - 2015-12-12 16:37:27,750 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35380/tests/kraken/driver.html?testName=audio-fft) 16:37:28 INFO - 2015-12-12 16:37:28,336 DEBUG : BROWSER_OUTPUT: RSS: Main: 139698176 16:37:28 INFO - 2015-12-12 16:37:28,336 DEBUG : BROWSER_OUTPUT: 16:37:30 INFO - 2015-12-12 16:37:30,413 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35380/tests/kraken/driver.html?testName=audio-oscillator) 16:37:30 INFO - 2015-12-12 16:37:30,999 DEBUG : BROWSER_OUTPUT: RSS: Main: 138641408 16:37:30 INFO - 2015-12-12 16:37:30,999 DEBUG : BROWSER_OUTPUT: 16:37:33 INFO - 2015-12-12 16:37:33,240 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35380/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:33 INFO - 2015-12-12 16:37:33,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 140386304 16:37:33 INFO - 2015-12-12 16:37:33,834 DEBUG : BROWSER_OUTPUT: 16:37:36 INFO - 2015-12-12 16:37:36,893 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35380/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:37 INFO - 2015-12-12 16:37:37,494 DEBUG : BROWSER_OUTPUT: RSS: Main: 140058624 16:37:37 INFO - 2015-12-12 16:37:37,494 DEBUG : BROWSER_OUTPUT: 16:37:40 INFO - 2015-12-12 16:37:40,946 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35380/tests/kraken/driver.html?testName=imaging-desaturate) 16:37:41 INFO - 2015-12-12 16:37:41,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 137920512 16:37:41 INFO - 2015-12-12 16:37:41,548 DEBUG : BROWSER_OUTPUT: 16:37:44 INFO - 2015-12-12 16:37:44,426 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35380/tests/kraken/driver.html?testName=json-parse-financial) 16:37:45 INFO - 2015-12-12 16:37:45,012 DEBUG : BROWSER_OUTPUT: RSS: Main: 139268096 16:37:45 INFO - 2015-12-12 16:37:45,012 DEBUG : BROWSER_OUTPUT: 16:37:46 INFO - 2015-12-12 16:37:46,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35380/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:37:47 INFO - 2015-12-12 16:37:47,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 139710464 16:37:47 INFO - 2015-12-12 16:37:47,368 DEBUG : BROWSER_OUTPUT: 16:37:49 INFO - 2015-12-12 16:37:49,264 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:49 INFO - 2015-12-12 16:37:49,884 DEBUG : BROWSER_OUTPUT: RSS: Main: 139735040 16:37:49 INFO - 2015-12-12 16:37:49,885 DEBUG : BROWSER_OUTPUT: 16:37:52 INFO - 2015-12-12 16:37:52,102 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:37:52 INFO - 2015-12-12 16:37:52,690 DEBUG : BROWSER_OUTPUT: RSS: Main: 140369920 16:37:52 INFO - 2015-12-12 16:37:52,690 DEBUG : BROWSER_OUTPUT: 16:37:55 INFO - 2015-12-12 16:37:55,305 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:37:55 INFO - 2015-12-12 16:37:55,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 140378112 16:37:55 INFO - 2015-12-12 16:37:55,907 DEBUG : BROWSER_OUTPUT: 16:37:58 INFO - 2015-12-12 16:37:58,574 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:37:59 INFO - 2015-12-12 16:37:59,175 DEBUG : BROWSER_OUTPUT: RSS: Main: 139956224 16:37:59 INFO - 2015-12-12 16:37:59,175 DEBUG : BROWSER_OUTPUT: 16:38:00 INFO - 2015-12-12 16:38:00,985 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35380/tests/kraken/driver.html?testName=ai-astar) 16:38:01 INFO - 2015-12-12 16:38:01,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 139964416 16:38:01 INFO - 2015-12-12 16:38:01,235 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,238 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:38:01 INFO - 2015-12-12 16:38:01,239 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:38:01 INFO - 2015-12-12 16:38:01,239 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:38:01 INFO - 2015-12-12 16:38:01,239 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:38:01 INFO - 2015-12-12 16:38:01,239 DEBUG : BROWSER_OUTPUT: |0;ai-astar;115;109;114;113;114;124;114;114;113;111 16:38:01 INFO - 2015-12-12 16:38:01,239 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;164;132;155;168;131;156;156;144;176;130 16:38:01 INFO - 2015-12-12 16:38:01,239 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;163;162;162;163;165;167;162;166;166 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |3;audio-fft;77;75;72;73;75;131;72;73;73;72 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;128;94;95;96;130;94;95;123 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;111;110;111;109;110;111;111;110;110 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;147;148;147;149;148;148;148;148 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;99;96;96;95;96;96;97;96 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;74;74;74;74;75;73;73;74;74 16:38:01 INFO - 2015-12-12 16:38:01,240 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;69;68;68;68;69;68;68;69;69 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;86;87;101;89;85;87;88;87;94 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;138;122;121;136;124;124;126;123;134;125 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;155;164;152;159;140;153;159;156;142 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;73;71;72;73;73;71;77;73;71;70 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,789 16:38:01 INFO - 2015-12-12 16:38:01,241 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:38:01 INFO - 2015-12-12 16:38:01,242 DEBUG : BROWSER_OUTPUT: __startTimestamp1449967081236__endTimestamp 16:38:01 INFO - 2015-12-12 16:38:01,242 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,245 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:38:01 INFO - 2015-12-12 16:38:01,245 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:38:01 INFO - 2015-12-12 16:38:01,246 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,246 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:3.90 (3.4%) stddev-sans-first:4.12 16:38:01 INFO - 2015-12-12 16:38:01,246 DEBUG : BROWSER_OUTPUT: Values: 115.0 109.0 114.0 113.0 114.0 124.0 114.0 114.0 113.0 111.0 16:38:01 INFO - 2015-12-12 16:38:01,246 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,246 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:151.20 Median:156.00 stddev:16.33 (10.5%) stddev-sans-first:16.65 16:38:01 INFO - 2015-12-12 16:38:01,247 DEBUG : BROWSER_OUTPUT: Values: 164.0 132.0 155.0 168.0 131.0 156.0 156.0 144.0 176.0 130.0 16:38:01 INFO - 2015-12-12 16:38:01,247 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,247 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:1.99 (1.2%) stddev-sans-first:2.00 16:38:01 INFO - 2015-12-12 16:38:01,247 DEBUG : BROWSER_OUTPUT: Values: 162.0 163.0 162.0 162.0 163.0 165.0 167.0 162.0 166.0 166.0 16:38:01 INFO - 2015-12-12 16:38:01,247 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,248 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.30 Median:73.00 stddev:18.24 (25.0%) stddev-sans-first:19.33 16:38:01 INFO - 2015-12-12 16:38:01,248 DEBUG : BROWSER_OUTPUT: Values: 77.0 75.0 72.0 73.0 75.0 131.0 72.0 73.0 73.0 72.0 16:38:01 INFO - 2015-12-12 16:38:01,248 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,248 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.40 Median:94.50 stddev:15.70 (16.6%) stddev-sans-first:16.28 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 128.0 94.0 95.0 96.0 130.0 94.0 95.0 123.0 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.40 Median:111.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: Values: 111.0 111.0 110.0 111.0 109.0 110.0 111.0 111.0 110.0 110.0 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 147.0 148.0 147.0 149.0 148.0 148.0 148.0 148.0 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:1.07 (1.1%) stddev-sans-first:1.13 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 99.0 96.0 96.0 95.0 96.0 96.0 97.0 96.0 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.00 Median:74.00 stddev:0.67 (0.9%) stddev-sans-first:0.60 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 74.0 74.0 74.0 75.0 73.0 73.0 74.0 74.0 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,250 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 68.0 68.0 68.0 69.0 68.0 68.0 69.0 69.0 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.00 stddev:4.98 (5.7%) stddev-sans-first:5.07 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 87.0 101.0 89.0 85.0 87.0 88.0 87.0 94.0 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:125.50 stddev:6.24 (5.0%) stddev-sans-first:5.28 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: Values: 138.0 122.0 121.0 136.0 124.0 124.0 126.0 123.0 134.0 125.0 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:155.50 stddev:7.99 (5.1%) stddev-sans-first:7.87 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: Values: 144.0 155.0 164.0 152.0 159.0 140.0 153.0 159.0 156.0 142.0 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:73.00 stddev:1.96 (2.7%) stddev-sans-first:2.06 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: Values: 73.0 71.0 72.0 73.0 73.0 71.0 77.0 73.0 71.0 70.0 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:38:01 INFO - 2015-12-12 16:38:01,251 DEBUG : BROWSER_OUTPUT: 16:38:02 INFO - 2015-12-12 16:38:02,133 INFO : Browser exited with error code: 0 16:38:02 INFO - 2015-12-12 16:38:02,186 INFO : Completed test kraken (00:01:01) 16:38:02 INFO - 2015-12-12 16:38:02,186 INFO : Starting test v8_7 16:38:02 INFO - 2015-12-12 16:38:02,186 DEBUG : operating with platform_type : linux_ 16:38:02 INFO - 2015-12-12 16:38:02,186 INFO : Initialising browser for v8_7 test... 16:38:02 INFO - 2015-12-12 16:38:02,192 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRbehO8/profile http://localhost:35380/getInfo.html 16:38:06 INFO - 2015-12-12 16:38:06,554 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:38:06 INFO - 2015-12-12 16:38:06,554 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:38:06 INFO - 2015-12-12 16:38:06,554 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:38:06 INFO - 2015-12-12 16:38:06,554 DEBUG : BROWSER_OUTPUT: __metrics 16:38:06 INFO - 2015-12-12 16:38:06,651 DEBUG : BROWSER_OUTPUT: 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:38:07 INFO - 2015-12-12 16:38:07,561 INFO : Browser initialized. 16:38:07 INFO - 2015-12-12 16:38:07,561 INFO : Running cycle 1/1 for v8_7 test... 16:38:07 INFO - 2015-12-12 16:38:07,561 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRbehO8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:38:19 INFO - 2015-12-12 16:38:19,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 143654912 16:38:19 INFO - 2015-12-12 16:38:19,611 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35380/tests/v8_7/run.html (next: http://localhost:35380/tests/v8_7/run.html) 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 138424320 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: |0;Richards;167.98252981689905 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.8045336306206 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.1641168289291 16:38:41 INFO - 2015-12-12 16:38:41,260 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5196.891191709845 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1062.6992561105208 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: |5;Earley;718.9072609633357 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: |6;Boyer;12156.626506024097 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: |7;RegExp;26526.315789473683 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: |8;Splay;593.3806146572105 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: __startTimestamp1449967121253__endTimestamp 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.98 Median:167.98 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: Values: 168.0 16:38:41 INFO - 2015-12-12 16:38:41,261 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.80 Median:185.80 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: Values: 185.8 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.16 Median:278.16 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: Values: 278.2 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: Values: 5196.9 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,262 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1062.70 Median:1062.70 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: Values: 1062.7 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:718.91 Median:718.91 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: Values: 718.9 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12156.63 Median:12156.63 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: Values: 12156.6 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26526.32 Median:26526.32 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: Values: 26526.3 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,263 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:593.38 Median:593.38 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,264 DEBUG : BROWSER_OUTPUT: Values: 593.4 16:38:41 INFO - 2015-12-12 16:38:41,264 DEBUG : BROWSER_OUTPUT: 16:38:41 INFO - 2015-12-12 16:38:41,264 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 16:38:41 INFO - 2015-12-12 16:38:41,264 DEBUG : BROWSER_OUTPUT: Values: 4845.4 16:38:41 INFO - 2015-12-12 16:38:41,264 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:38:41 INFO - 2015-12-12 16:38:41,264 DEBUG : BROWSER_OUTPUT: 16:38:42 INFO - 2015-12-12 16:38:42,177 INFO : Browser exited with error code: 0 16:38:42 INFO - 2015-12-12 16:38:42,194 INFO : Completed test v8_7 (00:00:40) 16:38:42 INFO - 2015-12-12 16:38:42,265 INFO : Completed test suite (00:20:15) 16:38:42 INFO - 2015-12-12 16:38:42,265 DEBUG : Working with test: dromaeo_css 16:38:42 INFO - 2015-12-12 16:38:42,265 DEBUG : Generating results file: dromaeo_css 16:38:42 INFO - 2015-12-12 16:38:42,266 DEBUG : Working with test: kraken 16:38:42 INFO - 2015-12-12 16:38:42,266 DEBUG : Generating results file: kraken 16:38:42 INFO - 2015-12-12 16:38:42,266 INFO : javascript benchmark 16:38:42 INFO - 2015-12-12 16:38:42,267 DEBUG : Working with test: v8_7 16:38:42 INFO - 2015-12-12 16:38:42,267 DEBUG : Generating results file: v8_7 16:38:42 INFO - 2015-12-12 16:38:42,267 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:38:42 INFO - 2015-12-12 16:38:42,321 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,132,43]] 16:38:42 INFO - 2015-12-12 16:38:42,321 DEBUG : process_Request line: dromaeo_css 6359.74 graph.html#tests=[[72,132,43]] 16:38:42 INFO - 2015-12-12 16:38:42,321 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:38:42 INFO - 2015-12-12 16:38:42,348 DEBUG : process_Request line: kraken 1550.70 graph.html#tests=[[232,132,43]] 16:38:42 INFO - 2015-12-12 16:38:42,349 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:38:42 INFO - 2015-12-12 16:38:42,375 DEBUG : process_Request line: v8_7 21332.89 graph.html#tests=[[230,132,43]] 16:38:42 INFO - 2015-12-12 16:38:42,376 INFO : javascript benchmark 16:38:42 INFO - 2015-12-12 16:38:42,380 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2679.5712685970248, 2722.659667541557, 2756.2420334408034, 2762.2403838944288, 2774.6115543823867, 4612.0, 4812.542808433193, 4842.104210721019, 4847.345608342873, 4856.295837103622, 4721.7639118044135, 4761.000189960113, 4789.784459699314, 4868.269759536062, 4873.756312184394, 4375.5843194896515, 4399.05420334628, 4492.842750503733, 4496.010877606937, 4544.613707834838, 4396.582324679151, 4417.558244175589, 4457.821687132511, 4490.079533695601, 4556.020455602062, 4288.742675439484, 4328.696991210617, 4384.386185933972, 4424.380586717862, 4452.599266066037, 4441.111777644484, 4474.127545128701, 4479.664025198091, 4494.37078808967, 4508.774561271924, 4429.49060857999, 4437.112577484516, 4446.0, 4455.331700244989, 4476.664250181217, 4521.073179998092, 4627.444706635191, 4662.0, 4722.386089808338, 4735.052989402099, 5152.9484705152845, 5188.740562971874, 5223.738813059332, 5228.973855130738, 5245.396779370383, 5235.39792923811, 5248.3701955765555, 5259.710715910624, 5263.210518422229, 5299.311089558333, 4637.188492013884, 4736.0, 4738.928916066297, 4809.639277054235, 4859.416869975626, 5292.5501332387175, 5311.362636483569, 5318.574514038867, 5342.9732851336275, 5383.67697938125, 4891.9755401222765, 5104.438511763703, 5107.157319042316, 5121.078205922895, 5123.718195499283, 5095.184770436712, 5122.0, 5170.0, 5195.792168313225, 5250.291210686585, 5126.077306084941, 5132.307138536325, 5143.639945203875, 5147.073526765218, 5178.404483484373, 4893.314935908976, 5046.571041461517, 5116.488351164854, 5120.462351453119, 5122.513361230677, 4202.306619407825, 4237.6821738369745, 4248.511421186541, 4294.818924795643, 4300.053988122608, 3735.327641024587, 3753.8310776015146, 3759.8908322045063, 3789.242151569697, 3804.9429258561145, 4318.913621727548, 4421.911561768811, 4443.666724995638, 4487.125010622963, 4505.324201369821, 5202.24567437727, 5209.53114219722, 5224.138017227191, 5239.240310155083, 5245.606579506552, 4519.932201016987, 4554.954450455453, 4558.0655965526985, 4562.338460923148, 4594.287885377772, 4430.246858034142, 4548.840790572314, 4550.021745324705, 4603.0, 4656.813727450932, 4936.753162341868, 4937.506249375034, 4945.23348880924, 4959.950400496021, 5010.423801262756, 5203.661761985459, 5229.895402092013, 5243.528082472596, 5249.08141075318, 5264.236685680478, 4673.042026384651, 4742.549457801503, 4756.7145971241835, 4801.855944321676, 4815.061063092803, 5093.487106517857, 5146.099432599355, 5150.948490515047, 5152.3044389007, 5165.354330708661, 5199.168133098686, 5207.3230480037355, 5211.687298762086, 5213.08770965072, 5262.315898933114, 4790.353302304145, 4901.166801643658, 4905.583025442735, 5048.167052436307, 5055.5702765263895, 5049.545540901189, 5054.1155297822, 5063.696178229318, 5067.695938243717, 5083.771230294572, 4936.827211047596, 4965.230389289666, 4968.3044373788425, 4982.078315511642, 5027.824026159067, 4848.854534363974, 4960.801567937242, 4965.155923492943, 5091.130740637069, 5112.948870511396, 4938.135826230324, 4969.577585905238, 4987.875303117451, 4991.2513123030385, 5033.5721463676], "unit": "ms", "name": "dojo.html", "value": 4733.956743381828}, {"lowerIsBetter": false, "replicates": [563.518191945886, 579.6319337220868, 580.6138917619783, 584.783630056879, 585.756910881984, 32800.835995820016, 33474.49788253172, 33661.15847103821, 33715.49426758594, 33753.0, 38486.230275394475, 38923.805380973055, 39052.60947390525, 39093.80453097737, 39179.0, 7807.765767026998, 7904.999999999971, 7963.203679632019, 7986.161453047404, 8018.158093400197, 7741.419393545479, 7811.76564703057, 7814.218578142169, 7836.686532538692, 7861.056673199196, 7884.093329267122, 7945.880811787799, 7954.085280192765, 7994.2405471480115, 8017.358611311081, 7837.451378403514, 7912.208779122099, 7917.247861453153, 7940.841183176304, 7958.602069896511, 7720.3051725344985, 7796.064472263308, 7847.6468558914985, 7849.0, 7863.410244231648, 36891.0, 37343.25313493715, 37494.62505374938, 37584.624153758385, 37653.43519847204, 3020.139260310823, 3052.8168309901475, 3063.9234019149476, 3095.6749541298063, 3330.400527904976, 26434.339141521425, 27105.0, 27155.45689086227, 27907.302317442023, 27973.720262797317, 3347.1799409144674, 3377.814220217887, 3387.0854869185428, 3446.827658617059, 3535.876244331436, 217398.0, 218426.0, 219583.9999999984, 219792.0, 220573.0, 52449.737751311, 53146.0, 55968.72015639896, 55992.44007559954, 56421.0, 62428.0, 63264.68367658132, 63269.367306327265, 63529.682351587944, 64006.35993640097, 14299.78550321746, 14314.928425358015, 14609.853901461061, 14611.634709132144, 14623.415063397344, 10428.426436546062, 10514.579416823395, 10557.683269501773, 10562.471876406149, 10605.681829545127, 19434.611307773765, 19827.0, 19883.801161988486, 19931.701024484646, 19992.90003549973, 7983.361331093499, 8111.553864537507, 8115.431919765678, 8118.0, 8138.267555919995, 883.739296907401, 948.184561277301, 955.3408148377597, 1022.3522018180566, 1026.7279171019686, 25981.74018259831, 26004.21987340383, 26039.349016274744, 26118.477630447283, 26325.473490530083, 921.9124183202586, 985.7239972807625, 1040.0119886108228, 1082.5858335910805, 1132.8753837077916, 13608.251546164858, 13656.453741850413, 13871.653208669864, 13901.79147312791, 13972.580822575339, 247878.0, 250914.0, 252340.0, 252764.0, 253213.0, 45320.32019519644, 45788.5421145791, 45812.54187458083, 45833.0, 45917.31124033142, 1222.0651201830428, 1225.7977433723336, 1241.2614494375832, 1255.8932490738207, 1266.3347697777392, 25178.24465266045, 25499.490010199323, 26122.608160877222, 26379.340516486474, 26440.8677956609, 40239.597604023584, 40332.0, 40404.3939340916, 40616.79691601523, 40665.1866962665, 241607.0, 242550.0, 242815.0, 244844.0, 245076.0, 29529.409411811215, 34067.0, 35034.474482882266, 40089.0, 44034.55965440305, 38376.232475350895, 38517.42223866588, 38857.0, 39067.609323907534, 39306.803465983634, 32175.0, 32487.0, 32998.67001330052, 33475.995720128434, 33701.0, 33022.17444563905, 38072.61927380691, 39238.607613924636, 39441.21117577575, 40235.59764402318, 32464.51303230406, 32957.67042329546, 33220.16949576183, 33223.50823688185, 33287.667123329426, 29060.27349316284, 29157.85421072966, 30244.84877575598, 30258.54612180775, 30554.236144096576, 28622.42755144927, 31245.658659444227, 31656.36687266288, 31834.198536929103, 32083.518747218342], "unit": "ms", "name": "ext.html", "value": 17250.158276829836}, {"lowerIsBetter": false, "replicates": [332.2524320279371, 332.99371287989845, 333.8314151353567, 334.6987711060044, 338.29296769751215, 14823.332950017248, 15318.617034574127, 16029.679406411864, 16572.254248558813, 16654.583635409104, 15251.0, 15660.921695391507, 15795.210239488008, 15916.602084947883, 16072.276747546359, 4602.884927876797, 4709.599684026862, 4732.574068333849, 4732.929006064912, 4736.715797052188, 4664.230401983668, 4718.127146477896, 4724.267738500521, 4725.480197178307, 4742.075295317414, 4661.731144997927, 4923.7291948942975, 4993.425756038066, 5092.592592592602, 5102.540771330579, 4961.677490963076, 5053.974730126362, 5054.1660625996665, 5056.595472362221, 5081.390233172006, 5061.670991385548, 5072.67027643202, 5096.235564665293, 5104.744762761847, 5120.974395128038, 11432.48553815072, 11674.649760507198, 14201.431942722382, 18282.725759113622, 18386.63226735471, 1835.7062869940746, 1855.5268406556377, 1875.784284807251, 1894.7726272847208, 1895.1661269041715, 9255.537223138816, 9810.46042467664, 12488.687782805411, 12662.923651489635, 12765.61703148907, 1706.2748331958992, 1725.7325114607256, 1745.729411941151, 1757.0599729144974, 1771.3800169940546, 48154.75922620364, 49329.260061099114, 49515.50484495181, 53071.0, 53744.73127634376, 8872.334574906909, 9081.863772043425, 9250.121238482334, 9255.12076352759, 9261.67584134552, 10953.56185752561, 11129.610463633895, 11153.609623663295, 11154.944225278821, 11169.83245251322, 6704.664766761643, 6759.729610815611, 6821.488388370892, 6960.164780226405, 6967.651617419108, 6782.1861376635115, 6795.38841504267, 6807.319268073252, 6808.489363297674, 6842.4183944364295, 3770.547534295902, 3820.7134464915243, 3867.439221312894, 4050.8177132029127, 4224.725392849455, 1604.4063696432395, 1633.6242664187066, 1644.9588760281088, 1677.2053902324396, 1694.8474637282457, 749.6551586270268, 844.1347618690891, 868.0668281597182, 873.8732883731823, 960.6829746183743, 15101.924490377478, 15357.769633455508, 15566.922165389327, 15804.525864224091, 16003.0, 866.4757821518017, 866.913308669128, 878.6178012564554, 897.8294124116397, 964.9951750241204, 8628.611712472954, 8941.508217047995, 9116.1339672731, 9166.312526560534, 9329.486878221764, 473160.0, 476911.0, 499194.0, 505599.0, 523535.0, 72356.63821680963, 73014.0, 75259.62370188223, 76317.23682763102, 76661.6166919173, 1094.3816743540033, 1101.3997371432588, 1104.8784633690289, 1109.4397329348628, 1117.6814607836727, 14749.778753318924, 14775.0, 14836.62908427298, 14928.402863885758, 15267.312970916113, 33474.0, 33587.83206083954, 33598.82404115942, 34137.82931085329, 34234.65765342415, 42897.35653965255, 45189.54810451985, 46962.530374695816, 47529.0, 48470.1122565406, 8023.077346105039, 8056.154103819014, 8126.268635822805, 8179.509229446252, 8550.273226775793, 15591.844081559038, 15633.609159771097, 15733.763993540335, 15774.290156942736, 15946.122963237134, 12704.428300726304, 13154.671133221746, 13205.273709946045, 13409.463621455032, 13415.195088294733, 12652.493900243888, 12741.745165096832, 13264.734705306033, 13607.0, 14065.296735163405, 13318.866811331764, 13502.0, 13571.457141714616, 13596.796048059485, 14067.437302508195, 8837.248833849195, 8878.689245876363, 8944.5080520572, 8944.731658050268, 9020.639174432948, 10409.583616655249, 13999.650008749863, 14070.507532236323, 14134.010619256964, 14161.362738676578], "unit": "ms", "name": "jquery.html", "value": 8194.176015712947}, {"lowerIsBetter": false, "replicates": [1479.5561331600518, 1635.9754603680942, 1699.6090899093208, 1719.802222744384, 1722.7690141267058, 6734.663266836663, 6790.354916282951, 6917.965410172942, 7076.964615176923, 7077.964610176949, 7064.9646751766295, 7095.184053833811, 7149.213586505481, 7166.60583667898, 7184.67668954897, 2020.3534868841975, 2044.9693254601193, 2058.094438447086, 2061.2064355223274, 2070.2650559051544, 1964.037621565437, 1964.9705254421194, 1967.7343558619546, 2006.47831563793, 2012.1347820437206, 2037.2869495676468, 2065.2461851424273, 2095.413284280404, 2097.5490269592033, 2100.3488918435332, 2013.1041686449537, 2031.2992017753852, 2031.471817327498, 2036.307655397172, 2038.0421202035093, 2041.4079916824103, 2054.5377290109604, 2060.4333808202714, 2061.8865962372065, 2077.8233850122756, 6971.093757811452, 7054.153501579792, 7057.788266352018, 7062.894056589155, 7069.540479868843, 4137.0, 4193.91612167758, 4221.936670949938, 4225.746455212697, 4240.660747140221, 2743.7393447622444, 2784.9443011139665, 2808.705085965985, 2815.0850973433717, 2819.9013034543896, 884.2307192742292, 885.4642941020654, 898.7932775461616, 898.9190972812413, 908.62746274028, 9094.818103637956, 9130.634774609007, 9142.542872856331, 9153.267738580898, 9168.26653867689, 3654.3604869147794, 3685.520882285339, 3686.9262614747554, 3688.280785246851, 3759.699224062123, 7247.818804529824, 7275.199728029913, 7279.235680253603, 7314.268573142643, 7324.853502930018, 4067.654249388776, 4075.612816782401, 4081.1225586499045, 4085.264652362544, 4097.836086556505, 3147.1660010097344, 3224.1778346521487, 3711.2206436648066, 3776.66010059096, 3790.2798468290935, 3073.30850558627, 3123.921901952424, 3124.0315502194394, 3139.073973177918, 3162.76279279055, 941.1247539788063, 952.3476418653245, 956.5743244256379, 961.0629636104882, 961.956711947964, 1022.2997246885907, 1030.160419258302, 1038.5015192707392, 1042.5204405973336, 1055.5883205549842, 6500.512461565401, 6517.837054073591, 6528.934710652833, 6548.1814773153355, 6552.770653027121, 414.0, 414.22126402363364, 416.0244227287007, 427.9101388708406, 442.48450555103585, 5197.8440646780655, 5200.97399513, 5215.765290561933, 5226.529612334832, 5278.630495865329, 7918.920810791934, 8270.090290068089, 8293.95853020731, 8307.37694672902, 8329.167083291622, 8121.309688676529, 8134.837303253902, 8136.633851476698, 8146.67413303473, 8170.754877353689, 6394.1687580614225, 6550.672466376662, 6552.672366381662, 6560.0, 6576.704048317837, 6566.967165164144, 6661.16735408074, 6663.933360666525, 6672.866542669217, 6673.232578253369, 7311.305425984523, 7544.47188696786, 7591.202923692933, 7593.278638529331, 7613.581253031137, 7661.195574464601, 7731.845363092594, 7792.181820908723, 7799.805004874924, 7809.1409944907155, 5299.49654782795, 5527.474889885454, 6269.996800511896, 6296.5592408530965, 6337.510684988932, 6299.96850015747, 6373.458256048288, 6391.680415979089, 6391.93608063932, 6424.196975378078, 5885.61743486672, 6121.571489995657, 6130.264368275835, 6145.477634400947, 6214.844628884314, 6096.908546371719, 6139.3246742857355, 6168.537359697951, 6181.876362472635, 6241.095041219089, 6217.316095229611, 6294.905576416266, 6302.0, 6302.527310451643, 6302.968485157545, 5814.476697097112, 5833.183354330312, 5857.414258574108, 5868.882622347443, 5877.118432235199, 5876.1773351731645, 5908.05471124618, 5911.113333000085, 5928.347881733092, 5932.673702946379], "unit": "ms", "name": "mootools.html", "value": 3893.312483863465}, {"lowerIsBetter": false, "replicates": [297.15163209038195, 298.21569272812513, 305.2292960275303, 308.4725120044723, 310.88808029109504, 5524.723763811813, 5611.130274807401, 5655.26481557398, 5686.516646085088, 5692.57305702072, 5558.0, 5558.888822223553, 5584.636998595099, 5590.385057643666, 5600.971995140018, 4181.937270940924, 4324.74051556906, 4330.047389574289, 4330.372096046071, 4336.284513055342, 4241.8303267869405, 4253.829846806139, 4258.233517966764, 4262.0, 4275.080857615612, 4210.536840947493, 4246.511651160125, 4252.489701235856, 4258.44640196774, 4259.190753756792, 4255.255330317182, 4258.659307255428, 4259.551752404197, 4260.147970405916, 4262.445882035347, 3713.832877520518, 3717.0, 3718.1819999600043, 3719.6466335698065, 3720.051386896324, 5279.128943724281, 5292.311999440087, 5403.054465468527, 5428.592855535811, 5442.591805614595, 4884.877878053042, 4891.8777030574165, 4893.947801222755, 4894.877628059327, 4896.363472748555, 5904.734286957054, 5906.143609176645, 5906.675132867691, 5920.230370051866, 5935.970320148372, 3937.03542632057, 4004.9199016019807, 4015.297322968469, 4018.4374187613757, 4030.0, 6146.815595532141, 6217.253929528486, 6225.62646241227, 6231.968840155816, 6242.937570624281, 4146.1914926589025, 4148.170365926827, 4148.336266197394, 4151.398047283127, 4152.335626299778, 4211.473565804275, 4217.030083080908, 4218.093109981369, 4218.493780746268, 4218.514870789837, 4694.155052090657, 4862.586680132228, 4869.36698229235, 4872.025594881038, 4874.634402419832, 5911.822645320647, 5913.142594323799, 5923.674197919156, 5928.584999050025, 5929.436703513159, 1448.9565313040625, 1451.0713143588112, 1452.7748199029168, 1454.985450145506, 1456.992715036418, 995.6813819577666, 996.0934613508732, 997.5012493753123, 998.7053819123377, 1010.4796030044386, 5165.483451654804, 5169.06956747789, 5176.81881134166, 5180.300659411007, 5181.663191892515, 6087.482563982111, 6101.0, 6114.57197996136, 6137.754489820357, 6139.570230083851, 5131.692098474104, 5155.845324640267, 5166.199239117943, 5168.948310516922, 5191.844244672666, 3205.7275131614074, 3294.456414691549, 3323.0, 3344.4481660526226, 3344.548485954365, 5658.688772117491, 5809.186713860063, 5826.533877289807, 5848.0350742127075, 5852.385499522489, 6342.270638876496, 6371.649559274192, 6376.904346434807, 6379.585326953733, 6385.584936979081, 5801.622894511843, 5802.100674395476, 5810.50610698087, 5819.243498345188, 5821.650700957956, 6150.261968563801, 6169.691515424301, 6173.104899789595, 6177.876442471215, 6178.783742568989, 5804.158397032322, 6014.0, 6022.397760223943, 6027.367126451835, 6032.849178770566, 5775.971120144541, 5795.971020144872, 5803.564732644984, 5809.912851307318, 5823.0, 4064.837406503707, 4086.1010577672864, 4097.282975479339, 4099.9795001026, 4101.1592623511515, 6025.879482410415, 6120.969395152996, 6185.8144255672405, 6186.350433204629, 6187.288461826767, 5877.676727780014, 5938.6436813791315, 5940.198073260228, 5940.524758019262, 5947.762089516371, 5875.764969401347, 5926.585139040399, 5929.733162007634, 5937.168796368425, 5943.167956486009, 5651.378348381757, 5793.478586927028, 5796.884062318646, 5799.94200057994, 5808.94191058084, 5962.55280853929, 5964.970175149097, 5985.790497332572, 5993.220881285405, 5995.2206213192, 5785.103308987113, 5799.82600521985, 5819.214406055128, 5820.5925585210325, 5825.271841019872], "unit": "ms", "name": "prototype.html", "value": 4399.524119344829}, {"lowerIsBetter": false, "replicates": [670.4267850987405, 769.4998251136764, 787.9803004924876, 792.3106896999611, 793.7063286583965, 11597.768044639102, 11779.0, 11800.645980620573, 11836.644900652984, 11883.821742673856, 11774.82337764932, 11794.528218871257, 11888.940555297233, 11894.0, 11903.64289071327, 613.1875265273508, 622.8318354044405, 800.2917418085008, 801.2588355770918, 818.6275244763648, 813.0527934955769, 815.8980127484065, 816.510093943635, 817.4727300890921, 822.0669540072031, 806.4878801960739, 846.3652260804397, 846.8687353460224, 852.377764232111, 854.4531499840107, 838.9538575378351, 839.3578912132162, 839.8152406470597, 841.9200175983271, 844.2106630300689, 626.6960524145786, 629.9244090709144, 631.3181763695244, 632.5034847644623, 632.6520413772407, 11501.022413094897, 11574.53701851925, 11582.942085289604, 11611.477483513178, 11677.29936203822, 10716.517756700967, 11207.950231233188, 11212.27120237182, 11514.136439766968, 11654.650360489199, 8952.776180595472, 9005.909940900638, 9082.727518174466, 9198.448093114435, 9365.110314520178, 3137.733292670103, 3176.6664500227375, 3176.6664500227607, 3186.7928584642154, 3220.3237320162793, 40442.0, 40470.19059618791, 40550.0, 40579.0, 40621.79689101565, 8733.694320698873, 8928.419652722552, 8969.14793094655, 8988.10118988096, 9073.954630226806, 16612.916935415247, 16631.6673666526, 16635.584110397336, 16650.3339866404, 16658.16709164537, 6284.685765711696, 6342.4926005919415, 6392.968035159887, 6455.7740479083, 6549.508786840911, 3457.0665920201404, 3512.841922113511, 3519.5776506819348, 3572.464130380412, 3592.515010473605, 2652.416468376954, 2679.1025006623, 2692.528807458687, 2699.7300269972848, 2703.405250844811, 478.1966296621709, 486.248745687913, 490.03707714340777, 497.8207845175734, 499.0692358750976, 453.76631035016993, 511.6520765879163, 576.8290370547795, 612.963222206669, 621.627023785725, 7812.062552493623, 7849.843003140019, 8009.479383840032, 8051.355891528664, 8175.305099066646, 554.9250851135126, 562.7552014873461, 578.531389574446, 587.0343285295664, 592.6740292838921, 3374.7806392584403, 3501.0, 3542.220711443478, 3651.9817400912825, 3678.31951089044, 50509.0, 50632.49367506352, 50671.0, 51204.0, 51324.48675513272, 44522.77738611286, 44602.0, 44901.32648010283, 45064.09871802545, 45166.32250516245, 40393.798031009654, 41355.0, 41681.999999999396, 41980.0, 42264.78867605704, 7558.58427786477, 7613.314801667876, 7616.69533218665, 7938.960305198437, 8324.0, 29997.700022999492, 30243.243918902204, 30778.846105769328, 30956.380872382877, 32258.677413225567, 36308.27383452263, 40010.19979600333, 40112.59887401089, 40858.1828363437, 41035.51198148191, 10255.58977640886, 10312.262222686391, 10327.32872363294, 10438.53026613789, 10493.895061049292, 10937.179711521827, 11434.0, 11657.825132622847, 11725.88274117248, 11783.941080294544, 11015.28400653987, 11081.889181108085, 11101.38942358178, 11279.661610151708, 11435.142364322875, 11056.115510758958, 11087.390193539433, 11396.145289103517, 11424.48589813477, 11648.534058637559, 10799.082078023142, 10946.671599852018, 11585.710357241136, 11608.30350178992, 11638.476268568103, 9437.814148734953, 9920.851187232052, 10004.599816007278, 10033.598656053677, 10049.397036177852, 9301.356199663545, 9336.079044809241, 9390.577424015799, 9436.528173591156, 9989.750256243651], "unit": "ms", "name": "yui.html", "value": 5772.19857255424}], "name": "dromaeo_css", "value": 6359.736085873556}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 109.0, 114.0, 113.0, 114.0, 124.0, 114.0, 114.0, 113.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 114.1}, {"lowerIsBetter": true, "replicates": [164.0, 132.0, 155.0, 168.0, 131.0, 156.0, 156.0, 144.0, 176.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 151.2}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 162.0, 162.0, 163.0, 165.0, 167.0, 162.0, 166.0, 166.0], "unit": "ms", "name": "audio-dft", "value": 163.8}, {"lowerIsBetter": true, "replicates": [77.0, 75.0, 72.0, 73.0, 75.0, 131.0, 72.0, 73.0, 73.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 79.3}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 128.0, 94.0, 95.0, 96.0, 130.0, 94.0, 95.0, 123.0], "unit": "ms", "name": "audio-oscillator", "value": 104.4}, {"lowerIsBetter": true, "replicates": [111.0, 111.0, 110.0, 111.0, 109.0, 110.0, 111.0, 111.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.4}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 148.0, 147.0, 149.0, 148.0, 148.0, 148.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 99.0, 96.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [75.0, 74.0, 74.0, 74.0, 74.0, 75.0, 73.0, 73.0, 74.0, 74.0], "unit": "ms", "name": "json-parse-financial", "value": 74.0}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.4}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 87.0, 101.0, 89.0, 85.0, 87.0, 88.0, 87.0, 94.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 88.9}, {"lowerIsBetter": true, "replicates": [138.0, 122.0, 121.0, 136.0, 124.0, 124.0, 126.0, 123.0, 134.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 127.3}, {"lowerIsBetter": true, "replicates": [144.0, 155.0, 164.0, 152.0, 159.0, 140.0, 153.0, 159.0, 156.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 152.4}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 72.0, 73.0, 73.0, 71.0, 77.0, 73.0, 71.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 72.4}], "name": "kraken", "value": 1550.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.98252981689905], "unit": "score", "name": "Richards", "value": 210.15280599999997}, {"lowerIsBetter": false, "replicates": [185.8045336306206], "unit": "score", "name": "DeltaBlue", "value": 355.8470759999999}, {"lowerIsBetter": false, "replicates": [278.1641168289291], "unit": "score", "name": "Encrypt", "value": 956.9206949999998}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [1062.6992561105208], "unit": "score", "name": "RayTrace", "value": 696.3296489999998}, {"lowerIsBetter": false, "replicates": [718.9072609633357], "unit": "score", "name": "Earley", "value": 927.0500329999999}, {"lowerIsBetter": false, "replicates": [12156.626506024097], "unit": "score", "name": "Boyer", "value": 54.82302180376615}, {"lowerIsBetter": false, "replicates": [26526.315789473683], "unit": "score", "name": "RegExp", "value": 34.34268849206351}, {"lowerIsBetter": false, "replicates": [593.3806146572105], "unit": "score", "name": "Splay", "value": 137.33343824701197}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 21332.89336124016}]} 16:38:42 INFO - RETURN: dromaeo_css: 6359.74 16:38:42 INFO - RETURN: kraken: 1550.70 16:38:42 INFO - RETURN: v8_7: 21332.89 16:38:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,43]]", "result": "21332.89"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,43]]", "result": "1550.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,43]]", "result": "6359.74"}}} 16:38:42 INFO - Return code: 0 16:38:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:38:42 INFO - # TBPL SUCCESS # 16:38:42 INFO - Running post-action listener: _resource_record_post_action 16:38:42 INFO - Running post-run listener: _resource_record_post_run 16:38:43 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 4096; Write bytes: 520007680; Read time: 16; Write time: 491360 16:38:43 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 237613056; Read time: 0; Write time: 294972 16:38:43 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 4096; Write bytes: 278384640; Read time: 16; Write time: 195712 16:38:43 INFO - Running post-run listener: _upload_blobber_files 16:38:43 INFO - Blob upload gear active. 16:38:43 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:38:43 INFO - Copying logs to upload dir... 16:38:43 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1267.322911 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2015-12-12 16:38:41.791496) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-12 16:38:41.795168) ========= 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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010160 build_url: 'https://queue.taskcluster.net/v1/task/K6fbYrbpSD-RnAU3R5H0yQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-12 16:38:41.850999) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:38:41.851382) ========= 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=919420d7ee4ef147bef0346300000385-1449965601.908877-1957186728 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004775 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 16:38:41.901544) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-12 16:38:41.901898) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-12 16:38:41.902228) ========= ========= Total master_lag: 0.28 =========