builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1456338250.38 results: success (0) buildid: 20160224075346 builduid: a454f74b937248a2835d5d4557373204 revision: d21b40099aa0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.381833) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.382731) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.383313) ========= bash -c pwd in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.008506 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.07 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.463562) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.464155) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.479152) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.479734) ========= rm -rf properties in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.533149) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.533896) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.534708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.535332) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-24 10:24:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.5M=0s 2016-02-24 10:24:11 (43.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174567 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.726930) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.727697) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019532 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.781021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:10.781835) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d21b40099aa0 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d21b40099aa0 --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-24 10:24:11,176 Setting DEBUG logging. 2016-02-24 10:24:11,176 attempt 1/10 2016-02-24 10:24:11,176 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d21b40099aa0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-24 10:24:11,448 unpacking tar archive at: mozilla-beta-d21b40099aa0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.581284 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:11.381100) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:11.381483) ========= echo d21b40099aa0 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'd21b40099aa0'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False d21b40099aa0 program finished with exit code 0 elapsedTime=0.004755 script_repo_revision: 'd21b40099aa0' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:11.436260) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:11.436944) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-24 10:24:11.448526) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-02-24 10:24:11.448846) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:24:11 INFO - MultiFileLogger online at 20160224 10:24:11 in /builds/slave/test-pgo 10:24:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:24:11 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:24:11 INFO - {'append_to_log': False, 10:24:11 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:24:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:24:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:24:11 INFO - 'branch': 'Mozilla-Beta', 10:24:11 INFO - 'buildbot_json_path': 'buildprops.json', 10:24:11 INFO - 'config_files': ('talos/linux_config.py',), 10:24:11 INFO - 'default_actions': ('clobber', 10:24:11 INFO - 'read-buildbot-config', 10:24:11 INFO - 'download-and-extract', 10:24:11 INFO - 'populate-webroot', 10:24:11 INFO - 'create-virtualenv', 10:24:11 INFO - 'install', 10:24:11 INFO - 'run-tests'), 10:24:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:24:11 INFO - 'download_minidump_stackwalk': True, 10:24:11 INFO - 'download_symbols': 'ondemand', 10:24:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:24:11 INFO - 'tooltool.py': '/tools/tooltool.py', 10:24:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:24:11 INFO - '/tools/misc-python/virtualenv.py')}, 10:24:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:24:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:24:11 INFO - 'installer_path': 'installer.exe', 10:24:11 INFO - 'log_level': 'info', 10:24:11 INFO - 'log_name': 'talos', 10:24:11 INFO - 'log_to_console': True, 10:24:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:24:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:24:11 INFO - 'opt_config_files': (), 10:24:11 INFO - 'pip_index': False, 10:24:11 INFO - 'sps_profile': False, 10:24:11 INFO - 'sps_profile_interval': 0, 10:24:11 INFO - 'suite': 'dromaeojs', 10:24:11 INFO - 'system_bits': '32', 10:24:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:24:11 INFO - 'title': 'talos-linux64-ix-048', 10:24:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:24:11 INFO - 'use_talos_json': True, 10:24:11 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:24:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:24:11 INFO - 'work_dir': 'build'} 10:24:11 INFO - ##### 10:24:11 INFO - ##### Running clobber step. 10:24:11 INFO - ##### 10:24:11 INFO - Running pre-action listener: _resource_record_pre_action 10:24:11 INFO - Running main action method: clobber 10:24:11 INFO - rmtree: /builds/slave/test-pgo/build 10:24:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:24:12 INFO - Running post-action listener: _resource_record_post_action 10:24:12 INFO - ##### 10:24:12 INFO - ##### Running read-buildbot-config step. 10:24:12 INFO - ##### 10:24:12 INFO - Running pre-action listener: _resource_record_pre_action 10:24:12 INFO - Running main action method: read_buildbot_config 10:24:12 INFO - Using buildbot properties: 10:24:12 INFO - { 10:24:12 INFO - "properties": { 10:24:12 INFO - "buildnumber": 134, 10:24:12 INFO - "product": "firefox", 10:24:12 INFO - "script_repo_revision": "production", 10:24:12 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:24:12 INFO - "repository": "", 10:24:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:24:12 INFO - "buildid": "20160224075346", 10:24:12 INFO - "slavename": "talos-linux64-ix-048", 10:24:12 INFO - "pgo_build": "True", 10:24:12 INFO - "basedir": "/builds/slave/test-pgo", 10:24:12 INFO - "project": "", 10:24:12 INFO - "platform": "ubuntu64_hw", 10:24:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:24:12 INFO - "slavebuilddir": "test", 10:24:12 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:24:12 INFO - "branch": "mozilla-beta", 10:24:12 INFO - "repo_path": "releases/mozilla-beta", 10:24:12 INFO - "stage_platform": "linux64-pgo", 10:24:12 INFO - "builduid": "a454f74b937248a2835d5d4557373204", 10:24:12 INFO - "revision": "d21b40099aa0" 10:24:12 INFO - }, 10:24:12 INFO - "sourcestamp": { 10:24:12 INFO - "repository": "", 10:24:12 INFO - "hasPatch": false, 10:24:12 INFO - "project": "", 10:24:12 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:24:12 INFO - "changes": [ 10:24:12 INFO - { 10:24:12 INFO - "category": null, 10:24:12 INFO - "files": [ 10:24:12 INFO - { 10:24:12 INFO - "url": null, 10:24:12 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:24:12 INFO - } 10:24:12 INFO - ], 10:24:12 INFO - "repository": "", 10:24:12 INFO - "rev": "d21b40099aa0", 10:24:12 INFO - "who": "sendchange", 10:24:12 INFO - "when": 1456338221, 10:24:12 INFO - "number": 7647527, 10:24:12 INFO - "comments": "Bug 1243684 - If symbols_url does not exist, let mozcrash auto-detect it on demand. r=jlund, a=test-only", 10:24:12 INFO - "project": "", 10:24:12 INFO - "at": "Wed 24 Feb 2016 10:23:41", 10:24:12 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:24:12 INFO - "revlink": "", 10:24:12 INFO - "properties": [ 10:24:12 INFO - [ 10:24:12 INFO - "buildid", 10:24:12 INFO - "20160224075346", 10:24:12 INFO - "Change" 10:24:12 INFO - ], 10:24:12 INFO - [ 10:24:12 INFO - "builduid", 10:24:12 INFO - "a454f74b937248a2835d5d4557373204", 10:24:12 INFO - "Change" 10:24:12 INFO - ], 10:24:12 INFO - [ 10:24:12 INFO - "pgo_build", 10:24:12 INFO - "True", 10:24:12 INFO - "Change" 10:24:12 INFO - ] 10:24:12 INFO - ], 10:24:12 INFO - "revision": "d21b40099aa0" 10:24:12 INFO - } 10:24:12 INFO - ], 10:24:12 INFO - "revision": "d21b40099aa0" 10:24:12 INFO - } 10:24:12 INFO - } 10:24:12 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:24:12 INFO - Running post-action listener: _resource_record_post_action 10:24:12 INFO - ##### 10:24:12 INFO - ##### Running download-and-extract step. 10:24:12 INFO - ##### 10:24:12 INFO - Running pre-action listener: _resource_record_pre_action 10:24:12 INFO - Running main action method: download_and_extract 10:24:12 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/test_packages.json 10:24:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:24:12 INFO - Downloaded 1270 bytes. 10:24:12 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:24:12 INFO - Using the following test package requirements: 10:24:12 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:24:12 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:24:12 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'jsshell-linux-x86_64.zip'], 10:24:12 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:24:12 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:24:12 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:24:12 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:24:12 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:24:12 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:24:12 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:24:12 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:24:12 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:24:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:24:13 INFO - Downloaded 21582935 bytes. 10:24:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:24:13 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:24:13 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:24:14 INFO - Return code: 0 10:24:14 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:24:14 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')]} 10:24:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:24:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:24:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:24:15 INFO - Downloaded 21582935 bytes. 10:24:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:24:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:24:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:24:16 INFO - Return code: 0 10:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:24:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:24:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:24:16 INFO - Downloaded 11132038 bytes. 10:24:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 10:24:16 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:24:16 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 10:24:17 INFO - Return code: 0 10:24:17 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')]} 10:24:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:24:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:24:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:24:24 INFO - Downloaded 57941713 bytes. 10:24:24 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:24:24 INFO - mkdir: /builds/slave/test-pgo/properties 10:24:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:24:24 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:24:24 INFO - Contents: 10:24:24 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:24:24 INFO - Running post-action listener: _resource_record_post_action 10:24:24 INFO - Running post-action listener: set_extra_try_arguments 10:24:24 INFO - ##### 10:24:24 INFO - ##### Running populate-webroot step. 10:24:24 INFO - ##### 10:24:24 INFO - Running pre-action listener: _resource_record_pre_action 10:24:24 INFO - Running main action method: populate_webroot 10:24:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:24:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:24:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:24:24 INFO - '--tppagecycles', 10:24:24 INFO - '7'], 10:24:24 INFO - 'tests': ['tsvgm']}}, 10:24:24 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:24:24 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:24:24 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:24:24 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:24:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:24:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:24:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'tests': ['damp', 'tps']}, 10:24:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['damp', 'tps']}, 10:24:24 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:24:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:24:24 INFO - 'other': {'tests': ['a11yr', 10:24:24 INFO - 'ts_paint', 10:24:24 INFO - 'tpaint', 10:24:24 INFO - 'sessionrestore', 10:24:24 INFO - 'sessionrestore_no_auto_restore']}, 10:24:24 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['a11yr', 10:24:24 INFO - 'ts_paint', 10:24:24 INFO - 'tpaint', 10:24:24 INFO - 'sessionrestore', 10:24:24 INFO - 'sessionrestore_no_auto_restore']}, 10:24:24 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['a11yr', 10:24:24 INFO - 'ts_paint', 10:24:24 INFO - 'tpaint', 10:24:24 INFO - 'sessionrestore', 10:24:24 INFO - 'sessionrestore_no_auto_restore']}, 10:24:24 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['a11yr', 10:24:24 INFO - 'ts_paint', 10:24:24 INFO - 'tpaint', 10:24:24 INFO - 'sessionrestore', 10:24:24 INFO - 'sessionrestore_no_auto_restore']}, 10:24:24 INFO - 'other_l64': {'tests': ['a11yr', 10:24:24 INFO - 'ts_paint', 10:24:24 INFO - 'tpaint', 10:24:24 INFO - 'sessionrestore', 10:24:24 INFO - 'sessionrestore_no_auto_restore']}, 10:24:24 INFO - 'other_nol64': {'tests': ['a11yr', 10:24:24 INFO - 'ts_paint', 10:24:24 INFO - 'tpaint', 10:24:24 INFO - 'sessionrestore', 10:24:24 INFO - 'sessionrestore_no_auto_restore']}, 10:24:24 INFO - 'svgr': {'tests': ['tsvgx', 10:24:24 INFO - 'tsvgr_opacity', 10:24:24 INFO - 'tart', 10:24:24 INFO - 'tscrollx', 10:24:24 INFO - 'cart']}, 10:24:24 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['tsvgx', 10:24:24 INFO - 'tsvgr_opacity', 10:24:24 INFO - 'tart', 10:24:24 INFO - 'tscrollx', 10:24:24 INFO - 'cart']}, 10:24:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'tests': ['tp5o']}, 10:24:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'talos_options': ['--e10s'], 10:24:24 INFO - 'tests': ['tp5o']}, 10:24:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'talos_options': ['--xperf_path', 10:24:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:24:24 INFO - 'tests': ['tp5n']}, 10:24:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:24:24 INFO - 'talos_options': ['--e10s', 10:24:24 INFO - '--xperf_path', 10:24:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:24:24 INFO - 'tests': ['tp5n']}}, 10:24:24 INFO - 'talos.zip': {'path': '', 10:24:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:24:24 INFO - Running post-action listener: _resource_record_post_action 10:24:24 INFO - ##### 10:24:24 INFO - ##### Running create-virtualenv step. 10:24:24 INFO - ##### 10:24:24 INFO - Running pre-action listener: _resource_record_pre_action 10:24:24 INFO - Running main action method: create_virtualenv 10:24:24 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:24:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 10:24:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:24:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:24:24 INFO - Using real prefix '/usr' 10:24:24 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:24:24 INFO - Installing distribute.............................................................................................................................................................................................done. 10:24:26 INFO - Installing pip.................done. 10:24:26 INFO - Return code: 0 10:24:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 10:24:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:24:26 INFO - Using env: {'DISPLAY': ':0', 10:24:26 INFO - 'HOME': '/home/cltbld', 10:24:26 INFO - 'LANG': 'en_US.UTF-8', 10:24:26 INFO - 'LANGUAGE': 'en_US:en', 10:24:26 INFO - 'LOGNAME': 'cltbld', 10:24:26 INFO - 'MAIL': '/var/mail/cltbld', 10:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:26 INFO - 'MOZ_NO_REMOTE': '1', 10:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:26 INFO - 'NO_EM_RESTART': '1', 10:24:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:26 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:26 INFO - 'SHELL': '/bin/bash', 10:24:26 INFO - 'SHLVL': '1', 10:24:26 INFO - 'TERM': 'linux', 10:24:26 INFO - 'TMOUT': '86400', 10:24:26 INFO - 'USER': 'cltbld', 10:24:26 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:26 INFO - '_': '/tools/buildbot/bin/python'} 10:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:26 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 10:24:26 INFO - Cleaning up... 10:24:26 INFO - Return code: 0 10:24:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 10:24:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:24:26 INFO - Using env: {'DISPLAY': ':0', 10:24:26 INFO - 'HOME': '/home/cltbld', 10:24:26 INFO - 'LANG': 'en_US.UTF-8', 10:24:26 INFO - 'LANGUAGE': 'en_US:en', 10:24:26 INFO - 'LOGNAME': 'cltbld', 10:24:26 INFO - 'MAIL': '/var/mail/cltbld', 10:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:26 INFO - 'MOZ_NO_REMOTE': '1', 10:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:26 INFO - 'NO_EM_RESTART': '1', 10:24:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:26 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:26 INFO - 'SHELL': '/bin/bash', 10:24:26 INFO - 'SHLVL': '1', 10:24:26 INFO - 'TERM': 'linux', 10:24:26 INFO - 'TMOUT': '86400', 10:24:26 INFO - 'USER': 'cltbld', 10:24:26 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:26 INFO - '_': '/tools/buildbot/bin/python'} 10:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:27 INFO - Downloading/unpacking psutil>=0.7.1 10:24:27 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 10:24:27 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 10:24:27 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 10:24:27 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 10:24:27 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 10:24:27 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 10:24:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:24:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:24:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:24:29 INFO - Installing collected packages: psutil 10:24:29 INFO - Running setup.py install for psutil 10:24:29 INFO - building 'psutil._psutil_linux' extension 10:24:29 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 10:24:29 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 10:24:29 INFO - building 'psutil._psutil_posix' extension 10:24:29 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 10:24:29 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 10:24:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:24:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:24:29 INFO - Successfully installed psutil 10:24:29 INFO - Cleaning up... 10:24:29 INFO - Return code: 0 10:24:29 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:24:29 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')]} 10:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:29 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')]} 10:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 10:24:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:24:29 INFO - Using env: {'DISPLAY': ':0', 10:24:29 INFO - 'HOME': '/home/cltbld', 10:24:29 INFO - 'LANG': 'en_US.UTF-8', 10:24:29 INFO - 'LANGUAGE': 'en_US:en', 10:24:29 INFO - 'LOGNAME': 'cltbld', 10:24:29 INFO - 'MAIL': '/var/mail/cltbld', 10:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:29 INFO - 'MOZ_NO_REMOTE': '1', 10:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:29 INFO - 'NO_EM_RESTART': '1', 10:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:29 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:29 INFO - 'SHELL': '/bin/bash', 10:24:29 INFO - 'SHLVL': '1', 10:24:29 INFO - 'TERM': 'linux', 10:24:29 INFO - 'TMOUT': '86400', 10:24:29 INFO - 'USER': 'cltbld', 10:24:29 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:29 INFO - '_': '/tools/buildbot/bin/python'} 10:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:29 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:24:29 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 10:24:29 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 10:24:29 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 10:24:29 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 10:24:29 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 10:24:29 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 10:24:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:24:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:24:31 INFO - Installing collected packages: mozsystemmonitor 10:24:31 INFO - Running setup.py install for mozsystemmonitor 10:24:31 INFO - Successfully installed mozsystemmonitor 10:24:31 INFO - Cleaning up... 10:24:31 INFO - Return code: 0 10:24:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 10:24:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:24:31 INFO - Using env: {'DISPLAY': ':0', 10:24:31 INFO - 'HOME': '/home/cltbld', 10:24:31 INFO - 'LANG': 'en_US.UTF-8', 10:24:31 INFO - 'LANGUAGE': 'en_US:en', 10:24:31 INFO - 'LOGNAME': 'cltbld', 10:24:31 INFO - 'MAIL': '/var/mail/cltbld', 10:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:31 INFO - 'MOZ_NO_REMOTE': '1', 10:24:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:31 INFO - 'NO_EM_RESTART': '1', 10:24:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:31 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:31 INFO - 'SHELL': '/bin/bash', 10:24:31 INFO - 'SHLVL': '1', 10:24:31 INFO - 'TERM': 'linux', 10:24:31 INFO - 'TMOUT': '86400', 10:24:31 INFO - 'USER': 'cltbld', 10:24:31 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:31 INFO - '_': '/tools/buildbot/bin/python'} 10:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:32 INFO - Downloading/unpacking blobuploader==1.2.4 10:24:32 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 10:24:32 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 10:24:32 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 10:24:32 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 10:24:32 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 10:24:32 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 10:24:33 INFO - Downloading blobuploader-1.2.4.tar.gz 10:24:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:24:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:24:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:34 INFO - Downloading docopt-0.6.1.tar.gz 10:24:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:24:34 INFO - Installing collected packages: blobuploader, requests, docopt 10:24:34 INFO - Running setup.py install for blobuploader 10:24:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:24:34 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:24:34 INFO - Running setup.py install for requests 10:24:35 INFO - Running setup.py install for docopt 10:24:35 INFO - Successfully installed blobuploader requests docopt 10:24:35 INFO - Cleaning up... 10:24:35 INFO - Return code: 0 10:24:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:24:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 10:24:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/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 10:24:35 INFO - Using env: {'DISPLAY': ':0', 10:24:35 INFO - 'HOME': '/home/cltbld', 10:24:35 INFO - 'LANG': 'en_US.UTF-8', 10:24:35 INFO - 'LANGUAGE': 'en_US:en', 10:24:35 INFO - 'LOGNAME': 'cltbld', 10:24:35 INFO - 'MAIL': '/var/mail/cltbld', 10:24:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:35 INFO - 'MOZ_NO_REMOTE': '1', 10:24:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:35 INFO - 'NO_EM_RESTART': '1', 10:24:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:35 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:35 INFO - 'SHELL': '/bin/bash', 10:24:35 INFO - 'SHLVL': '1', 10:24:35 INFO - 'TERM': 'linux', 10:24:35 INFO - 'TMOUT': '86400', 10:24:35 INFO - 'USER': 'cltbld', 10:24:35 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:35 INFO - '_': '/tools/buildbot/bin/python'} 10:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:24:35 INFO - Running setup.py (path:/tmp/pip-1zzq7i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:24:35 INFO - Running setup.py (path:/tmp/pip-aoPJG9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:24:35 INFO - Running setup.py (path:/tmp/pip-eiq1R8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:24:35 INFO - Running setup.py (path:/tmp/pip-npc_jl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:24:35 INFO - Running setup.py (path:/tmp/pip-VmVSDy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:24:35 INFO - Running setup.py (path:/tmp/pip-8Gtkny-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:24:35 INFO - Running setup.py (path:/tmp/pip-zPhVIC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:24:35 INFO - Running setup.py (path:/tmp/pip-lgTaWy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:24:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:24:35 INFO - Running setup.py (path:/tmp/pip-Ao22Sc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:24:36 INFO - Running setup.py (path:/tmp/pip-_Pv67Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:24:36 INFO - Running setup.py (path:/tmp/pip-BVYgW8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:24:36 INFO - Running setup.py (path:/tmp/pip-Iy5U9s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:24:36 INFO - Running setup.py (path:/tmp/pip-uf8jEv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:24:36 INFO - Running setup.py (path:/tmp/pip-cDqnbh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:24:36 INFO - Running setup.py (path:/tmp/pip-U8HhBs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:24:36 INFO - Running setup.py (path:/tmp/pip-SZaOuZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:24:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:24:36 INFO - Running setup.py (path:/tmp/pip-V6q_8s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:24:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:24:36 INFO - Running setup.py install for manifestparser 10:24:36 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:24:36 INFO - Running setup.py install for mozcrash 10:24:36 INFO - Running setup.py install for mozdebug 10:24:36 INFO - Running setup.py install for mozdevice 10:24:37 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for mozfile 10:24:37 INFO - Running setup.py install for mozhttpd 10:24:37 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for mozinfo 10:24:37 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for mozInstall 10:24:37 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for mozleak 10:24:37 INFO - Running setup.py install for mozlog 10:24:37 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for moznetwork 10:24:37 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for mozprocess 10:24:37 INFO - Running setup.py install for mozprofile 10:24:37 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:24:37 INFO - Running setup.py install for mozrunner 10:24:38 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:24:38 INFO - Running setup.py install for mozscreenshot 10:24:38 INFO - Running setup.py install for moztest 10:24:38 INFO - Running setup.py install for mozversion 10:24:38 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:24:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:24:38 INFO - Cleaning up... 10:24:38 INFO - Return code: 0 10:24:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:24: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')]} 10:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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')]} 10:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 10:24:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/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 10:24:38 INFO - Using env: {'DISPLAY': ':0', 10:24:38 INFO - 'HOME': '/home/cltbld', 10:24:38 INFO - 'LANG': 'en_US.UTF-8', 10:24:38 INFO - 'LANGUAGE': 'en_US:en', 10:24:38 INFO - 'LOGNAME': 'cltbld', 10:24:38 INFO - 'MAIL': '/var/mail/cltbld', 10:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:38 INFO - 'MOZ_NO_REMOTE': '1', 10:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:38 INFO - 'NO_EM_RESTART': '1', 10:24:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:38 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:38 INFO - 'SHELL': '/bin/bash', 10:24:38 INFO - 'SHLVL': '1', 10:24:38 INFO - 'TERM': 'linux', 10:24:38 INFO - 'TMOUT': '86400', 10:24:38 INFO - 'USER': 'cltbld', 10:24:38 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:38 INFO - '_': '/tools/buildbot/bin/python'} 10:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:24:38 INFO - Running setup.py (path:/tmp/pip-nBRfqH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 10:24:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:24:38 INFO - Running setup.py (path:/tmp/pip-YnYxfJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:24:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:24:38 INFO - Running setup.py (path:/tmp/pip-ibF8GB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:24:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:24:38 INFO - Running setup.py (path:/tmp/pip-ARcXb_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:24:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:24:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:24:38 INFO - Running setup.py (path:/tmp/pip-ym3Eej-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:24:39 INFO - Running setup.py (path:/tmp/pip-uLW9G0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:24:39 INFO - Running setup.py (path:/tmp/pip-yCr9J4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:24:39 INFO - Running setup.py (path:/tmp/pip-uZD9mi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:24:39 INFO - Running setup.py (path:/tmp/pip-hvTOc9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:24:39 INFO - Running setup.py (path:/tmp/pip-65pD9q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:24:39 INFO - Running setup.py (path:/tmp/pip-F55ElJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:24:39 INFO - Running setup.py (path:/tmp/pip-MoDO_U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:24:39 INFO - Running setup.py (path:/tmp/pip-GsBT3l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:24:39 INFO - Running setup.py (path:/tmp/pip-fLuzsf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:24:39 INFO - Running setup.py (path:/tmp/pip-gyv9ia-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:24:39 INFO - Running setup.py (path:/tmp/pip-nqaj5Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 10:24:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:24:39 INFO - Running setup.py (path:/tmp/pip-igy0GX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:24:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:24:39 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 10:24:39 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 10:24:39 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 10:24:39 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 10:24:39 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 10:24:39 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 10:24:41 INFO - Downloading blessings-1.6.tar.gz 10:24:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:24:41 INFO - Installing collected packages: blessings 10:24:41 INFO - Running setup.py install for blessings 10:24:41 INFO - Successfully installed blessings 10:24:41 INFO - Cleaning up... 10:24:41 INFO - Return code: 0 10:24:41 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:24:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:24:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:24:42 INFO - Reading from file tmpfile_stdout 10:24:42 INFO - Current package versions: 10:24:42 INFO - argparse == 1.2.1 10:24:42 INFO - blessings == 1.6 10:24:42 INFO - blobuploader == 1.2.4 10:24:42 INFO - docopt == 0.6.1 10:24:42 INFO - manifestparser == 1.1 10:24:42 INFO - mozInstall == 1.12 10:24:42 INFO - mozcrash == 0.16 10:24:42 INFO - mozdebug == 0.1 10:24:42 INFO - mozdevice == 0.47 10:24:42 INFO - mozfile == 1.2 10:24:42 INFO - mozhttpd == 0.7 10:24:42 INFO - mozinfo == 0.9 10:24:42 INFO - mozleak == 0.1 10:24:42 INFO - mozlog == 3.1 10:24:42 INFO - moznetwork == 0.27 10:24:42 INFO - mozprocess == 0.22 10:24:42 INFO - mozprofile == 0.27 10:24:42 INFO - mozrunner == 6.11 10:24:42 INFO - mozscreenshot == 0.1 10:24:42 INFO - mozsystemmonitor == 0.0 10:24:42 INFO - moztest == 0.7 10:24:42 INFO - mozversion == 1.4 10:24:42 INFO - psutil == 3.1.1 10:24:42 INFO - requests == 1.2.3 10:24:42 INFO - wsgiref == 0.1.2 10:24:42 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:24: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')]} 10:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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')]} 10:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 10:24:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/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 10:24:42 INFO - Using env: {'DISPLAY': ':0', 10:24:42 INFO - 'HOME': '/home/cltbld', 10:24:42 INFO - 'LANG': 'en_US.UTF-8', 10:24:42 INFO - 'LANGUAGE': 'en_US:en', 10:24:42 INFO - 'LOGNAME': 'cltbld', 10:24:42 INFO - 'MAIL': '/var/mail/cltbld', 10:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:42 INFO - 'MOZ_NO_REMOTE': '1', 10:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:42 INFO - 'NO_EM_RESTART': '1', 10:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:42 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:42 INFO - 'SHELL': '/bin/bash', 10:24:42 INFO - 'SHLVL': '1', 10:24:42 INFO - 'TERM': 'linux', 10:24:42 INFO - 'TMOUT': '86400', 10:24:42 INFO - 'USER': 'cltbld', 10:24:42 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:42 INFO - '_': '/tools/buildbot/bin/python'} 10:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:24:42 INFO - Cleaning up... 10:24:42 INFO - Return code: 0 10:24:42 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:24: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')]} 10:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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')]} 10:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 10:24:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:24:42 INFO - Using env: {'DISPLAY': ':0', 10:24:42 INFO - 'HOME': '/home/cltbld', 10:24:42 INFO - 'LANG': 'en_US.UTF-8', 10:24:42 INFO - 'LANGUAGE': 'en_US:en', 10:24:42 INFO - 'LOGNAME': 'cltbld', 10:24:42 INFO - 'MAIL': '/var/mail/cltbld', 10:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:42 INFO - 'MOZ_NO_REMOTE': '1', 10:24:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:42 INFO - 'NO_EM_RESTART': '1', 10:24:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:42 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:42 INFO - 'SHELL': '/bin/bash', 10:24:42 INFO - 'SHLVL': '1', 10:24:42 INFO - 'TERM': 'linux', 10:24:42 INFO - 'TMOUT': '86400', 10:24:42 INFO - 'USER': 'cltbld', 10:24:42 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:42 INFO - '_': '/tools/buildbot/bin/python'} 10:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:42 INFO - Downloading/unpacking jsonschema 10:24:42 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 10:24:42 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 10:24:42 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 10:24:42 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 10:24:42 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 10:24:42 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 10:24:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:24:44 INFO - Downloading/unpacking functools32 (from jsonschema) 10:24:44 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 10:24:44 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 10:24:44 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 10:24:44 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 10:24:44 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 10:24:44 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 10:24:44 INFO - Downloading functools32-3.2.3-2.tar.gz 10:24:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:24:44 INFO - warning: no files found matching '*.txt' 10:24:44 INFO - no previously-included directories found matching 'build' 10:24:44 INFO - no previously-included directories found matching 'dist' 10:24:44 INFO - no previously-included directories found matching '.git*' 10:24:44 INFO - Installing collected packages: jsonschema, functools32 10:24:44 INFO - Running setup.py install for functools32 10:24:44 INFO - warning: no files found matching '*.txt' 10:24:44 INFO - no previously-included directories found matching 'build' 10:24:44 INFO - no previously-included directories found matching 'dist' 10:24:44 INFO - no previously-included directories found matching '.git*' 10:24:44 INFO - Successfully installed jsonschema functools32 10:24:44 INFO - Cleaning up... 10:24:44 INFO - Return code: 0 10:24:44 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:24: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')]} 10:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24: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')]} 10:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ff0bd6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b228a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bef600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 10:24:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 10:24:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:24:44 INFO - Using env: {'DISPLAY': ':0', 10:24:44 INFO - 'HOME': '/home/cltbld', 10:24:44 INFO - 'LANG': 'en_US.UTF-8', 10:24:44 INFO - 'LANGUAGE': 'en_US:en', 10:24:44 INFO - 'LOGNAME': 'cltbld', 10:24:44 INFO - 'MAIL': '/var/mail/cltbld', 10:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:44 INFO - 'MOZ_NO_REMOTE': '1', 10:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:24:44 INFO - 'NO_EM_RESTART': '1', 10:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:24:44 INFO - 'PWD': '/builds/slave/test-pgo', 10:24:44 INFO - 'SHELL': '/bin/bash', 10:24:44 INFO - 'SHLVL': '1', 10:24:44 INFO - 'TERM': 'linux', 10:24:44 INFO - 'TMOUT': '86400', 10:24:44 INFO - 'USER': 'cltbld', 10:24:44 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:44 INFO - '_': '/tools/buildbot/bin/python'} 10:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:45 INFO - Downloading/unpacking flake8 10:24:45 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 10:24:45 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 10:24:45 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 10:24:45 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 10:24:45 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 10:24:45 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 10:24:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:24:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:24:46 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 10:24:46 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 10:24:46 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 10:24:46 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 10:24:46 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 10:24:46 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 10:24:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:24:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:24:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:24:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:24:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:24:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:24:47 INFO - no previously-included directories found matching 'docs/_build' 10:24:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:24:47 INFO - Running setup.py install for pep8 10:24:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:24:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:24:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:24:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:24:47 INFO - no previously-included directories found matching 'docs/_build' 10:24:47 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:24:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:24:47 INFO - Cleaning up... 10:24:48 INFO - Return code: 0 10:24:48 INFO - Running post-action listener: _resource_record_post_action 10:24:48 INFO - Running post-action listener: _start_resource_monitoring 10:24:48 INFO - Starting resource monitoring. 10:24:48 INFO - ##### 10:24:48 INFO - ##### Running install step. 10:24:48 INFO - ##### 10:24:48 INFO - Running pre-action listener: _resource_record_pre_action 10:24:48 INFO - Running main action method: install 10:24:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:24:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:24:48 INFO - Reading from file tmpfile_stdout 10:24:48 INFO - Detecting whether we're running mozinstall >=1.0... 10:24:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:24:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:24:48 INFO - Reading from file tmpfile_stdout 10:24:48 INFO - Output received: 10:24:48 INFO - Usage: mozinstall [options] installer 10:24:48 INFO - Options: 10:24:48 INFO - -h, --help show this help message and exit 10:24:48 INFO - -d DEST, --destination=DEST 10:24:48 INFO - Directory to install application into. [default: 10:24:48 INFO - "/builds/slave/test-pgo"] 10:24:48 INFO - --app=APP Application being installed. [default: firefox] 10:24:48 INFO - mkdir: /builds/slave/test-pgo/build/application 10:24:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:24:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:25:03 INFO - Reading from file tmpfile_stdout 10:25:03 INFO - Output received: 10:25:03 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:25:03 INFO - Running post-action listener: _resource_record_post_action 10:25:03 INFO - ##### 10:25:03 INFO - ##### Running run-tests step. 10:25:03 INFO - ##### 10:25:03 INFO - Running pre-action listener: _resource_record_pre_action 10:25:03 INFO - Running main action method: run_tests 10:25:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:25:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:25:03 INFO - Python 2.7.3 10:25:03 INFO - Return code: 0 10:25:03 INFO - grabbing minidump binary from tooltool 10:25:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:25:03 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-pgo/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 0x2c7e350>, '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 0x2c7f250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7f6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:25:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 10:25:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:25:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:25:03 INFO - Return code: 0 10:25:03 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:25:03 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:25:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:25:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:25:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:25:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:25:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:25:04 INFO - Return code: 0 10:25:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:25:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost 10:25:04 INFO - Using env: {'DISPLAY': ':0', 10:25:04 INFO - 'HOME': '/home/cltbld', 10:25:04 INFO - 'LANG': 'en_US.UTF-8', 10:25:04 INFO - 'LANGUAGE': 'en_US:en', 10:25:04 INFO - 'LOGNAME': 'cltbld', 10:25:04 INFO - 'MAIL': '/var/mail/cltbld', 10:25:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:25:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:04 INFO - 'MOZ_NO_REMOTE': '1', 10:25:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:25:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:25:04 INFO - 'NO_EM_RESTART': '1', 10:25:04 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:25:04 INFO - 'PWD': '/builds/slave/test-pgo', 10:25:04 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:25:04 INFO - 'SHELL': '/bin/bash', 10:25:04 INFO - 'SHLVL': '1', 10:25:04 INFO - 'TERM': 'linux', 10:25:04 INFO - 'TMOUT': '86400', 10:25:04 INFO - 'USER': 'cltbld', 10:25:04 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003', 10:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:04 INFO - '_': '/tools/buildbot/bin/python'} 10:25:04 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost'] with output_timeout 3600 10:25:04 INFO - mozversion INFO | application_buildid: 20160224075346 10:25:04 INFO - mozversion INFO | application_changeset: d21b40099aa0294775000d89746b28bfea4d4591 10:25:04 INFO - mozversion INFO | application_display_name: Nightly 10:25:04 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:25:04 INFO - mozversion INFO | application_name: Firefox 10:25:04 INFO - mozversion INFO | application_remotingname: firefox 10:25:04 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:25:04 INFO - mozversion INFO | application_vendor: Mozilla 10:25:04 INFO - mozversion INFO | application_version: 45.0 10:25:04 INFO - mozversion INFO | platform_buildid: 20160224075346 10:25:04 INFO - mozversion INFO | platform_changeset: d21b40099aa0294775000d89746b28bfea4d4591 10:25:04 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:25:04 INFO - mozversion INFO | platform_version: 45.0 10:25:04 INFO - 2016-02-24 10:25:04,645 DEBUG : using testdate: 1456338304 10:25:04 INFO - 2016-02-24 10:25:04,645 DEBUG : actual date: 1456338304 10:25:04 INFO - 2016-02-24 10:25:04,691 INFO : starting webserver on 'localhost:38196' 10:25:04 INFO - 2016-02-24 10:25:04,692 INFO : Starting test suite talos-linux64-ix-048 10:25:04 INFO - 2016-02-24 10:25:04,693 INFO : Starting test dromaeo_css 10:25:04 INFO - 2016-02-24 10:25:04,693 DEBUG : operating with platform_type : linux_ 10:25:04 INFO - 2016-02-24 10:25:04,693 INFO : Initialising browser for dromaeo_css test... 10:25:04 INFO - 2016-02-24 10:25:04,708 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgNPVNH/profile http://localhost:38196/getInfo.html 10:25:08 INFO - 2016-02-24 10:25:08,666 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:25:08 INFO - 2016-02-24 10:25:08,667 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:25:08 INFO - 2016-02-24 10:25:08,667 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:25:08 INFO - 2016-02-24 10:25:08,667 DEBUG : BROWSER_OUTPUT: __metrics 10:25:08 INFO - 2016-02-24 10:25:08,977 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:25:10 INFO - 2016-02-24 10:25:10,367 INFO : Browser initialized. 10:25:10 INFO - 2016-02-24 10:25:10,368 INFO : Running cycle 1/1 for dromaeo_css test... 10:25:10 INFO - 2016-02-24 10:25:10,368 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgNPVNH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:25:22 INFO - 2016-02-24 10:25:22,047 DEBUG : BROWSER_OUTPUT: RSS: Main: 132050944 10:25:22 INFO - 2016-02-24 10:25:22,047 DEBUG : BROWSER_OUTPUT: 10:26:12 INFO - 2016-02-24 10:26:12,867 DEBUG : BROWSER_OUTPUT: 1456338372866 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] 10:28:21 INFO - 2016-02-24 10:28:21,083 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38196/tests/dromaeo/cssquery-ext.html) 10:28:22 INFO - 2016-02-24 10:28:22,152 DEBUG : BROWSER_OUTPUT: RSS: Main: 230744064 10:28:22 INFO - 2016-02-24 10:28:22,153 DEBUG : BROWSER_OUTPUT: 10:31:24 INFO - 2016-02-24 10:31:24,133 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/dromaeo/cssquery-ext.html (next: http://localhost:38196/tests/dromaeo/cssquery-jquery.html) 10:31:24 INFO - 2016-02-24 10:31:24,698 DEBUG : BROWSER_OUTPUT: RSS: Main: 214863872 10:31:24 INFO - 2016-02-24 10:31:24,699 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-24 10:34:26,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38196/tests/dromaeo/cssquery-mootools.html) 10:34:26 INFO - 2016-02-24 10:34:26,952 DEBUG : BROWSER_OUTPUT: RSS: Main: 222457856 10:34:26 INFO - 2016-02-24 10:34:26,952 DEBUG : BROWSER_OUTPUT: 10:37:28 INFO - 2016-02-24 10:37:28,633 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38196/tests/dromaeo/cssquery-prototype.html) 10:37:29 INFO - 2016-02-24 10:37:29,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 197386240 10:37:29 INFO - 2016-02-24 10:37:29,201 DEBUG : BROWSER_OUTPUT: 10:40:31 INFO - 2016-02-24 10:40:31,662 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38196/tests/dromaeo/cssquery-yui.html) 10:40:32 INFO - 2016-02-24 10:40:32,235 DEBUG : BROWSER_OUTPUT: RSS: Main: 199958528 10:40:32 INFO - 2016-02-24 10:40:32,235 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,389 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/dromaeo/cssquery-yui.html (next: http://localhost:38196/tests/dromaeo/cssquery-dojo.html) 10:43:34 INFO - 2016-02-24 10:43:34,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 241602560 10:43:34 INFO - 2016-02-24 10:43:34,640 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,640 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:43:34 INFO - 2016-02-24 10:43:34,640 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:43:34 INFO - 2016-02-24 10:43:34,640 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:43:34 INFO - 2016-02-24 10:43:34,641 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:43:34 INFO - 2016-02-24 10:43:34,644 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3279.098247981805;3315.552400425942;3335.8498867550957;3342.9331413371733;3365.579302587176;5441.537469315103;5447.155690867917;5451.427600101987;5463.972680136599;5475.397706252309;5447.6731396116165;5450.5367043801225;5456.48163424475;5466.562674986001;5475.534579560742;4793.185158523042;4857.635677324197;4948.406191257045;4990.1516742153735;5009.223570346602;4856.271559266121;4883.755812209393;4925.975370123145;4978.875528111808;5000.374953130859;4919.508049195088;4947.554720075193;4952.975235123838;4959.553640172384;4987.925181122286;4895.388076490439;4908;4933.975330123362;4960.33035540201;4974.751262436864;4766.713997160182;4792.688475249132;4870.975645121787;4908.092002979461;4909.337239472697;5246.23929530216;5257.76340064698;5261.579073674096;5288.497592728685;5356.276902618175;5763.682997435139;5775;5778.5377169826315;5785.27684039495;5794;5676.091825307973;5794.797182098607;5840.415958404168;5866.70666466675;5885.852853678693;5546.528545073714;5546.69493177871;5547.33431988164;5549.833504994856;5554.666719996772;5979.5814292999075;5987;5987.760489580368;5992.400759923973;5994.100884867305;5585.106382978704;5664.8867022659315;5685.232493613392;5779.971100144557;5822.970885145632;5854.121881717776;5859.882802344015;5865.912011319833;5877.324107727581;5913.704314784243;5844.035734103911;5851.297844258631;5852.356240813507;5853.209816674779;5858.150568167592;5622.718864056781;5704.201411802351;5788.681622510718;5804.477597016289;5827.737751801179;4516.141933032779;4525.660575456854;4526.456825180933;4536.228841097021;4556;3943.4479172915817;4081.285774989365;4093.3246014407887;4094.6724262058965;4110.568390319033;5438.048341540215;5517.282753242052;5611.915821262684;5665.320161580555;5666.03677374839;5887.470127688529;5928.8814223716145;5931.822045338647;5932.703364831741;5934.703264836741;5326.0945639240645;5609.326880774252;5624.437556244343;5625.521830644359;5628.465295796893;5004.099262132851;5036.748162591855;5077.1876499759865;5103.10695628271;5111.821086261963;6069.332373438918;6082.787102451482;6083.026715725463;6084.60450070744;6087.96956015226;5647.774089036392;5695.117256825199;5916.171735956969;5921.3782552831335;5922.200502932049;5993.910091348546;6117.816465506042;6123.08153776937;6127.80043271483;6132.091946452653;5769.826905192851;5815.76736930518;5824.475797178275;5824.796132135355;5850.707464626837;5645.858853528694;5703.942960570341;5756.654600723802;5902.763889444374;5920.496757775465;5768.596198266081;5770.913436298543;5771.249737534262;5772.134179873053;5853.707314634336;5720.8283751487525;5724.4561766632105;5736.1109028099045;5740.942590574041;5742.19609254713;5414.83755487336;5521.5306698931045;5613.101903695552;5684.147377893349;5729.598928074995;5744.540436764965;5778.191053252464;5781.682007489629;5802.361740208658;5803.7968671096305;5644.096944489026;5656.943430565641;5684.261046063985;5696.174054761954;5707.229524014205 10:43:34 INFO - 2016-02-24 10:43:34,644 DEBUG : BROWSER_OUTPUT: |1;ext.html;610;645.8320836582488;655.5837043477392;667.4293479075387;703.5356664601361;30306;40229.195416091694;40658.7967060165;40918.38622420666;41172.794136029275;45513;45965.54034459654;46015.539844601546;46044;46102.538974610245;8001.599920004006;8135.593220338989;9124.36129470939;9308.767280817967;9319;10798;11011.28426652265;11037.503312350964;11045.502952367162;11051.613193538227;10773.676789696321;10893.455327233607;10899;10902.400367979759;10903.400312982783;10730.141588672888;10850.18623603225;10854.945725271324;10861.294015888941;10862.61980830671;10449.111825494841;10517.631882884063;10571.682849514527;10578.788424231472;10584.947075264652;41358.586414135774;41939.37090943638;41954.580454195675;42026;42063.57936420627;3354.9161270968175;3391.1522119470133;3426.760126791101;3435.12404336895;3499.422595271777;32392.352152957046;32594.194162278363;32721.836390817894;32739;32836.507452388236;4310.698251122422;4349.456317960255;4377.978110109461;4399.208142534342;4466.4640243171025;297989;298153;298898;299417;301312;53421.7328913353;54108.188377174374;59097.70451147717;61187;61567.69216153891;60088.69955650281;60450;60882;60924.390756092755;61091;14934.178620175777;15055.548333549792;15060.698786024219;15071.246437678072;15092.924535377253;11744;11777.293362398112;11812.055035597132;11839.704007399712;11885.28688278706;19720.605587888447;20062.197512099643;20084;20139;20226.797732022787;8250.092489826115;8253.133420990709;8288.917110828814;8342.916570834335;8375.8324833503;1022.5143057047843;1090.4820210399996;1153.454371142673;1223.5839814462947;1229.465182645552;28884.42231155365;29495.410091798043;30301;30392.848035759678;30397.544036839467;1127.7414504337899;1167.6030149749126;1237.2638280223252;1246.2584762066556;1275.6173148055545;13474.393652285671;15704.528864134094;15711.335075276844;16002.519924402286;16311.347546098023;259305;261381;261715;261857;262448;49105.263421048716;49298.26052609214;49547;49627.75186124046;49708.50291497111;1453.157168842069;1462.7805829125716;1479.2751551739784;1487.4719474586582;1489.7467430536835;28897.56653650239;29818.254543636584;30011;30239.697603023687;30776.38447230998;45517.77241113773;45622;45897.31154032626;45918.31122533231;45974.54025459703;211892;214465;214909;215624;215798;28341.149765507067;33674.83162584171;33696.326073478886;33910;33986.15034624154;39443.605563945144;39903.60096399115;40048.79975600103;40978.59021409867;41003.38494922638;35477.29045419025;36356.272874541835;36621.63378366289;36696.63303366932;37120.628793711716;33324.50013249851;37257.441138382404;38307;38434;39122.217555649295;34166.487502687974;37909;37987;38178.80910595429;38481;29534.409311814074;30785.384292314477;30880.53679194769;31276.687233128287;31734;25797;26324.21027369182;26553;26567.734322656524;26917.327066823487 10:43:34 INFO - 2016-02-24 10:43:34,648 DEBUG : BROWSER_OUTPUT: |2;jquery.html;372.8061408067805;375.3113037576048;376.27379158224613;376.89070169650796;378.09446375929633;15225.390984360612;17305.82694173058;17323.65352692947;19020.90489547552;19423.611527769437;11529.711757206074;11707.414629268545;11711.824322635146;18913.621727565474;18994.905025474854;3888.358420860555;5056.620753443497;5164.664296820713;5165.9225111623355;5167.224916262553;3991.42124391963;5467.45325467452;5556.499915007648;5565.554755619561;5600.27196464459;5498.917516237239;5531.640443371168;5585.078462053756;5587.3015873015875;5710.77156913723;6259.968700156515;6263.592866463688;6308.779192728232;6310.274318453391;6313.905291420632;5475.370332411784;5602.887942241132;5606.187102870102;5632.5212356949705;5641.210230567724;12544;12925.547605833845;12931.870681293161;22227.110915563357;22330;2219.8668079915096;2226.899789509476;2230.152542034033;2235.35174799308;2254.6505291679814;9465.621375144987;9759.707208783748;9918.05778451046;9949.154321882648;11596.942015289955;1942.5143714071482;1971.5169783403014;1990.2934458267396;1992.2728204205362;1994.5911088226878;64688;66760.99858502127;67637.66181169111;68345;68918.31081689219;10795.40625265618;10806.297590656583;10859.51132199037;10863.130949524018;10870.673879783619;14504.637384065483;14582.4896128635;14590.85409145895;14604.853951460562;14617.634559136106;7145.249748776304;7230.13238411394;7283.562986220843;7284.235155308616;7302.415806735448;6779.93220067793;6816.829579260459;6823.078884350549;6895.4138898194215;6903.585784852925;4185.937210941838;4566.474855391606;4584.15193189261;4597.563231493003;4666.393368862094;1732.3850033238018;1773.9201735921915;1808.5117018404958;1825.0600940515644;1861.2834058887427;919.3702313914989;925.3522534226069;957.9856302155473;999.2505620784411;1080.9945950270198;14651.194184319751;16466.506004819876;16648.417305394254;17729.202185901664;18419.815801842076;989.1542730965065;1009.3287963504188;1011.716719318592;1021.5862575656871;1068.3803394031443;10109.73628296463;10263.794724105475;10287.537060832281;10288.2798204125;10333.379997200193;640069;653096;659570;665869;676550;82425.58787206146;84683.15316846753;84980;86319;88591;1152.4525850221078;1158.9072874170215;1165.935873526964;1168.8831116888414;1169.7601991591873;15478.9226053869;15849.445269415515;16250.756238656666;16394.918025410272;16517;33562.83218583892;33625.49561756527;34364.82817585896;34759.4786078204;35220.64779352173;45146;47352;47755.28367074566;48925.75537122291;49570.50429495803;10022.3485473444;10057.245706572183;10159.949200253952;10209.74475638115;10336.224783141144;11972.16194866351;12087.51649934028;12148.878511214774;12217.144799863925;17326.91336543309;15480.225988700295;15481.690366192388;15506;15609.297581608627;15642.687146257238;11484.82772758426;11502.367369794407;15091.849081509483;15450.30473628667;15493.07041577509;14610.342534586182;15043.924780376028;15313;15322.923385383001;15494.84505154934;9729.951350243204;9823.852642210515;9872.85190722154;9975.45135017552;10003.949980250052;10573.418461984666;10635.14918806509;13810.93094534521;15535.300911458786;15666.37334506607 10:43:34 INFO - 2016-02-24 10:43:34,648 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1457.9708405831877;1750.7723995880533;1793.9999999999995;1832.28540869061;1851.0282101896494;7636.885446718296;8065.354771618278;8088.878666820003;8095.028596568405;8098.878516822245;7613.961930190355;7651.579163146039;7661.885071723915;7668.079830420357;7670.92329076709;2291.644795056769;2297.241910169644;2300.804431623314;2337.9532409351805;2338.53229354129;2308.5267520158327;2330.627099664054;2330.8601483910934;2333.871637059961;2335.381124002141;1961.1959096770327;1991.3030439346185;2142.528643698384;2272.715910511186;2323.8721870297127;2295.5064661097863;2298.287530865437;2311.2719493359623;2319.1419174905222;2321.3964369263945;2326.6975293211776;2396.017632770556;2398.784109430142;2399.3761621978238;2402.5435167318155;6549.541532092755;6738.730450781963;7488.063992001;7743.806404839867;7749.612519374009;4625.838095666669;4662.0675864826835;4663.27719203524;4693.48371679115;4695.67130300879;3026.8637911294045;3042.4979878320273;3044.406340763552;3045.4365942300747;3107;1011.22135955314;1026.558579810681;1037.7042542875245;1039.5166247694858;1088.031651829872;10716.35701857891;10785.78428431427;10796.352218866894;10802.891971080267;10814.837777433344;4137.193247316744;4206.348016057516;4266.2960611499375;4267.338562522814;4277.230098582223;9415.811683766286;9420.95289523548;9421.340506164503;9436.433813971184;9438.669646562337;4748.6438517111355;4764.690295130806;4783.904321913612;4787.904241915142;4794.712317260975;4328.199283132568;4355.586219309161;4378.16814805186;4397.032652816375;4398.450193725785;3569.5716514018486;3606.5131207287204;3612.151144481045;3614.367485689994;3642.817859107034;1115.7880002799443;1121.103117505992;1121.9719507012226;1130.0902773267599;1133.518254741748;1341.7249463859885;1383.6056723833658;1391.3669280477359;1460.6348412896775;1462.6782107936237;7640.885386719204;7752.728654497066;7783.4162437817395;7788.610569471503;7789.454738168274;1103.172620534599;1145.6333973128685;1200.153891506486;1204.078879657063;1208.0818577880873;5479.068558345091;6213.440790328892;6263.843403914848;6268.874622507525;6271.6550589718;7784.2994130527395;7798.649060792278;7857.489263197874;7889.408294377945;7900.881486777703;8975.865362019575;9184.081591840762;9441.197498212592;9450.054994500495;9453.480058596706;7493.138289096823;7578.886316705364;7599.278068583476;7635.121960974448;7637.236276372319;7586.658600363107;7640.426967977312;7649.464537482323;7695;7697.114831794415;8337.916620833714;8466.153384661484;8574.78563035929;8600.78498037554;8715.912840871764;8625.309975201842;8858.379913406;8876.023637399752;8878.11218878107;9001.909980900107;6767.830804229934;6854.10896583441;6855.108835851507;6858.451323894176;6860.382565569123;7260.201377848538;7308.232635573401;7733.419993500623;7751.612419379121;7811.531308121531;7211.3870321023305;7218.314260145278;7243.20324764286;7251.56490610565;7357.0803649543805;7022.648867556704;7064.894026589503;7089.361957423856;7177.7487787927175;7321.78034658961;7147.070880785464;7187.496875218893;7215.098112735908;7217.025701530436;7236.457265704988;6745.25802161752;6754.932450675627;6807.217170025312;6848.212455567674;6855.5886646801355;6834.384905358542;6865;6880.862382752217;6882.242953275236;6884.483663725341 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: |4;prototype.html;329.2131804986084;342.40250762419583;348.4703251058392;350.3973166154214;358.96948759355456;5915.911261331073;6039.637621742699;6051.062085376768;6095.664738439384;6156.7229474673695;5822.214001109849;5953.970230148854;5981.371955944628;5991;5998.1602575639445;4637.397138372007;4644.256918892977;4648.302754586805;4650.534946505356;4676.766161691919;4372.562743725618;4465.575770301816;4570.840020599263;4693.084848454553;4697.530246975292;4639.234526303156;4645.419322584677;4658.394408726861;4666.650001249903;4667.6032537234205;4640.443146822403;4643.233866412038;4646.9070618587775;4652.720836749806;4668;4110.91778164438;4112;4114.156597897424;4117.8970525737095;4119.134981653856;5683.062294721366;5761.0494268445655;5762.683052432115;5772.740226689767;5784.652920824764;5445.727713614304;5446.537044351235;5456.345238571357;5456.599096397573;5461.808836690697;6116.571839971204;6420.133282006963;6420.903686444707;6421.133147025139;6427.357264273582;4295.334223195378;4455.1757924784015;4516.503184649686;4561.977190114028;4568.428946381702;6623.801285961429;6635.635040072794;6651.634160121192;6659.101021362151;6659.700313485905;4657.604103651228;4661.440627124767;4668.766561671903;4675.275332323496;4676.719396836201;4688.46082700487;4715.033418149271;4729.763511824463;4737.336772851803;4747.952520474751;5427.3215848019;5434.945650543444;5435.701036443033;5438.293021907127;5440.591955603346;6468.482521398277;6478.805635830932;6480.773172938924;6481.546291759626;6486.6756662167645;1655.3709590355588;1674.556242595713;1674.790651168604;1675.388483203622;1681.3106626283165;1085.6417382263835;1105.2263415609107;1111.8832522585012;1162.8372027916098;1167.2762887010108;6175.104609831549;6362.522810789209;6371.904421433682;6372.1716176897635;6378.617282962944;6635.369639884203;6642.634655094016;6643.933560664428;6648.734050638017;6648.900266496006;6226.1905952226825;6281.591696539802;6284.183056202664;6296.496280297657;6307.117003619497;3625.510556074949;3661.6704496595435;3667.6882464990776;3670.889873303805;3671.155634204148;7033.718651253893;7051.1891132519395;7059.2234854166;7059.294070593002;7060.293970603001;6844.794656160323;6915.377616014583;6953.756618518429;6955.478339124485;6956.65216739161;6998.160220773438;7034.2262351141335;7043;7048.541844780073;7056.470764692669;6511.576747511586;6632.90050649231;6654.600723956578;6767.35710107539;6770.593764374154;6667.699953502201;6686;6690.464762818866;6694.163229596301;6704.865902682017;7013.614251216232;7019.719211231493;7025.437964962688;7028.016077749017;7028.332308430691;4546.74992875395;4582.106489234568;4598.977005115087;4614.561616646413;4644.7213167210075;6684.398404143651;6725.697343619451;6734.797956061326;6737.898931516129;6743.629100399525;6559.212894452506;6560.048792925095;6576.967115164393;6578.868422631424;6580.473562114924;6422.486201103807;6459;6553.737850485927;6554.246261679968;6555.410013098844;6426;6444.8388790280615;6448.161738973903;6453.741850325934;6464.41820236181;6582.670866456754;6586.80239592813;6588.110605068446;6595.538312318284;6596.043573681885;6262.15460912771;6420.807375778734;6440.742370305135;6468.676566171766;6479.449246814073 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: |5;yui.html;772.5480593852596;798.4690181029615;853.5262929074365;903.8237543678981;933.6685476655791;13958.16251024938;14360.138391696479;14373.856261437382;14391.136531808073;14411.351489182985;13821.308934553283;13829;13851.238181899991;13892.305384730773;13908.304584770747;704.9647517624111;719.8128486593471;890.9064548222441;899.3524662243207;899.6626265150569;894.0344428017758;894.1728900766798;897.2821742605889;900.4507250577143;901.0133903375793;747.6934456872683;760.7907825348018;928.8560273157672;939.4973689076346;941.8587211918233;900.3112618846587;913.1872633356318;919.3977944446365;920.2637889688223;920.4799288402098;674.322306082389;682.6620822692798;683.8016975077271;684.0730809752813;684.3669605614837;13154;13371.465141394332;13378.531751388751;13430.19418834873;13460.461581536727;11451.94274028633;13194.802077968838;13230.544640089582;13318.400671969785;13362.064655474122;10800.891991080067;10994.945025274823;11678.824817627743;12014.879851201464;12140.817887731691;3921.784301863396;3986.681065514752;3991.600839916014;4010.218007488541;4027.0335119571387;50517.74741126271;50657;50759.238611420486;50957.49042509602;50978.49021509774;10269.127124194527;10279.42824834094;10607.363558186533;10676.199285053497;10729.678109656723;18653.347132850555;19535.316263930978;19909.701354479694;19940.800591993895;19951.102200401016;7745.302922736981;7783.4162437817395;7850.293473587404;7962.7213047543055;8137.104918458965;4560.88597785058;4570.634349252052;4608.124456353282;4699.318598803155;4724.763761811964;3234.7897386669792;3242.821644809511;3258.283177700902;3268.2319654881085;3268.7875288106197;581.65100939436;599.053495477145;601.7141857617596;601.9036954087325;607.6840043177612;685.9485538584527;692.5290802254415;706.7243774927782;708.2846325211574;715.0561259137888;8816.911830881738;9193.49435781025;9262.120098590623;9270.165685088374;9412.811743765224;519.7011718262014;603.408659513679;636.3572791480638;699.1575151942;714.6962540920089;4381.518033016366;4514.683972121986;4577.633789296849;4656.767161641905;4696.788644511005;53800.73099634477;61357.38642613606;62913.37086629167;63172;63393.683031584544;54850.72574637101;55064.72467637636;55335.44664553383;56160.71919640457;56207;48282.51717482851;48414;48899;49608.75195623999;50522.49477505178;8791.604377802887;8840.469571825712;8877.289816814768;9049.864252036356;9157.17585417289;37094.07264818401;37106.628933710315;37702.62297376991;37768.244635106596;37985.4302185473;44121.33817992797;44280.335794962455;46907.76546117248;48363.27455088247;48738.756306218245;11406.600768973047;11702.531898723955;11813.291202527533;11947.402629868646;11950.103742219195;12626.116171868249;12639.494420223087;12770;12912.548060817826;13688.041837071309;12043.397830108284;12512.43694033789;12572.937135314265;12717.872821271669;12753.744925101631;11916.940415297868;12148.817767733315;12426.81359779586;12454;12791.616251512469;12400.81398779001;12572.371381430708;12596.622101336974;12674.112812103065;12749;10693.984071513194;10808.729781755204;11026.669199924016;11030.724231894266;11096.61161859331;9964.551595178089;10355.792884142425;10478;10652.73368165771;10692.732681683021 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,768 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:43:34 INFO - 2016-02-24 10:43:34,649 DEBUG : BROWSER_OUTPUT: __startTimestamp1456339414639__endTimestamp 10:43:34 INFO - 2016-02-24 10:43:34,650 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5434.23 Median:5664.89 stddev:600.79 (10.6%) stddev-sans-first:578.36 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: Values: 3279.1 3315.6 3335.8 3342.9 3365.6 5441.5 5447.2 5451.4 5464.0 5475.4 5447.7 5450.5 5456.5 5466.6 5475.5 4793.2 4857.6 4948.4 4990.2 5009.2 4856.3 4883.8 4926.0 4978.9 5000.4 4919.5 4947.6 4953.0 4959.6 4987.9 4895.4 4908.0 4934.0 4960.3 4974.8 4766.7 4792.7 4871.0 4908.1 4909.3 5246.2 5257.8 5261.6 5288.5 5356.3 5763.7 5775.0 5778.5 5785.3 5794.0 5676.1 5794.8 5840.4 5866.7 5885.9 5546.5 5546.7 5547.3 5549.8 5554.7 5979.6 5987.0 5987.8 5992.4 5994.1 5585.1 5664.9 5685.2 5780.0 5823.0 5854.1 5859.9 5865.9 5877.3 5913.7 5844.0 5851.3 5852.4 5853.2 5858.2 5622.7 5704.2 5788.7 5804.5 5827.7 4516.1 4525.7 4526.5 4536.2 4556.0 3943.4 4081.3 4093.3 4094.7 4110.6 5438.0 5517.3 5611.9 5665.3 5666.0 5887.5 5928.9 5931.8 5932.7 5934.7 5326.1 5609.3 5624.4 5625.5 5628.5 5004.1 5036.7 5077.2 5103.1 5111.8 6069.3 6082.8 6083.0 6084.6 6088.0 5647.8 5695.1 5916.2 5921.4 5922.2 5993.9 6117.8 6123.1 6127.8 6132.1 5769.8 5815.8 5824.5 5824.8 5850.7 5645.9 5703.9 5756.7 5902.8 5920.5 5768.6 5770.9 5771.2 5772.1 5853.7 5720.8 5724.5 5736.1 5740.9 5742.2 5414.8 5521.5 5613.1 5684.1 5729.6 5744.5 5778.2 5781.7 5802.4 5803.8 5644.1 5656.9 5684.3 5696.2 5707.2 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:44002.71 Median:31505.34 stddev:67696.53 (214.9%) stddev-sans-first:67808.03 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: Values: 610.0 645.8 655.6 667.4 703.5 30306.0 40229.2 40658.8 40918.4 41172.8 45513.0 45965.5 46015.5 46044.0 46102.5 8001.6 8135.6 9124.4 9308.8 9319.0 10798.0 11011.3 11037.5 11045.5 11051.6 10773.7 10893.5 10899.0 10902.4 10903.4 10730.1 10850.2 10854.9 10861.3 10862.6 10449.1 10517.6 10571.7 10578.8 10584.9 41358.6 41939.4 41954.6 42026.0 42063.6 3354.9 3391.2 3426.8 3435.1 3499.4 32392.4 32594.2 32721.8 32739.0 32836.5 4310.7 4349.5 4378.0 4399.2 4466.5 297989.0 298153.0 298898.0 299417.0 301312.0 53421.7 54108.2 59097.7 61187.0 61567.7 60088.7 60450.0 60882.0 60924.4 61091.0 14934.2 15055.5 15060.7 15071.2 15092.9 11744.0 11777.3 11812.1 11839.7 11885.3 19720.6 20062.2 20084.0 20139.0 20226.8 8250.1 8253.1 8288.9 8342.9 8375.8 1022.5 1090.5 1153.5 1223.6 1229.5 28884.4 29495.4 30301.0 30392.8 30397.5 1127.7 1167.6 1237.3 1246.3 1275.6 13474.4 15704.5 15711.3 16002.5 16311.3 259305.0 261381.0 261715.0 261857.0 262448.0 49105.3 49298.3 49547.0 49627.8 49708.5 1453.2 1462.8 1479.3 1487.5 1489.7 28897.6 29818.3 30011.0 30239.7 30776.4 45517.8 45622.0 45897.3 45918.3 45974.5 211892.0 214465.0 214909.0 215624.0 215798.0 28341.1 33674.8 33696.3 33910.0 33986.2 39443.6 39903.6 40048.8 40978.6 41003.4 35477.3 36356.3 36621.6 36696.6 37120.6 33324.5 37257.4 38307.0 38434.0 39122.2 34166.5 37909.0 37987.0 38178.8 38481.0 29534.4 30785.4 30880.5 31276.7 31734.0 25797.0 26324.2 26553.0 26567.7 26917.3 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,651 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32443.46 Median:12223.49 stddev:107705.11 (881.1%) stddev-sans-first:107980.32 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: Values: 372.8 375.3 376.3 376.9 378.1 15225.4 17305.8 17323.7 19020.9 19423.6 11529.7 11707.4 11711.8 18913.6 18994.9 3888.4 5056.6 5164.7 5165.9 5167.2 3991.4 5467.5 5556.5 5565.6 5600.3 5498.9 5531.6 5585.1 5587.3 5710.8 6260.0 6263.6 6308.8 6310.3 6313.9 5475.4 5602.9 5606.2 5632.5 5641.2 12544.0 12925.5 12931.9 22227.1 22330.0 2219.9 2226.9 2230.2 2235.4 2254.7 9465.6 9759.7 9918.1 9949.2 11596.9 1942.5 1971.5 1990.3 1992.3 1994.6 64688.0 66761.0 67637.7 68345.0 68918.3 10795.4 10806.3 10859.5 10863.1 10870.7 14504.6 14582.5 14590.9 14604.9 14617.6 7145.2 7230.1 7283.6 7284.2 7302.4 6779.9 6816.8 6823.1 6895.4 6903.6 4185.9 4566.5 4584.2 4597.6 4666.4 1732.4 1773.9 1808.5 1825.1 1861.3 919.4 925.4 958.0 999.3 1081.0 14651.2 16466.5 16648.4 17729.2 18419.8 989.2 1009.3 1011.7 1021.6 1068.4 10109.7 10263.8 10287.5 10288.3 10333.4 640069.0 653096.0 659570.0 665869.0 676550.0 82425.6 84683.2 84980.0 86319.0 88591.0 1152.5 1158.9 1165.9 1168.9 1169.8 15478.9 15849.4 16250.8 16394.9 16517.0 33562.8 33625.5 34364.8 34759.5 35220.6 45146.0 47352.0 47755.3 48925.8 49570.5 10022.3 10057.2 10159.9 10209.7 10336.2 11972.2 12087.5 12148.9 12217.1 17326.9 15480.2 15481.7 15506.0 15609.3 15642.7 11484.8 11502.4 15091.8 15450.3 15493.1 14610.3 15043.9 15313.0 15322.9 15494.8 9730.0 9823.9 9872.9 9975.5 10003.9 10573.4 10635.1 13810.9 15535.3 15666.4 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5530.05 Median:6644.14 stddev:2820.09 (42.4%) stddev-sans-first:2811.39 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: Values: 1458.0 1750.8 1794.0 1832.3 1851.0 7636.9 8065.4 8088.9 8095.0 8098.9 7614.0 7651.6 7661.9 7668.1 7670.9 2291.6 2297.2 2300.8 2338.0 2338.5 2308.5 2330.6 2330.9 2333.9 2335.4 1961.2 1991.3 2142.5 2272.7 2323.9 2295.5 2298.3 2311.3 2319.1 2321.4 2326.7 2396.0 2398.8 2399.4 2402.5 6549.5 6738.7 7488.1 7743.8 7749.6 4625.8 4662.1 4663.3 4693.5 4695.7 3026.9 3042.5 3044.4 3045.4 3107.0 1011.2 1026.6 1037.7 1039.5 1088.0 10716.4 10785.8 10796.4 10802.9 10814.8 4137.2 4206.3 4266.3 4267.3 4277.2 9415.8 9421.0 9421.3 9436.4 9438.7 4748.6 4764.7 4783.9 4787.9 4794.7 4328.2 4355.6 4378.2 4397.0 4398.5 3569.6 3606.5 3612.2 3614.4 3642.8 1115.8 1121.1 1122.0 1130.1 1133.5 1341.7 1383.6 1391.4 1460.6 1462.7 7640.9 7752.7 7783.4 7788.6 7789.5 1103.2 1145.6 1200.2 1204.1 1208.1 5479.1 6213.4 6263.8 6268.9 6271.7 7784.3 7798.6 7857.5 7889.4 7900.9 8975.9 9184.1 9441.2 9450.1 9453.5 7493.1 7578.9 7599.3 7635.1 7637.2 7586.7 7640.4 7649.5 7695.0 7697.1 8337.9 8466.2 8574.8 8600.8 8715.9 8625.3 8858.4 8876.0 8878.1 9001.9 6767.8 6854.1 6855.1 6858.5 6860.4 7260.2 7308.2 7733.4 7751.6 7811.5 7211.4 7218.3 7243.2 7251.6 7357.1 7022.6 7064.9 7089.4 7177.7 7321.8 7147.1 7187.5 7215.1 7217.0 7236.5 6745.3 6754.9 6807.2 6848.2 6855.6 6834.4 6865.0 6880.9 6882.2 6884.5 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5442.75 Median:6165.91 stddev:1641.73 (26.6%) stddev-sans-first:1600.85 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: Values: 329.2 342.4 348.5 350.4 359.0 5915.9 6039.6 6051.1 6095.7 6156.7 5822.2 5954.0 5981.4 5991.0 5998.2 4637.4 4644.3 4648.3 4650.5 4676.8 4372.6 4465.6 4570.8 4693.1 4697.5 4639.2 4645.4 4658.4 4666.7 4667.6 4640.4 4643.2 4646.9 4652.7 4668.0 4110.9 4112.0 4114.2 4117.9 4119.1 5683.1 5761.0 5762.7 5772.7 5784.7 5445.7 5446.5 5456.3 5456.6 5461.8 6116.6 6420.1 6420.9 6421.1 6427.4 4295.3 4455.2 4516.5 4562.0 4568.4 6623.8 6635.6 6651.6 6659.1 6659.7 4657.6 4661.4 4668.8 4675.3 4676.7 4688.5 4715.0 4729.8 4737.3 4748.0 5427.3 5434.9 5435.7 5438.3 5440.6 6468.5 6478.8 6480.8 6481.5 6486.7 1655.4 1674.6 1674.8 1675.4 1681.3 1085.6 1105.2 1111.9 1162.8 1167.3 6175.1 6362.5 6371.9 6372.2 6378.6 6635.4 6642.6 6643.9 6648.7 6648.9 6226.2 6281.6 6284.2 6296.5 6307.1 3625.5 3661.7 3667.7 3670.9 3671.2 7033.7 7051.2 7059.2 7059.3 7060.3 6844.8 6915.4 6953.8 6955.5 6956.7 6998.2 7034.2 7043.0 7048.5 7056.5 6511.6 6632.9 6654.6 6767.4 6770.6 6667.7 6686.0 6690.5 6694.2 6704.9 7013.6 7019.7 7025.4 7028.0 7028.3 4546.7 4582.1 4599.0 4614.6 4644.7 6684.4 6725.7 6734.8 6737.9 6743.6 6559.2 6560.0 6577.0 6578.9 6580.5 6422.5 6459.0 6553.7 6554.2 6555.4 6426.0 6444.8 6448.2 6453.7 6464.4 6582.7 6586.8 6588.1 6595.5 6596.0 6262.2 6420.8 6440.7 6468.7 6479.4 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: 10:43:34 INFO - 2016-02-24 10:43:34,653 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14705.73 Median:4592.88 stddev:16932.45 (368.7%) stddev-sans-first:16947.63 10:43:34 INFO - 2016-02-24 10:43:34,654 DEBUG : BROWSER_OUTPUT: Values: 772.5 798.5 853.5 903.8 933.7 13958.2 14360.1 14373.9 14391.1 14411.4 13821.3 13829.0 13851.2 13892.3 13908.3 705.0 719.8 890.9 899.4 899.7 894.0 894.2 897.3 900.5 901.0 747.7 760.8 928.9 939.5 941.9 900.3 913.2 919.4 920.3 920.5 674.3 682.7 683.8 684.1 684.4 13154.0 13371.5 13378.5 13430.2 13460.5 11451.9 13194.8 13230.5 13318.4 13362.1 10800.9 10994.9 11678.8 12014.9 12140.8 3921.8 3986.7 3991.6 4010.2 4027.0 50517.7 50657.0 50759.2 50957.5 50978.5 10269.1 10279.4 10607.4 10676.2 10729.7 18653.3 19535.3 19909.7 19940.8 19951.1 7745.3 7783.4 7850.3 7962.7 8137.1 4560.9 4570.6 4608.1 4699.3 4724.8 3234.8 3242.8 3258.3 3268.2 3268.8 581.7 599.1 601.7 601.9 607.7 685.9 692.5 706.7 708.3 715.1 8816.9 9193.5 9262.1 9270.2 9412.8 519.7 603.4 636.4 699.2 714.7 4381.5 4514.7 4577.6 4656.8 4696.8 53800.7 61357.4 62913.4 63172.0 63393.7 54850.7 55064.7 55335.4 56160.7 56207.0 48282.5 48414.0 48899.0 49608.8 50522.5 8791.6 8840.5 8877.3 9049.9 9157.2 37094.1 37106.6 37702.6 37768.2 37985.4 44121.3 44280.3 46907.8 48363.3 48738.8 11406.6 11702.5 11813.3 11947.4 11950.1 12626.1 12639.5 12770.0 12912.5 13688.0 12043.4 12512.4 12572.9 12717.9 12753.7 11916.9 12148.8 12426.8 12454.0 12791.6 12400.8 12572.4 12596.6 12674.1 12749.0 10694.0 10808.7 11026.7 11030.7 11096.6 9964.6 10355.8 10478.0 10652.7 10692.7 10:43:34 INFO - 2016-02-24 10:43:34,654 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:43:34 INFO - 2016-02-24 10:43:34,655 DEBUG : BROWSER_OUTPUT: 10:43:35 INFO - 2016-02-24 10:43:35,215 INFO : Browser exited with error code: 0 10:43:35 INFO - 2016-02-24 10:43:35,231 INFO : Completed test dromaeo_css (00:18:30) 10:43:35 INFO - 2016-02-24 10:43:35,231 INFO : Starting test kraken 10:43:35 INFO - 2016-02-24 10:43:35,231 DEBUG : operating with platform_type : linux_ 10:43:35 INFO - 2016-02-24 10:43:35,232 INFO : Initialising browser for kraken test... 10:43:35 INFO - 2016-02-24 10:43:35,238 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaTBG7M/profile http://localhost:38196/getInfo.html 10:43:39 INFO - 2016-02-24 10:43:39,046 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:43:39 INFO - 2016-02-24 10:43:39,046 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:43:39 INFO - 2016-02-24 10:43:39,046 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:43:39 INFO - 2016-02-24 10:43:39,046 DEBUG : BROWSER_OUTPUT: __metrics 10:43:39 INFO - 2016-02-24 10:43:39,195 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:43:40 INFO - 2016-02-24 10:43:40,283 INFO : Browser initialized. 10:43:40 INFO - 2016-02-24 10:43:40,284 INFO : Running cycle 1/1 for kraken test... 10:43:40 INFO - 2016-02-24 10:43:40,284 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaTBG7M/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:43:51 INFO - 2016-02-24 10:43:51,916 DEBUG : BROWSER_OUTPUT: RSS: Main: 132808704 10:43:51 INFO - 2016-02-24 10:43:51,917 DEBUG : BROWSER_OUTPUT: 10:43:54 INFO - 2016-02-24 10:43:54,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38196/tests/kraken/driver.html?testName=audio-beat-detection) 10:43:54 INFO - 2016-02-24 10:43:54,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 166625280 10:43:54 INFO - 2016-02-24 10:43:54,919 DEBUG : BROWSER_OUTPUT: 10:43:57 INFO - 2016-02-24 10:43:57,496 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38196/tests/kraken/driver.html?testName=audio-dft) 10:43:58 INFO - 2016-02-24 10:43:58,057 DEBUG : BROWSER_OUTPUT: RSS: Main: 164364288 10:43:58 INFO - 2016-02-24 10:43:58,058 DEBUG : BROWSER_OUTPUT: 10:44:00 INFO - 2016-02-24 10:44:00,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38196/tests/kraken/driver.html?testName=audio-fft) 10:44:01 INFO - 2016-02-24 10:44:01,389 DEBUG : BROWSER_OUTPUT: RSS: Main: 161095680 10:44:01 INFO - 2016-02-24 10:44:01,389 DEBUG : BROWSER_OUTPUT: 10:44:03 INFO - 2016-02-24 10:44:03,363 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38196/tests/kraken/driver.html?testName=audio-oscillator) 10:44:03 INFO - 2016-02-24 10:44:03,925 DEBUG : BROWSER_OUTPUT: RSS: Main: 159154176 10:44:03 INFO - 2016-02-24 10:44:03,925 DEBUG : BROWSER_OUTPUT: 10:44:06 INFO - 2016-02-24 10:44:06,059 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38196/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:44:06 INFO - 2016-02-24 10:44:06,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 159350784 10:44:06 INFO - 2016-02-24 10:44:06,614 DEBUG : BROWSER_OUTPUT: 10:44:09 INFO - 2016-02-24 10:44:09,438 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38196/tests/kraken/driver.html?testName=imaging-darkroom) 10:44:10 INFO - 2016-02-24 10:44:10,006 DEBUG : BROWSER_OUTPUT: RSS: Main: 159322112 10:44:10 INFO - 2016-02-24 10:44:10,007 DEBUG : BROWSER_OUTPUT: 10:44:13 INFO - 2016-02-24 10:44:13,327 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38196/tests/kraken/driver.html?testName=imaging-desaturate) 10:44:13 INFO - 2016-02-24 10:44:13,900 DEBUG : BROWSER_OUTPUT: RSS: Main: 161583104 10:44:13 INFO - 2016-02-24 10:44:13,901 DEBUG : BROWSER_OUTPUT: 10:44:16 INFO - 2016-02-24 10:44:16,608 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38196/tests/kraken/driver.html?testName=json-parse-financial) 10:44:17 INFO - 2016-02-24 10:44:17,172 DEBUG : BROWSER_OUTPUT: RSS: Main: 160796672 10:44:17 INFO - 2016-02-24 10:44:17,172 DEBUG : BROWSER_OUTPUT: 10:44:18 INFO - 2016-02-24 10:44:18,709 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38196/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:44:19 INFO - 2016-02-24 10:44:19,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 167444480 10:44:19 INFO - 2016-02-24 10:44:19,271 DEBUG : BROWSER_OUTPUT: 10:44:21 INFO - 2016-02-24 10:44:21,061 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:44:21 INFO - 2016-02-24 10:44:21,647 DEBUG : BROWSER_OUTPUT: RSS: Main: 163782656 10:44:21 INFO - 2016-02-24 10:44:21,647 DEBUG : BROWSER_OUTPUT: 10:44:23 INFO - 2016-02-24 10:44:23,651 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:44:24 INFO - 2016-02-24 10:44:24,218 DEBUG : BROWSER_OUTPUT: RSS: Main: 169455616 10:44:24 INFO - 2016-02-24 10:44:24,219 DEBUG : BROWSER_OUTPUT: 10:44:26 INFO - 2016-02-24 10:44:26,586 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:44:27 INFO - 2016-02-24 10:44:27,168 DEBUG : BROWSER_OUTPUT: RSS: Main: 175607808 10:44:27 INFO - 2016-02-24 10:44:27,168 DEBUG : BROWSER_OUTPUT: 10:44:29 INFO - 2016-02-24 10:44:29,664 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:44:30 INFO - 2016-02-24 10:44:30,231 DEBUG : BROWSER_OUTPUT: RSS: Main: 172441600 10:44:30 INFO - 2016-02-24 10:44:30,231 DEBUG : BROWSER_OUTPUT: 10:44:31 INFO - 2016-02-24 10:44:31,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38196/tests/kraken/driver.html?testName=ai-astar) 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 230649856 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;105;110;112;112;101;111;108;109 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;129;146;159;125;150;153;121;169;123 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;161;160;160;160;162;159;160 10:44:32 INFO - 2016-02-24 10:44:32,117 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;71;148;73;74;72;74;154;73 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;140;92;92;93;138;92;93;135 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;146;145;146;145;145;145;146 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;96;95;95;95;96 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;57;56;57;57;56;59;55;56 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;64;65;66;65;64;65;65 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;78;78;81;79;78;85;77;79;80 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;108;116;115;112;119;117;120;121;111;118 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;142;143;145;144;136;143;137;143;137 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;64;63;64;65;65;63;64;65;68 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,857 10:44:32 INFO - 2016-02-24 10:44:32,118 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: __startTimestamp1456339472111__endTimestamp 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.80 Median:110.50 stddev:3.46 (3.1%) stddev-sans-first:3.67 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 105.0 110.0 112.0 112.0 101.0 111.0 108.0 109.0 10:44:32 INFO - 2016-02-24 10:44:32,119 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.10 Median:148.00 stddev:16.65 (11.2%) stddev-sans-first:17.60 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: Values: 146.0 129.0 146.0 159.0 125.0 150.0 153.0 121.0 169.0 123.0 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 161.0 160.0 160.0 160.0 162.0 159.0 160.0 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.20 Median:72.50 stddev:33.15 (45.7%) stddev-sans-first:34.63 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 148.0 73.0 74.0 72.0 74.0 154.0 73.0 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.00 Median:92.00 stddev:21.89 (23.8%) stddev-sans-first:22.62 10:44:32 INFO - 2016-02-24 10:44:32,120 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 140.0 92.0 92.0 93.0 138.0 92.0 93.0 135.0 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 146.0 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.50 stddev:1.08 (1.9%) stddev-sans-first:1.13 10:44:32 INFO - 2016-02-24 10:44:32,121 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 57.0 56.0 57.0 57.0 56.0 59.0 55.0 56.0 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 64.0 65.0 66.0 65.0 64.0 65.0 65.0 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:2.87 (3.6%) stddev-sans-first:2.40 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: Values: 85.0 78.0 78.0 81.0 79.0 78.0 85.0 77.0 79.0 80.0 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.70 Median:117.50 stddev:4.22 (3.6%) stddev-sans-first:3.43 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: Values: 108.0 116.0 115.0 112.0 119.0 117.0 120.0 121.0 111.0 118.0 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.40 Median:143.00 stddev:3.95 (2.8%) stddev-sans-first:3.44 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: Values: 134.0 142.0 143.0 145.0 144.0 136.0 143.0 137.0 143.0 137.0 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:1.49 (2.3%) stddev-sans-first:1.51 10:44:32 INFO - 2016-02-24 10:44:32,122 DEBUG : BROWSER_OUTPUT: Values: 66.0 64.0 63.0 64.0 65.0 65.0 63.0 64.0 65.0 68.0 10:44:32 INFO - 2016-02-24 10:44:32,123 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:44:32 INFO - 2016-02-24 10:44:32,123 DEBUG : BROWSER_OUTPUT: 10:44:32 INFO - 2016-02-24 10:44:32,771 INFO : Browser exited with error code: 0 10:44:32 INFO - 2016-02-24 10:44:32,826 INFO : Completed test kraken (00:00:57) 10:44:32 INFO - 2016-02-24 10:44:32,827 INFO : Starting test v8_7 10:44:32 INFO - 2016-02-24 10:44:32,827 DEBUG : operating with platform_type : linux_ 10:44:32 INFO - 2016-02-24 10:44:32,827 INFO : Initialising browser for v8_7 test... 10:44:32 INFO - 2016-02-24 10:44:32,833 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTRGAbf/profile http://localhost:38196/getInfo.html 10:44:36 INFO - 2016-02-24 10:44:36,918 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:44:36 INFO - 2016-02-24 10:44:36,918 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:44:36 INFO - 2016-02-24 10:44:36,918 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:44:36 INFO - 2016-02-24 10:44:36,918 DEBUG : BROWSER_OUTPUT: __metrics 10:44:37 INFO - 2016-02-24 10:44:37,075 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:44:38 INFO - 2016-02-24 10:44:38,003 INFO : Browser initialized. 10:44:38 INFO - 2016-02-24 10:44:38,004 INFO : Running cycle 1/1 for v8_7 test... 10:44:38 INFO - 2016-02-24 10:44:38,004 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTRGAbf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:44:49 INFO - 2016-02-24 10:44:49,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 132751360 10:44:49 INFO - 2016-02-24 10:44:49,679 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,038 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38196/tests/v8_7/run.html (next: http://localhost:38196/tests/v8_7/run.html) 10:45:11 INFO - 2016-02-24 10:45:11,288 DEBUG : BROWSER_OUTPUT: RSS: Main: 423960576 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: |0;Richards;168.37851490149856 10:45:11 INFO - 2016-02-24 10:45:11,289 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.63615409944023 10:45:11 INFO - 2016-02-24 10:45:11,290 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.6291446085261 10:45:11 INFO - 2016-02-24 10:45:11,290 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 10:45:11 INFO - 2016-02-24 10:45:11,290 DEBUG : BROWSER_OUTPUT: |4;RayTrace;941.6195856873823 10:45:11 INFO - 2016-02-24 10:45:11,290 DEBUG : BROWSER_OUTPUT: |5;Earley;545.5040871934605 10:45:11 INFO - 2016-02-24 10:45:11,290 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 10:45:11 INFO - 2016-02-24 10:45:11,291 DEBUG : BROWSER_OUTPUT: |7;RegExp;24119.04761904762 10:45:11 INFO - 2016-02-24 10:45:11,291 DEBUG : BROWSER_OUTPUT: |8;Splay;519.7505197505197 10:45:11 INFO - 2016-02-24 10:45:11,291 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 10:45:11 INFO - 2016-02-24 10:45:11,291 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:45:11 INFO - 2016-02-24 10:45:11,291 DEBUG : BROWSER_OUTPUT: __startTimestamp1456339511287__endTimestamp 10:45:11 INFO - 2016-02-24 10:45:11,292 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,292 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:45:11 INFO - 2016-02-24 10:45:11,292 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:45:11 INFO - 2016-02-24 10:45:11,292 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,292 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.38 Median:168.38 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,292 DEBUG : BROWSER_OUTPUT: Values: 168.4 10:45:11 INFO - 2016-02-24 10:45:11,294 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,294 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.64 Median:164.64 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,294 DEBUG : BROWSER_OUTPUT: Values: 164.6 10:45:11 INFO - 2016-02-24 10:45:11,294 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,294 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.63 Median:278.63 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: Values: 278.6 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: Values: 5202.1 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:941.62 Median:941.62 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: Values: 941.6 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:545.50 Median:545.50 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,295 DEBUG : BROWSER_OUTPUT: Values: 545.5 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: Values: 10526.3 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24119.05 Median:24119.05 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: Values: 24119.0 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:519.75 Median:519.75 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: Values: 519.8 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 10:45:11 INFO - 2016-02-24 10:45:11,296 DEBUG : BROWSER_OUTPUT: Values: 4822.1 10:45:11 INFO - 2016-02-24 10:45:11,297 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:45:11 INFO - 2016-02-24 10:45:11,297 DEBUG : BROWSER_OUTPUT: 10:45:11 INFO - 2016-02-24 10:45:11,947 INFO : Browser exited with error code: 0 10:45:11 INFO - 2016-02-24 10:45:11,966 INFO : Completed test v8_7 (00:00:39) 10:45:12 INFO - 2016-02-24 10:45:12,302 INFO : Completed test suite (00:20:07) 10:45:12 INFO - 2016-02-24 10:45:12,302 DEBUG : Working with test: dromaeo_css 10:45:12 INFO - 2016-02-24 10:45:12,302 DEBUG : Generating results file: dromaeo_css 10:45:12 INFO - 2016-02-24 10:45:12,303 DEBUG : Working with test: kraken 10:45:12 INFO - 2016-02-24 10:45:12,303 DEBUG : Generating results file: kraken 10:45:12 INFO - 2016-02-24 10:45:12,304 INFO : javascript benchmark 10:45:12 INFO - 2016-02-24 10:45:12,304 DEBUG : Working with test: v8_7 10:45:12 INFO - 2016-02-24 10:45:12,304 DEBUG : Generating results file: v8_7 10:45:12 INFO - 2016-02-24 10:45:12,304 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:45:12 INFO - 2016-02-24 10:45:12,458 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:45:12 INFO - 2016-02-24 10:45:12,458 DEBUG : process_Request line: dromaeo_css 7223.97 graph.html#tests=[[72,53,35]] 10:45:12 INFO - 2016-02-24 10:45:12,458 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:45:12 INFO - 2016-02-24 10:45:12,586 DEBUG : process_Request line: kraken 1477.20 graph.html#tests=[[232,53,35]] 10:45:12 INFO - 2016-02-24 10:45:12,587 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:45:12 INFO - 2016-02-24 10:45:12,718 DEBUG : process_Request line: v8_7 23310.93 graph.html#tests=[[230,53,35]] 10:45:12 INFO - 2016-02-24 10:45:12,720 INFO : javascript benchmark 10:45:12 INFO - 2016-02-24 10:45:12,723 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3279.098247981805, 3315.552400425942, 3335.8498867550957, 3342.9331413371733, 3365.579302587176, 5441.537469315103, 5447.155690867917, 5451.427600101987, 5463.972680136599, 5475.397706252309, 5447.6731396116165, 5450.5367043801225, 5456.48163424475, 5466.562674986001, 5475.534579560742, 4793.185158523042, 4857.635677324197, 4948.406191257045, 4990.1516742153735, 5009.223570346602, 4856.271559266121, 4883.755812209393, 4925.975370123145, 4978.875528111808, 5000.374953130859, 4919.508049195088, 4947.554720075193, 4952.975235123838, 4959.553640172384, 4987.925181122286, 4895.388076490439, 4908.0, 4933.975330123362, 4960.33035540201, 4974.751262436864, 4766.713997160182, 4792.688475249132, 4870.975645121787, 4908.092002979461, 4909.337239472697, 5246.23929530216, 5257.76340064698, 5261.579073674096, 5288.497592728685, 5356.276902618175, 5763.682997435139, 5775.0, 5778.5377169826315, 5785.27684039495, 5794.0, 5676.091825307973, 5794.797182098607, 5840.415958404168, 5866.70666466675, 5885.852853678693, 5546.528545073714, 5546.69493177871, 5547.33431988164, 5549.833504994856, 5554.666719996772, 5979.5814292999075, 5987.0, 5987.760489580368, 5992.400759923973, 5994.100884867305, 5585.106382978704, 5664.8867022659315, 5685.232493613392, 5779.971100144557, 5822.970885145632, 5854.121881717776, 5859.882802344015, 5865.912011319833, 5877.324107727581, 5913.704314784243, 5844.035734103911, 5851.297844258631, 5852.356240813507, 5853.209816674779, 5858.150568167592, 5622.718864056781, 5704.201411802351, 5788.681622510718, 5804.477597016289, 5827.737751801179, 4516.141933032779, 4525.660575456854, 4526.456825180933, 4536.228841097021, 4556.0, 3943.4479172915817, 4081.285774989365, 4093.3246014407887, 4094.6724262058965, 4110.568390319033, 5438.048341540215, 5517.282753242052, 5611.915821262684, 5665.320161580555, 5666.03677374839, 5887.470127688529, 5928.8814223716145, 5931.822045338647, 5932.703364831741, 5934.703264836741, 5326.0945639240645, 5609.326880774252, 5624.437556244343, 5625.521830644359, 5628.465295796893, 5004.099262132851, 5036.748162591855, 5077.1876499759865, 5103.10695628271, 5111.821086261963, 6069.332373438918, 6082.787102451482, 6083.026715725463, 6084.60450070744, 6087.96956015226, 5647.774089036392, 5695.117256825199, 5916.171735956969, 5921.3782552831335, 5922.200502932049, 5993.910091348546, 6117.816465506042, 6123.08153776937, 6127.80043271483, 6132.091946452653, 5769.826905192851, 5815.76736930518, 5824.475797178275, 5824.796132135355, 5850.707464626837, 5645.858853528694, 5703.942960570341, 5756.654600723802, 5902.763889444374, 5920.496757775465, 5768.596198266081, 5770.913436298543, 5771.249737534262, 5772.134179873053, 5853.707314634336, 5720.8283751487525, 5724.4561766632105, 5736.1109028099045, 5740.942590574041, 5742.19609254713, 5414.83755487336, 5521.5306698931045, 5613.101903695552, 5684.147377893349, 5729.598928074995, 5744.540436764965, 5778.191053252464, 5781.682007489629, 5802.361740208658, 5803.7968671096305, 5644.096944489026, 5656.943430565641, 5684.261046063985, 5696.174054761954, 5707.229524014205], "unit": "ms", "name": "dojo.html", "value": 5395.92983316926}, {"lowerIsBetter": false, "replicates": [610.0, 645.8320836582488, 655.5837043477392, 667.4293479075387, 703.5356664601361, 30306.0, 40229.195416091694, 40658.7967060165, 40918.38622420666, 41172.794136029275, 45513.0, 45965.54034459654, 46015.539844601546, 46044.0, 46102.538974610245, 8001.599920004006, 8135.593220338989, 9124.36129470939, 9308.767280817967, 9319.0, 10798.0, 11011.28426652265, 11037.503312350964, 11045.502952367162, 11051.613193538227, 10773.676789696321, 10893.455327233607, 10899.0, 10902.400367979759, 10903.400312982783, 10730.141588672888, 10850.18623603225, 10854.945725271324, 10861.294015888941, 10862.61980830671, 10449.111825494841, 10517.631882884063, 10571.682849514527, 10578.788424231472, 10584.947075264652, 41358.586414135774, 41939.37090943638, 41954.580454195675, 42026.0, 42063.57936420627, 3354.9161270968175, 3391.1522119470133, 3426.760126791101, 3435.12404336895, 3499.422595271777, 32392.352152957046, 32594.194162278363, 32721.836390817894, 32739.0, 32836.507452388236, 4310.698251122422, 4349.456317960255, 4377.978110109461, 4399.208142534342, 4466.4640243171025, 297989.0, 298153.0, 298898.0, 299417.0, 301312.0, 53421.7328913353, 54108.188377174374, 59097.70451147717, 61187.0, 61567.69216153891, 60088.69955650281, 60450.0, 60882.0, 60924.390756092755, 61091.0, 14934.178620175777, 15055.548333549792, 15060.698786024219, 15071.246437678072, 15092.924535377253, 11744.0, 11777.293362398112, 11812.055035597132, 11839.704007399712, 11885.28688278706, 19720.605587888447, 20062.197512099643, 20084.0, 20139.0, 20226.797732022787, 8250.092489826115, 8253.133420990709, 8288.917110828814, 8342.916570834335, 8375.8324833503, 1022.5143057047843, 1090.4820210399996, 1153.454371142673, 1223.5839814462947, 1229.465182645552, 28884.42231155365, 29495.410091798043, 30301.0, 30392.848035759678, 30397.544036839467, 1127.7414504337899, 1167.6030149749126, 1237.2638280223252, 1246.2584762066556, 1275.6173148055545, 13474.393652285671, 15704.528864134094, 15711.335075276844, 16002.519924402286, 16311.347546098023, 259305.0, 261381.0, 261715.0, 261857.0, 262448.0, 49105.263421048716, 49298.26052609214, 49547.0, 49627.75186124046, 49708.50291497111, 1453.157168842069, 1462.7805829125716, 1479.2751551739784, 1487.4719474586582, 1489.7467430536835, 28897.56653650239, 29818.254543636584, 30011.0, 30239.697603023687, 30776.38447230998, 45517.77241113773, 45622.0, 45897.31154032626, 45918.31122533231, 45974.54025459703, 211892.0, 214465.0, 214909.0, 215624.0, 215798.0, 28341.149765507067, 33674.83162584171, 33696.326073478886, 33910.0, 33986.15034624154, 39443.605563945144, 39903.60096399115, 40048.79975600103, 40978.59021409867, 41003.38494922638, 35477.29045419025, 36356.272874541835, 36621.63378366289, 36696.63303366932, 37120.628793711716, 33324.50013249851, 37257.441138382404, 38307.0, 38434.0, 39122.217555649295, 34166.487502687974, 37909.0, 37987.0, 38178.80910595429, 38481.0, 29534.409311814074, 30785.384292314477, 30880.53679194769, 31276.687233128287, 31734.0, 25797.0, 26324.21027369182, 26553.0, 26567.734322656524, 26917.327066823487], "unit": "ms", "name": "ext.html", "value": 19252.653855379456}, {"lowerIsBetter": false, "replicates": [372.8061408067805, 375.3113037576048, 376.27379158224613, 376.89070169650796, 378.09446375929633, 15225.390984360612, 17305.82694173058, 17323.65352692947, 19020.90489547552, 19423.611527769437, 11529.711757206074, 11707.414629268545, 11711.824322635146, 18913.621727565474, 18994.905025474854, 3888.358420860555, 5056.620753443497, 5164.664296820713, 5165.9225111623355, 5167.224916262553, 3991.42124391963, 5467.45325467452, 5556.499915007648, 5565.554755619561, 5600.27196464459, 5498.917516237239, 5531.640443371168, 5585.078462053756, 5587.3015873015875, 5710.77156913723, 6259.968700156515, 6263.592866463688, 6308.779192728232, 6310.274318453391, 6313.905291420632, 5475.370332411784, 5602.887942241132, 5606.187102870102, 5632.5212356949705, 5641.210230567724, 12544.0, 12925.547605833845, 12931.870681293161, 22227.110915563357, 22330.0, 2219.8668079915096, 2226.899789509476, 2230.152542034033, 2235.35174799308, 2254.6505291679814, 9465.621375144987, 9759.707208783748, 9918.05778451046, 9949.154321882648, 11596.942015289955, 1942.5143714071482, 1971.5169783403014, 1990.2934458267396, 1992.2728204205362, 1994.5911088226878, 64688.0, 66760.99858502127, 67637.66181169111, 68345.0, 68918.31081689219, 10795.40625265618, 10806.297590656583, 10859.51132199037, 10863.130949524018, 10870.673879783619, 14504.637384065483, 14582.4896128635, 14590.85409145895, 14604.853951460562, 14617.634559136106, 7145.249748776304, 7230.13238411394, 7283.562986220843, 7284.235155308616, 7302.415806735448, 6779.93220067793, 6816.829579260459, 6823.078884350549, 6895.4138898194215, 6903.585784852925, 4185.937210941838, 4566.474855391606, 4584.15193189261, 4597.563231493003, 4666.393368862094, 1732.3850033238018, 1773.9201735921915, 1808.5117018404958, 1825.0600940515644, 1861.2834058887427, 919.3702313914989, 925.3522534226069, 957.9856302155473, 999.2505620784411, 1080.9945950270198, 14651.194184319751, 16466.506004819876, 16648.417305394254, 17729.202185901664, 18419.815801842076, 989.1542730965065, 1009.3287963504188, 1011.716719318592, 1021.5862575656871, 1068.3803394031443, 10109.73628296463, 10263.794724105475, 10287.537060832281, 10288.2798204125, 10333.379997200193, 640069.0, 653096.0, 659570.0, 665869.0, 676550.0, 82425.58787206146, 84683.15316846753, 84980.0, 86319.0, 88591.0, 1152.4525850221078, 1158.9072874170215, 1165.935873526964, 1168.8831116888414, 1169.7601991591873, 15478.9226053869, 15849.445269415515, 16250.756238656666, 16394.918025410272, 16517.0, 33562.83218583892, 33625.49561756527, 34364.82817585896, 34759.4786078204, 35220.64779352173, 45146.0, 47352.0, 47755.28367074566, 48925.75537122291, 49570.50429495803, 10022.3485473444, 10057.245706572183, 10159.949200253952, 10209.74475638115, 10336.224783141144, 11972.16194866351, 12087.51649934028, 12148.878511214774, 12217.144799863925, 17326.91336543309, 15480.225988700295, 15481.690366192388, 15506.0, 15609.297581608627, 15642.687146257238, 11484.82772758426, 11502.367369794407, 15091.849081509483, 15450.30473628667, 15493.07041577509, 14610.342534586182, 15043.924780376028, 15313.0, 15322.923385383001, 15494.84505154934, 9729.951350243204, 9823.852642210515, 9872.85190722154, 9975.45135017552, 10003.949980250052, 10573.418461984666, 10635.14918806509, 13810.93094534521, 15535.300911458786, 15666.37334506607], "unit": "ms", "name": "jquery.html", "value": 9038.320318023843}, {"lowerIsBetter": false, "replicates": [1457.9708405831877, 1750.7723995880533, 1793.9999999999995, 1832.28540869061, 1851.0282101896494, 7636.885446718296, 8065.354771618278, 8088.878666820003, 8095.028596568405, 8098.878516822245, 7613.961930190355, 7651.579163146039, 7661.885071723915, 7668.079830420357, 7670.92329076709, 2291.644795056769, 2297.241910169644, 2300.804431623314, 2337.9532409351805, 2338.53229354129, 2308.5267520158327, 2330.627099664054, 2330.8601483910934, 2333.871637059961, 2335.381124002141, 1961.1959096770327, 1991.3030439346185, 2142.528643698384, 2272.715910511186, 2323.8721870297127, 2295.5064661097863, 2298.287530865437, 2311.2719493359623, 2319.1419174905222, 2321.3964369263945, 2326.6975293211776, 2396.017632770556, 2398.784109430142, 2399.3761621978238, 2402.5435167318155, 6549.541532092755, 6738.730450781963, 7488.063992001, 7743.806404839867, 7749.612519374009, 4625.838095666669, 4662.0675864826835, 4663.27719203524, 4693.48371679115, 4695.67130300879, 3026.8637911294045, 3042.4979878320273, 3044.406340763552, 3045.4365942300747, 3107.0, 1011.22135955314, 1026.558579810681, 1037.7042542875245, 1039.5166247694858, 1088.031651829872, 10716.35701857891, 10785.78428431427, 10796.352218866894, 10802.891971080267, 10814.837777433344, 4137.193247316744, 4206.348016057516, 4266.2960611499375, 4267.338562522814, 4277.230098582223, 9415.811683766286, 9420.95289523548, 9421.340506164503, 9436.433813971184, 9438.669646562337, 4748.6438517111355, 4764.690295130806, 4783.904321913612, 4787.904241915142, 4794.712317260975, 4328.199283132568, 4355.586219309161, 4378.16814805186, 4397.032652816375, 4398.450193725785, 3569.5716514018486, 3606.5131207287204, 3612.151144481045, 3614.367485689994, 3642.817859107034, 1115.7880002799443, 1121.103117505992, 1121.9719507012226, 1130.0902773267599, 1133.518254741748, 1341.7249463859885, 1383.6056723833658, 1391.3669280477359, 1460.6348412896775, 1462.6782107936237, 7640.885386719204, 7752.728654497066, 7783.4162437817395, 7788.610569471503, 7789.454738168274, 1103.172620534599, 1145.6333973128685, 1200.153891506486, 1204.078879657063, 1208.0818577880873, 5479.068558345091, 6213.440790328892, 6263.843403914848, 6268.874622507525, 6271.6550589718, 7784.2994130527395, 7798.649060792278, 7857.489263197874, 7889.408294377945, 7900.881486777703, 8975.865362019575, 9184.081591840762, 9441.197498212592, 9450.054994500495, 9453.480058596706, 7493.138289096823, 7578.886316705364, 7599.278068583476, 7635.121960974448, 7637.236276372319, 7586.658600363107, 7640.426967977312, 7649.464537482323, 7695.0, 7697.114831794415, 8337.916620833714, 8466.153384661484, 8574.78563035929, 8600.78498037554, 8715.912840871764, 8625.309975201842, 8858.379913406, 8876.023637399752, 8878.11218878107, 9001.909980900107, 6767.830804229934, 6854.10896583441, 6855.108835851507, 6858.451323894176, 6860.382565569123, 7260.201377848538, 7308.232635573401, 7733.419993500623, 7751.612419379121, 7811.531308121531, 7211.3870321023305, 7218.314260145278, 7243.20324764286, 7251.56490610565, 7357.0803649543805, 7022.648867556704, 7064.894026589503, 7089.361957423856, 7177.7487787927175, 7321.78034658961, 7147.070880785464, 7187.496875218893, 7215.098112735908, 7217.025701530436, 7236.457265704988, 6745.25802161752, 6754.932450675627, 6807.217170025312, 6848.212455567674, 6855.5886646801355, 6834.384905358542, 6865.0, 6880.862382752217, 6882.242953275236, 6884.483663725341], "unit": "ms", "name": "mootools.html", "value": 4575.625037977103}, {"lowerIsBetter": false, "replicates": [329.2131804986084, 342.40250762419583, 348.4703251058392, 350.3973166154214, 358.96948759355456, 5915.911261331073, 6039.637621742699, 6051.062085376768, 6095.664738439384, 6156.7229474673695, 5822.214001109849, 5953.970230148854, 5981.371955944628, 5991.0, 5998.1602575639445, 4637.397138372007, 4644.256918892977, 4648.302754586805, 4650.534946505356, 4676.766161691919, 4372.562743725618, 4465.575770301816, 4570.840020599263, 4693.084848454553, 4697.530246975292, 4639.234526303156, 4645.419322584677, 4658.394408726861, 4666.650001249903, 4667.6032537234205, 4640.443146822403, 4643.233866412038, 4646.9070618587775, 4652.720836749806, 4668.0, 4110.91778164438, 4112.0, 4114.156597897424, 4117.8970525737095, 4119.134981653856, 5683.062294721366, 5761.0494268445655, 5762.683052432115, 5772.740226689767, 5784.652920824764, 5445.727713614304, 5446.537044351235, 5456.345238571357, 5456.599096397573, 5461.808836690697, 6116.571839971204, 6420.133282006963, 6420.903686444707, 6421.133147025139, 6427.357264273582, 4295.334223195378, 4455.1757924784015, 4516.503184649686, 4561.977190114028, 4568.428946381702, 6623.801285961429, 6635.635040072794, 6651.634160121192, 6659.101021362151, 6659.700313485905, 4657.604103651228, 4661.440627124767, 4668.766561671903, 4675.275332323496, 4676.719396836201, 4688.46082700487, 4715.033418149271, 4729.763511824463, 4737.336772851803, 4747.952520474751, 5427.3215848019, 5434.945650543444, 5435.701036443033, 5438.293021907127, 5440.591955603346, 6468.482521398277, 6478.805635830932, 6480.773172938924, 6481.546291759626, 6486.6756662167645, 1655.3709590355588, 1674.556242595713, 1674.790651168604, 1675.388483203622, 1681.3106626283165, 1085.6417382263835, 1105.2263415609107, 1111.8832522585012, 1162.8372027916098, 1167.2762887010108, 6175.104609831549, 6362.522810789209, 6371.904421433682, 6372.1716176897635, 6378.617282962944, 6635.369639884203, 6642.634655094016, 6643.933560664428, 6648.734050638017, 6648.900266496006, 6226.1905952226825, 6281.591696539802, 6284.183056202664, 6296.496280297657, 6307.117003619497, 3625.510556074949, 3661.6704496595435, 3667.6882464990776, 3670.889873303805, 3671.155634204148, 7033.718651253893, 7051.1891132519395, 7059.2234854166, 7059.294070593002, 7060.293970603001, 6844.794656160323, 6915.377616014583, 6953.756618518429, 6955.478339124485, 6956.65216739161, 6998.160220773438, 7034.2262351141335, 7043.0, 7048.541844780073, 7056.470764692669, 6511.576747511586, 6632.90050649231, 6654.600723956578, 6767.35710107539, 6770.593764374154, 6667.699953502201, 6686.0, 6690.464762818866, 6694.163229596301, 6704.865902682017, 7013.614251216232, 7019.719211231493, 7025.437964962688, 7028.016077749017, 7028.332308430691, 4546.74992875395, 4582.106489234568, 4598.977005115087, 4614.561616646413, 4644.7213167210075, 6684.398404143651, 6725.697343619451, 6734.797956061326, 6737.898931516129, 6743.629100399525, 6559.212894452506, 6560.048792925095, 6576.967115164393, 6578.868422631424, 6580.473562114924, 6422.486201103807, 6459.0, 6553.737850485927, 6554.246261679968, 6555.410013098844, 6426.0, 6444.8388790280615, 6448.161738973903, 6453.741850325934, 6464.41820236181, 6582.670866456754, 6586.80239592813, 6588.110605068446, 6595.538312318284, 6596.043573681885, 6262.15460912771, 6420.807375778734, 6440.742370305135, 6468.676566171766, 6479.449246814073], "unit": "ms", "name": "prototype.html", "value": 4912.219493962782}, {"lowerIsBetter": false, "replicates": [772.5480593852596, 798.4690181029615, 853.5262929074365, 903.8237543678981, 933.6685476655791, 13958.16251024938, 14360.138391696479, 14373.856261437382, 14391.136531808073, 14411.351489182985, 13821.308934553283, 13829.0, 13851.238181899991, 13892.305384730773, 13908.304584770747, 704.9647517624111, 719.8128486593471, 890.9064548222441, 899.3524662243207, 899.6626265150569, 894.0344428017758, 894.1728900766798, 897.2821742605889, 900.4507250577143, 901.0133903375793, 747.6934456872683, 760.7907825348018, 928.8560273157672, 939.4973689076346, 941.8587211918233, 900.3112618846587, 913.1872633356318, 919.3977944446365, 920.2637889688223, 920.4799288402098, 674.322306082389, 682.6620822692798, 683.8016975077271, 684.0730809752813, 684.3669605614837, 13154.0, 13371.465141394332, 13378.531751388751, 13430.19418834873, 13460.461581536727, 11451.94274028633, 13194.802077968838, 13230.544640089582, 13318.400671969785, 13362.064655474122, 10800.891991080067, 10994.945025274823, 11678.824817627743, 12014.879851201464, 12140.817887731691, 3921.784301863396, 3986.681065514752, 3991.600839916014, 4010.218007488541, 4027.0335119571387, 50517.74741126271, 50657.0, 50759.238611420486, 50957.49042509602, 50978.49021509774, 10269.127124194527, 10279.42824834094, 10607.363558186533, 10676.199285053497, 10729.678109656723, 18653.347132850555, 19535.316263930978, 19909.701354479694, 19940.800591993895, 19951.102200401016, 7745.302922736981, 7783.4162437817395, 7850.293473587404, 7962.7213047543055, 8137.104918458965, 4560.88597785058, 4570.634349252052, 4608.124456353282, 4699.318598803155, 4724.763761811964, 3234.7897386669792, 3242.821644809511, 3258.283177700902, 3268.2319654881085, 3268.7875288106197, 581.65100939436, 599.053495477145, 601.7141857617596, 601.9036954087325, 607.6840043177612, 685.9485538584527, 692.5290802254415, 706.7243774927782, 708.2846325211574, 715.0561259137888, 8816.911830881738, 9193.49435781025, 9262.120098590623, 9270.165685088374, 9412.811743765224, 519.7011718262014, 603.408659513679, 636.3572791480638, 699.1575151942, 714.6962540920089, 4381.518033016366, 4514.683972121986, 4577.633789296849, 4656.767161641905, 4696.788644511005, 53800.73099634477, 61357.38642613606, 62913.37086629167, 63172.0, 63393.683031584544, 54850.72574637101, 55064.72467637636, 55335.44664553383, 56160.71919640457, 56207.0, 48282.51717482851, 48414.0, 48899.0, 49608.75195623999, 50522.49477505178, 8791.604377802887, 8840.469571825712, 8877.289816814768, 9049.864252036356, 9157.17585417289, 37094.07264818401, 37106.628933710315, 37702.62297376991, 37768.244635106596, 37985.4302185473, 44121.33817992797, 44280.335794962455, 46907.76546117248, 48363.27455088247, 48738.756306218245, 11406.600768973047, 11702.531898723955, 11813.291202527533, 11947.402629868646, 11950.103742219195, 12626.116171868249, 12639.494420223087, 12770.0, 12912.548060817826, 13688.041837071309, 12043.397830108284, 12512.43694033789, 12572.937135314265, 12717.872821271669, 12753.744925101631, 11916.940415297868, 12148.817767733315, 12426.81359779586, 12454.0, 12791.616251512469, 12400.81398779001, 12572.371381430708, 12596.622101336974, 12674.112812103065, 12749.0, 10693.984071513194, 10808.729781755204, 11026.669199924016, 11030.724231894266, 11096.61161859331, 9964.551595178089, 10355.792884142425, 10478.0, 10652.73368165771, 10692.732681683021], "unit": "ms", "name": "yui.html", "value": 6733.550136442801}], "name": "dromaeo_css", "value": 7223.968635980036}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 105.0, 110.0, 112.0, 112.0, 101.0, 111.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.8}, {"lowerIsBetter": true, "replicates": [146.0, 129.0, 146.0, 159.0, 125.0, 150.0, 153.0, 121.0, 169.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.1}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 161.0, 160.0, 160.0, 160.0, 162.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 148.0, 73.0, 74.0, 72.0, 74.0, 154.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 88.2}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 140.0, 92.0, 92.0, 93.0, 138.0, 92.0, 93.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 106.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 57.0, 56.0, 57.0, 57.0, 56.0, 59.0, 55.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 66.0, 65.0, 64.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.7}, {"lowerIsBetter": true, "replicates": [85.0, 78.0, 78.0, 81.0, 79.0, 78.0, 85.0, 77.0, 79.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.0}, {"lowerIsBetter": true, "replicates": [108.0, 116.0, 115.0, 112.0, 119.0, 117.0, 120.0, 121.0, 111.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.7}, {"lowerIsBetter": true, "replicates": [134.0, 142.0, 143.0, 145.0, 144.0, 136.0, 143.0, 137.0, 143.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.4}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 63.0, 64.0, 65.0, 65.0, 63.0, 64.0, 65.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.7}], "name": "kraken", "value": 1477.2000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.37851490149856], "unit": "score", "name": "Richards", "value": 209.65857799999998}, {"lowerIsBetter": false, "replicates": [164.63615409944023], "unit": "score", "name": "DeltaBlue", "value": 401.60073199999994}, {"lowerIsBetter": false, "replicates": [278.6291446085261], "unit": "score", "name": "Encrypt", "value": 955.3236090000001}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [941.6195856873823], "unit": "score", "name": "RayTrace", "value": 785.8683179999997}, {"lowerIsBetter": false, "replicates": [545.5040871934605], "unit": "score", "name": "Earley", "value": 1221.7378671328672}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24119.04761904762], "unit": "score", "name": "RegExp", "value": 37.77035538005923}, {"lowerIsBetter": false, "replicates": [519.7505197505197], "unit": "score", "name": "Splay", "value": 156.78868399999996}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23310.929806096716}]} 10:45:12 INFO - RETURN: dromaeo_css: 7223.97 10:45:12 INFO - RETURN: kraken: 1477.20 10:45:12 INFO - RETURN: v8_7: 23310.93 10:45:12 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23310.93"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1477.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7223.97"}}} 10:45:12 INFO - Return code: 0 10:45:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:45:12 INFO - # TBPL SUCCESS # 10:45:12 INFO - Running post-action listener: _resource_record_post_action 10:45:12 INFO - Running post-run listener: _resource_record_post_run 10:45:13 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 315392; Write bytes: 411074560; Read time: 56; Write time: 464028 10:45:13 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 77848576; Read time: 0; Write time: 120164 10:45:13 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 315392; Write bytes: 265658368; Read time: 56; Write time: 212388 10:45:13 INFO - Running post-run listener: _upload_blobber_files 10:45:13 INFO - Blob upload gear active. 10:45:13 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:45:13 INFO - Copying logs to upload dir... 10:45:13 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1262.167984 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-02-24 10:45:13.632938) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-24 10:45:13.636118) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010104 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456329226/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-24 10:45:13.688013) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:45:13.688354) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1456338249.902579-1978514003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-24 10:45:13.738675) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-24 10:45:13.739017) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-24 10:45:13.739342) ========= ========= Total master_lag: 0.34 =========