builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-026 starttime: 1454371823.71 results: success (0) buildid: 20160201134231 builduid: eac5788d2f844640996dfc6fb3a859ec revision: 55272f23ad95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.707617) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.708045) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.708359) ========= 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006274 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.730710) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.730994) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.750647) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.750916) ========= 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004568 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.799945) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.800239) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.803634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:23.803954) ========= 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-01 16:10:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 215M=0s 2016-02-01 16:10:25 (215 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.184181 ========= master_lag: 0.14 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:24.132603) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:24.132952) ========= 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016282 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:24.160592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-01 16:10:24.160921) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 55272f23ad95 --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 55272f23ad95 --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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-01 16:10:25,240 Setting DEBUG logging. 2016-02-01 16:10:25,240 attempt 1/10 2016-02-01 16:10:25,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/55272f23ad95?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-01 16:10:26,614 unpacking tar archive at: mozilla-beta-55272f23ad95/testing/mozharness/ program finished with exit code 0 elapsedTime=1.683537 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-01 16:10:25.860415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:25.860814) ========= echo 55272f23ad95 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'55272f23ad95'] 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 55272f23ad95 program finished with exit code 0 elapsedTime=0.004684 script_repo_revision: '55272f23ad95' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:25.911600) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:25.911874) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-01 16:10:25.921375) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-01 16:10:25.921642) ========= /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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:10:27 INFO - MultiFileLogger online at 20160201 16:10:27 in /builds/slave/test-pgo 16:10:27 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 16:10:27 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:10:27 INFO - {'append_to_log': False, 16:10:27 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:10:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:10:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:10:27 INFO - 'branch': 'Mozilla-Beta', 16:10:27 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:27 INFO - 'config_files': ('talos/linux_config.py',), 16:10:27 INFO - 'default_actions': ('clobber', 16:10:27 INFO - 'read-buildbot-config', 16:10:27 INFO - 'download-and-extract', 16:10:27 INFO - 'populate-webroot', 16:10:27 INFO - 'create-virtualenv', 16:10:27 INFO - 'install', 16:10:27 INFO - 'run-tests'), 16:10:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:27 INFO - 'download_minidump_stackwalk': True, 16:10:27 INFO - 'download_symbols': 'ondemand', 16:10:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:27 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:27 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:27 INFO - 'installer_path': 'installer.exe', 16:10:27 INFO - 'log_level': 'info', 16:10:27 INFO - 'log_name': 'talos', 16:10:27 INFO - 'log_to_console': True, 16:10:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:10:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:10:27 INFO - 'opt_config_files': (), 16:10:27 INFO - 'pip_index': False, 16:10:27 INFO - 'sps_profile': False, 16:10:27 INFO - 'sps_profile_interval': 0, 16:10:27 INFO - 'suite': 'dromaeojs', 16:10:27 INFO - 'system_bits': '32', 16:10:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:27 INFO - 'title': 'talos-linux64-ix-026', 16:10:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:27 INFO - 'use_talos_json': True, 16:10:27 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:10:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:27 INFO - 'work_dir': 'build'} 16:10:27 INFO - ##### 16:10:27 INFO - ##### Running clobber step. 16:10:27 INFO - ##### 16:10:27 INFO - Running pre-action listener: _resource_record_pre_action 16:10:27 INFO - Running main action method: clobber 16:10:27 INFO - rmtree: /builds/slave/test-pgo/build 16:10:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:10:28 INFO - Running post-action listener: _resource_record_post_action 16:10:28 INFO - ##### 16:10:28 INFO - ##### Running read-buildbot-config step. 16:10:28 INFO - ##### 16:10:28 INFO - Running pre-action listener: _resource_record_pre_action 16:10:28 INFO - Running main action method: read_buildbot_config 16:10:28 INFO - Using buildbot properties: 16:10:28 INFO - { 16:10:28 INFO - "properties": { 16:10:28 INFO - "buildnumber": 99, 16:10:28 INFO - "product": "firefox", 16:10:28 INFO - "script_repo_revision": "production", 16:10:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:10:28 INFO - "repository": "", 16:10:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:10:28 INFO - "buildid": "20160201134231", 16:10:28 INFO - "slavename": "talos-linux64-ix-026", 16:10:28 INFO - "pgo_build": "True", 16:10:28 INFO - "basedir": "/builds/slave/test-pgo", 16:10:28 INFO - "project": "", 16:10:28 INFO - "platform": "ubuntu64_hw", 16:10:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:10:28 INFO - "slavebuilddir": "test", 16:10:28 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:10:28 INFO - "branch": "mozilla-beta", 16:10:28 INFO - "repo_path": "releases/mozilla-beta", 16:10:28 INFO - "stage_platform": "linux64-pgo", 16:10:28 INFO - "builduid": "eac5788d2f844640996dfc6fb3a859ec", 16:10:28 INFO - "revision": "55272f23ad95" 16:10:28 INFO - }, 16:10:28 INFO - "sourcestamp": { 16:10:28 INFO - "repository": "", 16:10:28 INFO - "hasPatch": false, 16:10:28 INFO - "project": "", 16:10:28 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:10:28 INFO - "changes": [ 16:10:28 INFO - { 16:10:28 INFO - "category": null, 16:10:28 INFO - "files": [ 16:10:28 INFO - { 16:10:28 INFO - "url": null, 16:10:28 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.tar.bz2" 16:10:28 INFO - } 16:10:28 INFO - ], 16:10:28 INFO - "repository": "", 16:10:28 INFO - "rev": "55272f23ad95", 16:10:28 INFO - "who": "sendchange", 16:10:28 INFO - "when": 1454371664, 16:10:28 INFO - "number": 7545661, 16:10:28 INFO - "comments": "Bug 1240985 - Stop returning MsgNotAllowed (r=dvander,a=sylvestre)", 16:10:28 INFO - "project": "", 16:10:28 INFO - "at": "Mon 01 Feb 2016 16:07:44", 16:10:28 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:10:28 INFO - "revlink": "", 16:10:28 INFO - "properties": [ 16:10:28 INFO - [ 16:10:28 INFO - "buildid", 16:10:28 INFO - "20160201134231", 16:10:28 INFO - "Change" 16:10:28 INFO - ], 16:10:28 INFO - [ 16:10:28 INFO - "builduid", 16:10:28 INFO - "eac5788d2f844640996dfc6fb3a859ec", 16:10:28 INFO - "Change" 16:10:28 INFO - ], 16:10:28 INFO - [ 16:10:28 INFO - "pgo_build", 16:10:28 INFO - "True", 16:10:28 INFO - "Change" 16:10:28 INFO - ] 16:10:28 INFO - ], 16:10:28 INFO - "revision": "55272f23ad95" 16:10:28 INFO - } 16:10:28 INFO - ], 16:10:28 INFO - "revision": "55272f23ad95" 16:10:28 INFO - } 16:10:28 INFO - } 16:10:28 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.tar.bz2. 16:10:28 INFO - Running post-action listener: _resource_record_post_action 16:10:28 INFO - ##### 16:10:28 INFO - ##### Running download-and-extract step. 16:10:28 INFO - ##### 16:10:28 INFO - Running pre-action listener: _resource_record_pre_action 16:10:28 INFO - Running main action method: download_and_extract 16:10:28 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/test_packages.json 16:10:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:10:28 INFO - Downloaded 1270 bytes. 16:10:28 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:10:28 INFO - Using the following test package requirements: 16:10:28 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:10:28 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:10:28 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'jsshell-linux-x86_64.zip'], 16:10:28 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:10:28 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:10:28 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 16:10:28 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 16:10:28 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:10:28 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:10:28 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:10:28 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:10:28 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:10:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:28 INFO - Downloaded 21580345 bytes. 16:10:28 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 16:10:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:10:28 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 16:10:29 INFO - Return code: 0 16:10:29 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 16:10: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')]} 16:10:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:10:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:30 INFO - Downloaded 21580345 bytes. 16:10:30 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 16:10:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:10:30 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 16:10:31 INFO - Return code: 0 16:10: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')]} 16:10:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:10:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:31 INFO - Downloaded 11132038 bytes. 16:10:31 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 16:10:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:10:31 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 16:10:31 INFO - Return code: 0 16:10: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')]} 16:10:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:10:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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 16:10:32 INFO - Downloaded 57947090 bytes. 16:10:32 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:10:32 INFO - mkdir: /builds/slave/test-pgo/properties 16:10:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:10:32 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:10:32 INFO - Contents: 16:10:32 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:10:32 INFO - Running post-action listener: _resource_record_post_action 16:10:32 INFO - Running post-action listener: set_extra_try_arguments 16:10:32 INFO - ##### 16:10:32 INFO - ##### Running populate-webroot step. 16:10:32 INFO - ##### 16:10:32 INFO - Running pre-action listener: _resource_record_pre_action 16:10:32 INFO - Running main action method: populate_webroot 16:10:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:32 INFO - '--tppagecycles', 16:10:32 INFO - '7'], 16:10:32 INFO - 'tests': ['tsvgm']}}, 16:10:32 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:10:32 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:32 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:10:32 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:10:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'tests': ['damp', 'tps']}, 16:10:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['damp', 'tps']}, 16:10:32 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:10:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:32 INFO - 'other': {'tests': ['a11yr', 16:10:32 INFO - 'ts_paint', 16:10:32 INFO - 'tpaint', 16:10:32 INFO - 'sessionrestore', 16:10:32 INFO - 'sessionrestore_no_auto_restore']}, 16:10:32 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['a11yr', 16:10:32 INFO - 'ts_paint', 16:10:32 INFO - 'tpaint', 16:10:32 INFO - 'sessionrestore', 16:10:32 INFO - 'sessionrestore_no_auto_restore']}, 16:10:32 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['a11yr', 16:10:32 INFO - 'ts_paint', 16:10:32 INFO - 'tpaint', 16:10:32 INFO - 'sessionrestore', 16:10:32 INFO - 'sessionrestore_no_auto_restore']}, 16:10:32 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['a11yr', 16:10:32 INFO - 'ts_paint', 16:10:32 INFO - 'tpaint', 16:10:32 INFO - 'sessionrestore', 16:10:32 INFO - 'sessionrestore_no_auto_restore']}, 16:10:32 INFO - 'other_l64': {'tests': ['a11yr', 16:10:32 INFO - 'ts_paint', 16:10:32 INFO - 'tpaint', 16:10:32 INFO - 'sessionrestore', 16:10:32 INFO - 'sessionrestore_no_auto_restore']}, 16:10:32 INFO - 'other_nol64': {'tests': ['a11yr', 16:10:32 INFO - 'ts_paint', 16:10:32 INFO - 'tpaint', 16:10:32 INFO - 'sessionrestore', 16:10:32 INFO - 'sessionrestore_no_auto_restore']}, 16:10:32 INFO - 'svgr': {'tests': ['tsvgx', 16:10:32 INFO - 'tsvgr_opacity', 16:10:32 INFO - 'tart', 16:10:32 INFO - 'tscrollx', 16:10:32 INFO - 'cart']}, 16:10:32 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['tsvgx', 16:10:32 INFO - 'tsvgr_opacity', 16:10:32 INFO - 'tart', 16:10:32 INFO - 'tscrollx', 16:10:32 INFO - 'cart']}, 16:10:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'tests': ['tp5o']}, 16:10:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'talos_options': ['--e10s'], 16:10:32 INFO - 'tests': ['tp5o']}, 16:10:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'talos_options': ['--xperf_path', 16:10:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:32 INFO - 'tests': ['tp5n']}, 16:10:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:32 INFO - 'talos_options': ['--e10s', 16:10:32 INFO - '--xperf_path', 16:10:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:32 INFO - 'tests': ['tp5n']}}, 16:10:32 INFO - 'talos.zip': {'path': '', 16:10:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:32 INFO - Running post-action listener: _resource_record_post_action 16:10:32 INFO - ##### 16:10:32 INFO - ##### Running create-virtualenv step. 16:10:32 INFO - ##### 16:10:32 INFO - Running pre-action listener: _resource_record_pre_action 16:10:32 INFO - Running main action method: create_virtualenv 16:10:32 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:10:32 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 16:10:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:10:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:32 INFO - Using real prefix '/usr' 16:10:32 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:10:33 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:35 INFO - Installing pip.................done. 16:10:35 INFO - Return code: 0 16:10:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:35 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 16:10:35 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 16:10:35 INFO - Using env: {'DISPLAY': ':0', 16:10:35 INFO - 'HOME': '/home/cltbld', 16:10:35 INFO - 'LANG': 'en_US.UTF-8', 16:10:35 INFO - 'LANGUAGE': 'en_US:en', 16:10:35 INFO - 'LOGNAME': 'cltbld', 16:10:35 INFO - 'MAIL': '/var/mail/cltbld', 16:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:35 INFO - 'MOZ_NO_REMOTE': '1', 16:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:35 INFO - 'NO_EM_RESTART': '1', 16:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:35 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:35 INFO - 'SHELL': '/bin/bash', 16:10:35 INFO - 'SHLVL': '1', 16:10:35 INFO - 'TERM': 'linux', 16:10:35 INFO - 'TMOUT': '86400', 16:10:35 INFO - 'USER': 'cltbld', 16:10:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:35 INFO - '_': '/tools/buildbot/bin/python'} 16:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:10:35 INFO - Cleaning up... 16:10:35 INFO - Return code: 0 16:10:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:35 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 16:10:35 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 16:10:35 INFO - Using env: {'DISPLAY': ':0', 16:10:35 INFO - 'HOME': '/home/cltbld', 16:10:35 INFO - 'LANG': 'en_US.UTF-8', 16:10:35 INFO - 'LANGUAGE': 'en_US:en', 16:10:35 INFO - 'LOGNAME': 'cltbld', 16:10:35 INFO - 'MAIL': '/var/mail/cltbld', 16:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:35 INFO - 'MOZ_NO_REMOTE': '1', 16:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:35 INFO - 'NO_EM_RESTART': '1', 16:10:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:35 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:35 INFO - 'SHELL': '/bin/bash', 16:10:35 INFO - 'SHLVL': '1', 16:10:35 INFO - 'TERM': 'linux', 16:10:35 INFO - 'TMOUT': '86400', 16:10:35 INFO - 'USER': 'cltbld', 16:10:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:35 INFO - '_': '/tools/buildbot/bin/python'} 16:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:35 INFO - Downloading/unpacking psutil>=0.7.1 16:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:37 INFO - Installing collected packages: psutil 16:10:37 INFO - Running setup.py install for psutil 16:10:37 INFO - building 'psutil._psutil_linux' extension 16:10:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:10:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:10:37 INFO - building 'psutil._psutil_posix' extension 16:10:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:10:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:10:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:38 INFO - Successfully installed psutil 16:10:38 INFO - Cleaning up... 16:10:38 INFO - Return code: 0 16:10:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:38 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:38 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 16:10:38 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 16:10:38 INFO - Using env: {'DISPLAY': ':0', 16:10:38 INFO - 'HOME': '/home/cltbld', 16:10:38 INFO - 'LANG': 'en_US.UTF-8', 16:10:38 INFO - 'LANGUAGE': 'en_US:en', 16:10:38 INFO - 'LOGNAME': 'cltbld', 16:10:38 INFO - 'MAIL': '/var/mail/cltbld', 16:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:38 INFO - 'MOZ_NO_REMOTE': '1', 16:10:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:38 INFO - 'NO_EM_RESTART': '1', 16:10:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:38 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:38 INFO - 'SHELL': '/bin/bash', 16:10:38 INFO - 'SHLVL': '1', 16:10:38 INFO - 'TERM': 'linux', 16:10:38 INFO - 'TMOUT': '86400', 16:10:38 INFO - 'USER': 'cltbld', 16:10:38 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:38 INFO - '_': '/tools/buildbot/bin/python'} 16:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:10:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:10:40 INFO - Installing collected packages: mozsystemmonitor 16:10:40 INFO - Running setup.py install for mozsystemmonitor 16:10:40 INFO - Successfully installed mozsystemmonitor 16:10:40 INFO - Cleaning up... 16:10:40 INFO - Return code: 0 16:10:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:10:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:40 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:40 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 16:10:40 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 16:10:40 INFO - Using env: {'DISPLAY': ':0', 16:10:40 INFO - 'HOME': '/home/cltbld', 16:10:40 INFO - 'LANG': 'en_US.UTF-8', 16:10:40 INFO - 'LANGUAGE': 'en_US:en', 16:10:40 INFO - 'LOGNAME': 'cltbld', 16:10:40 INFO - 'MAIL': '/var/mail/cltbld', 16:10:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:40 INFO - 'MOZ_NO_REMOTE': '1', 16:10:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:40 INFO - 'NO_EM_RESTART': '1', 16:10:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:40 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:40 INFO - 'SHELL': '/bin/bash', 16:10:40 INFO - 'SHLVL': '1', 16:10:40 INFO - 'TERM': 'linux', 16:10:40 INFO - 'TMOUT': '86400', 16:10:40 INFO - 'USER': 'cltbld', 16:10:40 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:40 INFO - '_': '/tools/buildbot/bin/python'} 16:10:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:10:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:43 INFO - Downloading docopt-0.6.1.tar.gz 16:10:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:43 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:43 INFO - Running setup.py install for blobuploader 16:10:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:43 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:10:43 INFO - Running setup.py install for requests 16:10:43 INFO - Running setup.py install for docopt 16:10:43 INFO - Successfully installed blobuploader requests docopt 16:10:43 INFO - Cleaning up... 16:10:43 INFO - Return code: 0 16:10:43 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:43 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:43 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 16:10:43 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 16:10:43 INFO - Using env: {'DISPLAY': ':0', 16:10:43 INFO - 'HOME': '/home/cltbld', 16:10:43 INFO - 'LANG': 'en_US.UTF-8', 16:10:43 INFO - 'LANGUAGE': 'en_US:en', 16:10:43 INFO - 'LOGNAME': 'cltbld', 16:10:43 INFO - 'MAIL': '/var/mail/cltbld', 16:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:43 INFO - 'MOZ_NO_REMOTE': '1', 16:10:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:43 INFO - 'NO_EM_RESTART': '1', 16:10:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:43 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:43 INFO - 'SHELL': '/bin/bash', 16:10:43 INFO - 'SHLVL': '1', 16:10:43 INFO - 'TERM': 'linux', 16:10:43 INFO - 'TMOUT': '86400', 16:10:43 INFO - 'USER': 'cltbld', 16:10:43 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:43 INFO - '_': '/tools/buildbot/bin/python'} 16:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:10:44 INFO - Running setup.py (path:/tmp/pip-QkMe4d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:10:44 INFO - Running setup.py (path:/tmp/pip-DYEeVj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:10:44 INFO - Running setup.py (path:/tmp/pip-Nci9i3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:10:44 INFO - Running setup.py (path:/tmp/pip-P3tvP5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:10:44 INFO - Running setup.py (path:/tmp/pip-0LDEEt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:10:44 INFO - Running setup.py (path:/tmp/pip-2bSdvm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:10:44 INFO - Running setup.py (path:/tmp/pip-UKMiqd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:10:44 INFO - Running setup.py (path:/tmp/pip-oOkbNl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:10:44 INFO - Running setup.py (path:/tmp/pip-jZiJ9l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:10:44 INFO - Running setup.py (path:/tmp/pip-RTNI6e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:10:44 INFO - Running setup.py (path:/tmp/pip-5iuMID-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:10:44 INFO - Running setup.py (path:/tmp/pip-bGG_gl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:10:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:10:44 INFO - Running setup.py (path:/tmp/pip-TRAZU1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:10:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:10:45 INFO - Running setup.py (path:/tmp/pip-ULw9q_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:10:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:10:45 INFO - Running setup.py (path:/tmp/pip-q5df7g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:10:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:10:45 INFO - Running setup.py (path:/tmp/pip-Zp6Zlc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:10:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:10:45 INFO - Running setup.py (path:/tmp/pip-mJzVru-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:10:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:45 INFO - Running setup.py install for manifestparser 16:10:45 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:10:45 INFO - Running setup.py install for mozcrash 16:10:45 INFO - Running setup.py install for mozdebug 16:10:45 INFO - Running setup.py install for mozdevice 16:10:45 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:10:45 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:10:45 INFO - Running setup.py install for mozfile 16:10:45 INFO - Running setup.py install for mozhttpd 16:10:45 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:10:45 INFO - Running setup.py install for mozinfo 16:10:46 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Running setup.py install for mozInstall 16:10:46 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Running setup.py install for mozleak 16:10:46 INFO - Running setup.py install for mozlog 16:10:46 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Running setup.py install for moznetwork 16:10:46 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Running setup.py install for mozprocess 16:10:46 INFO - Running setup.py install for mozprofile 16:10:46 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Running setup.py install for mozrunner 16:10:46 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:10:46 INFO - Running setup.py install for mozscreenshot 16:10:46 INFO - Running setup.py install for moztest 16:10:47 INFO - Running setup.py install for mozversion 16:10:47 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:10:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:47 INFO - Cleaning up... 16:10:47 INFO - Return code: 0 16:10:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:47 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:47 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 16:10:47 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 16:10:47 INFO - Using env: {'DISPLAY': ':0', 16:10:47 INFO - 'HOME': '/home/cltbld', 16:10:47 INFO - 'LANG': 'en_US.UTF-8', 16:10:47 INFO - 'LANGUAGE': 'en_US:en', 16:10:47 INFO - 'LOGNAME': 'cltbld', 16:10:47 INFO - 'MAIL': '/var/mail/cltbld', 16:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:47 INFO - 'MOZ_NO_REMOTE': '1', 16:10:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:47 INFO - 'NO_EM_RESTART': '1', 16:10:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:47 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:47 INFO - 'SHELL': '/bin/bash', 16:10:47 INFO - 'SHLVL': '1', 16:10:47 INFO - 'TERM': 'linux', 16:10:47 INFO - 'TMOUT': '86400', 16:10:47 INFO - 'USER': 'cltbld', 16:10:47 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:47 INFO - '_': '/tools/buildbot/bin/python'} 16:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:10:47 INFO - Running setup.py (path:/tmp/pip-VEtEqC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:10:47 INFO - Running setup.py (path:/tmp/pip-zJcOX4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:10:47 INFO - Running setup.py (path:/tmp/pip-WeUCbK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:10:47 INFO - Running setup.py (path:/tmp/pip-dZynWv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:10:47 INFO - Running setup.py (path:/tmp/pip-ur4HF4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:10:47 INFO - Running setup.py (path:/tmp/pip-BdjuOo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:10:47 INFO - Running setup.py (path:/tmp/pip-xF5xPI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:10:47 INFO - Running setup.py (path:/tmp/pip-KC_b69-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:10:47 INFO - Running setup.py (path:/tmp/pip-9bDqhV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:10:47 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)) 16:10:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:10:47 INFO - Running setup.py (path:/tmp/pip-51KV6e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:10:48 INFO - Running setup.py (path:/tmp/pip-FKHogT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:10:48 INFO - Running setup.py (path:/tmp/pip-25sTRO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:10:48 INFO - Running setup.py (path:/tmp/pip-ldHTb1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:10:48 INFO - Running setup.py (path:/tmp/pip-hQSVsw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:10:48 INFO - Running setup.py (path:/tmp/pip-WN1evo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:10:48 INFO - Running setup.py (path:/tmp/pip-JKkQ6s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:10:48 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)) 16:10:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:10:48 INFO - Running setup.py (path:/tmp/pip-ExjZMK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:10:48 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)) 16:10:48 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)) 16:10:48 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)) 16:10:48 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)) 16:10:48 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)) 16:10:48 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)) 16:10:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:50 INFO - Downloading blessings-1.6.tar.gz 16:10:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:50 INFO - Installing collected packages: blessings 16:10:50 INFO - Running setup.py install for blessings 16:10:50 INFO - Successfully installed blessings 16:10:50 INFO - Cleaning up... 16:10:50 INFO - Return code: 0 16:10:50 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:10:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:10:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:10:50 INFO - Reading from file tmpfile_stdout 16:10:50 INFO - Current package versions: 16:10:50 INFO - argparse == 1.2.1 16:10:50 INFO - blessings == 1.6 16:10:50 INFO - blobuploader == 1.2.4 16:10:50 INFO - docopt == 0.6.1 16:10:50 INFO - manifestparser == 1.1 16:10:50 INFO - mozInstall == 1.12 16:10:50 INFO - mozcrash == 0.16 16:10:50 INFO - mozdebug == 0.1 16:10:50 INFO - mozdevice == 0.47 16:10:50 INFO - mozfile == 1.2 16:10:50 INFO - mozhttpd == 0.7 16:10:50 INFO - mozinfo == 0.9 16:10:50 INFO - mozleak == 0.1 16:10:50 INFO - mozlog == 3.1 16:10:50 INFO - moznetwork == 0.27 16:10:50 INFO - mozprocess == 0.22 16:10:50 INFO - mozprofile == 0.27 16:10:50 INFO - mozrunner == 6.11 16:10:50 INFO - mozscreenshot == 0.1 16:10:50 INFO - mozsystemmonitor == 0.0 16:10:50 INFO - moztest == 0.7 16:10:50 INFO - mozversion == 1.4 16:10:50 INFO - psutil == 3.1.1 16:10:50 INFO - requests == 1.2.3 16:10:50 INFO - wsgiref == 0.1.2 16:10:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:50 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:50 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 16:10:50 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 16:10:50 INFO - Using env: {'DISPLAY': ':0', 16:10:50 INFO - 'HOME': '/home/cltbld', 16:10:50 INFO - 'LANG': 'en_US.UTF-8', 16:10:50 INFO - 'LANGUAGE': 'en_US:en', 16:10:50 INFO - 'LOGNAME': 'cltbld', 16:10:50 INFO - 'MAIL': '/var/mail/cltbld', 16:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:50 INFO - 'MOZ_NO_REMOTE': '1', 16:10:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:50 INFO - 'NO_EM_RESTART': '1', 16:10:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:50 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:50 INFO - 'SHELL': '/bin/bash', 16:10:50 INFO - 'SHLVL': '1', 16:10:50 INFO - 'TERM': 'linux', 16:10:50 INFO - 'TMOUT': '86400', 16:10:50 INFO - 'USER': 'cltbld', 16:10:50 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:50 INFO - '_': '/tools/buildbot/bin/python'} 16:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 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)) 16:10:51 INFO - Cleaning up... 16:10:51 INFO - Return code: 0 16:10:51 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:51 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:51 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 16:10:51 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 16:10:51 INFO - Using env: {'DISPLAY': ':0', 16:10:51 INFO - 'HOME': '/home/cltbld', 16:10:51 INFO - 'LANG': 'en_US.UTF-8', 16:10:51 INFO - 'LANGUAGE': 'en_US:en', 16:10:51 INFO - 'LOGNAME': 'cltbld', 16:10:51 INFO - 'MAIL': '/var/mail/cltbld', 16:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:51 INFO - 'MOZ_NO_REMOTE': '1', 16:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:51 INFO - 'NO_EM_RESTART': '1', 16:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:51 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:51 INFO - 'SHELL': '/bin/bash', 16:10:51 INFO - 'SHLVL': '1', 16:10:51 INFO - 'TERM': 'linux', 16:10:51 INFO - 'TMOUT': '86400', 16:10:51 INFO - 'USER': 'cltbld', 16:10:51 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:51 INFO - '_': '/tools/buildbot/bin/python'} 16:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:51 INFO - Downloading/unpacking jsonschema 16:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:10:52 INFO - Downloading/unpacking functools32 (from jsonschema) 16:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:53 INFO - Downloading functools32-3.2.3-2.tar.gz 16:10:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:10:53 INFO - warning: no files found matching '*.txt' 16:10:53 INFO - no previously-included directories found matching 'build' 16:10:53 INFO - no previously-included directories found matching 'dist' 16:10:53 INFO - no previously-included directories found matching '.git*' 16:10:53 INFO - Installing collected packages: jsonschema, functools32 16:10:53 INFO - Running setup.py install for functools32 16:10:53 INFO - warning: no files found matching '*.txt' 16:10:53 INFO - no previously-included directories found matching 'build' 16:10:53 INFO - no previously-included directories found matching 'dist' 16:10:53 INFO - no previously-included directories found matching '.git*' 16:10:53 INFO - Successfully installed jsonschema functools32 16:10:53 INFO - Cleaning up... 16:10:53 INFO - Return code: 0 16:10:53 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 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 0x7fa22c802f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcf78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdc4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, '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': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', '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 16:10:53 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 16:10:53 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 16:10:53 INFO - Using env: {'DISPLAY': ':0', 16:10:53 INFO - 'HOME': '/home/cltbld', 16:10:53 INFO - 'LANG': 'en_US.UTF-8', 16:10:53 INFO - 'LANGUAGE': 'en_US:en', 16:10:53 INFO - 'LOGNAME': 'cltbld', 16:10:53 INFO - 'MAIL': '/var/mail/cltbld', 16:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:53 INFO - 'MOZ_NO_REMOTE': '1', 16:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:53 INFO - 'NO_EM_RESTART': '1', 16:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:10:53 INFO - 'PWD': '/builds/slave/test-pgo', 16:10:53 INFO - 'SHELL': '/bin/bash', 16:10:53 INFO - 'SHLVL': '1', 16:10:53 INFO - 'TERM': 'linux', 16:10:53 INFO - 'TMOUT': '86400', 16:10:53 INFO - 'USER': 'cltbld', 16:10:53 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:53 INFO - '_': '/tools/buildbot/bin/python'} 16:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:53 INFO - Downloading/unpacking flake8 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:10:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:10:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:10:56 INFO - no previously-included directories found matching 'docs/_build' 16:10:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:10:56 INFO - Running setup.py install for pep8 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:10:56 INFO - no previously-included directories found matching 'docs/_build' 16:10:56 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:10:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:10:56 INFO - Cleaning up... 16:10:56 INFO - Return code: 0 16:10:56 INFO - Running post-action listener: _resource_record_post_action 16:10:56 INFO - Running post-action listener: _start_resource_monitoring 16:10:56 INFO - Starting resource monitoring. 16:10:56 INFO - ##### 16:10:56 INFO - ##### Running install step. 16:10:56 INFO - ##### 16:10:56 INFO - Running pre-action listener: _resource_record_pre_action 16:10:56 INFO - Running main action method: install 16:10:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:10:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:10:56 INFO - Reading from file tmpfile_stdout 16:10:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:10:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:10:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:10:56 INFO - Reading from file tmpfile_stdout 16:10:56 INFO - Output received: 16:10:56 INFO - Usage: mozinstall [options] installer 16:10:56 INFO - Options: 16:10:56 INFO - -h, --help show this help message and exit 16:10:56 INFO - -d DEST, --destination=DEST 16:10:56 INFO - Directory to install application into. [default: 16:10:56 INFO - "/builds/slave/test-pgo"] 16:10:56 INFO - --app=APP Application being installed. [default: firefox] 16:10:56 INFO - mkdir: /builds/slave/test-pgo/build/application 16:10:56 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'] 16:10:56 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 16:11:11 INFO - Reading from file tmpfile_stdout 16:11:11 INFO - Output received: 16:11:11 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:11:11 INFO - Running post-action listener: _resource_record_post_action 16:11:11 INFO - ##### 16:11:11 INFO - ##### Running run-tests step. 16:11:11 INFO - ##### 16:11:11 INFO - Running pre-action listener: _resource_record_pre_action 16:11:11 INFO - Running main action method: run_tests 16:11:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:11:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:11:11 INFO - Python 2.7.3 16:11:11 INFO - Return code: 0 16:11:11 INFO - grabbing minidump binary from tooltool 16:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:11 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 0xe53350>, '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 0xe54250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe546e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:11:11 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 16:11:11 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 16:11:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:11 INFO - Return code: 0 16:11:11 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:11:11 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:11:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:11:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:11:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:11:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:11:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:11:12 INFO - Return code: 0 16:11:12 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/1454362951/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:11:12 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/1454362951/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --webServer localhost 16:11:12 INFO - Using env: {'DISPLAY': ':0', 16:11:12 INFO - 'HOME': '/home/cltbld', 16:11:12 INFO - 'LANG': 'en_US.UTF-8', 16:11:12 INFO - 'LANGUAGE': 'en_US:en', 16:11:12 INFO - 'LOGNAME': 'cltbld', 16:11:12 INFO - 'MAIL': '/var/mail/cltbld', 16:11:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:11:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:12 INFO - 'MOZ_NO_REMOTE': '1', 16:11:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:11:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:12 INFO - 'NO_EM_RESTART': '1', 16:11:12 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:11:12 INFO - 'PWD': '/builds/slave/test-pgo', 16:11:12 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:11:12 INFO - 'SHELL': '/bin/bash', 16:11:12 INFO - 'SHLVL': '1', 16:11:12 INFO - 'TERM': 'linux', 16:11:12 INFO - 'TMOUT': '86400', 16:11:12 INFO - 'USER': 'cltbld', 16:11:12 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866', 16:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:12 INFO - '_': '/tools/buildbot/bin/python'} 16:11:12 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/1454362951/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost'] with output_timeout 3600 16:11:12 INFO - mozversion INFO | application_buildid: 20160201134231 16:11:12 INFO - mozversion INFO | application_changeset: 55272f23ad9565bc2b7ba87cfcf1d36cc8fe7bce 16:11:12 INFO - mozversion INFO | application_display_name: Nightly 16:11:12 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:12 INFO - mozversion INFO | application_name: Firefox 16:11:12 INFO - mozversion INFO | application_remotingname: firefox 16:11:12 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:11:12 INFO - mozversion INFO | application_vendor: Mozilla 16:11:12 INFO - mozversion INFO | application_version: 45.0 16:11:12 INFO - mozversion INFO | platform_buildid: 20160201134231 16:11:12 INFO - mozversion INFO | platform_changeset: 55272f23ad9565bc2b7ba87cfcf1d36cc8fe7bce 16:11:12 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:11:12 INFO - mozversion INFO | platform_version: 45.0 16:11:12 INFO - 2016-02-01 16:11:12,635 DEBUG : using testdate: 1454371872 16:11:12 INFO - 2016-02-01 16:11:12,635 DEBUG : actual date: 1454371872 16:11:12 INFO - 2016-02-01 16:11:12,644 INFO : starting webserver on 'localhost:54385' 16:11:12 INFO - 2016-02-01 16:11:12,645 INFO : Starting test suite talos-linux64-ix-026 16:11:12 INFO - 2016-02-01 16:11:12,645 INFO : Starting test dromaeo_css 16:11:12 INFO - 2016-02-01 16:11:12,645 DEBUG : operating with platform_type : linux_ 16:11:12 INFO - 2016-02-01 16:11:12,646 INFO : Initialising browser for dromaeo_css test... 16:11:12 INFO - 2016-02-01 16:11:12,659 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuO1X5K/profile http://localhost:54385/getInfo.html 16:11:16 INFO - 2016-02-01 16:11:16,680 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:11:16 INFO - 2016-02-01 16:11:16,680 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:11:16 INFO - 2016-02-01 16:11:16,681 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:11:16 INFO - 2016-02-01 16:11:16,681 DEBUG : BROWSER_OUTPUT: __metrics 16:11:16 INFO - 2016-02-01 16:11:16,817 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] 16:11:17 INFO - 2016-02-01 16:11:17,479 INFO : Browser initialized. 16:11:17 INFO - 2016-02-01 16:11:17,480 INFO : Running cycle 1/1 for dromaeo_css test... 16:11:17 INFO - 2016-02-01 16:11:17,480 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuO1X5K/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:11:29 INFO - 2016-02-01 16:11:29,244 DEBUG : BROWSER_OUTPUT: RSS: Main: 134930432 16:11:29 INFO - 2016-02-01 16:11:29,244 DEBUG : BROWSER_OUTPUT: 16:12:19 INFO - 2016-02-01 16:12:19,990 DEBUG : BROWSER_OUTPUT: 1454371939985 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:14:28 INFO - 2016-02-01 16:14:28,121 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54385/tests/dromaeo/cssquery-ext.html) 16:14:29 INFO - 2016-02-01 16:14:29,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 232357888 16:14:29 INFO - 2016-02-01 16:14:29,187 DEBUG : BROWSER_OUTPUT: 16:17:31 INFO - 2016-02-01 16:17:31,267 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/dromaeo/cssquery-ext.html (next: http://localhost:54385/tests/dromaeo/cssquery-jquery.html) 16:17:31 INFO - 2016-02-01 16:17:31,833 DEBUG : BROWSER_OUTPUT: RSS: Main: 211410944 16:17:31 INFO - 2016-02-01 16:17:31,833 DEBUG : BROWSER_OUTPUT: 16:20:33 INFO - 2016-02-01 16:20:33,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54385/tests/dromaeo/cssquery-mootools.html) 16:20:34 INFO - 2016-02-01 16:20:34,228 DEBUG : BROWSER_OUTPUT: RSS: Main: 209072128 16:20:34 INFO - 2016-02-01 16:20:34,229 DEBUG : BROWSER_OUTPUT: 16:23:35 INFO - 2016-02-01 16:23:35,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54385/tests/dromaeo/cssquery-prototype.html) 16:23:36 INFO - 2016-02-01 16:23:36,563 DEBUG : BROWSER_OUTPUT: RSS: Main: 207691776 16:23:36 INFO - 2016-02-01 16:23:36,564 DEBUG : BROWSER_OUTPUT: 16:26:38 INFO - 2016-02-01 16:26:38,920 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54385/tests/dromaeo/cssquery-yui.html) 16:26:39 INFO - 2016-02-01 16:26:39,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 199536640 16:26:39 INFO - 2016-02-01 16:26:39,487 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,600 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/dromaeo/cssquery-yui.html (next: http://localhost:54385/tests/dromaeo/cssquery-dojo.html) 16:29:41 INFO - 2016-02-01 16:29:41,850 DEBUG : BROWSER_OUTPUT: RSS: Main: 242515968 16:29:41 INFO - 2016-02-01 16:29:41,850 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,851 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:29:41 INFO - 2016-02-01 16:29:41,851 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:29:41 INFO - 2016-02-01 16:29:41,851 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:29:41 INFO - 2016-02-01 16:29:41,851 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:29:41 INFO - 2016-02-01 16:29:41,851 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3275.328557645683;3359.344927739092;3382.9323413531747;3389.4407422775244;3419.316136772645;5549.472800083996;5596.048671725806;5627.268455100836;5631.493165615094;5653.293338332704;5575.944240557593;5605.495505404513;5611.719414029292;5621.831345059644;5630.662160270386;4910.337104490884;5043.369578802641;5062.721550314731;5072.3405957225505;5130.589552835764;5027.271045698371;5093.414257360396;5154.896902061956;5158.535731784139;5162.974185129088;5003.549680528752;5090.592752579785;5104.106781313274;5107.821226257081;5108.335916330871;5141.15170996785;5180.948190518122;5182.740862956837;5190.11767999441;5219.712915789649;4966.428860681031;4998.975005124988;5044.47033061527;5094.566961808251;5096;5339.786408543654;5341.145416733343;5348.197770334442;5359.169328754049;5371.5165635092635;5928.525717942554;5939.198208241875;5979.222701048836;5981.611195272337;5983.551233657449;5860.706964651793;5871.207387002785;5986.221791167164;5998.7600495980105;6019.879602407971;5500.724963751796;5553.139263414178;5597.636153650041;5606.074997625387;5614.775408983676;6094.11635312886;6118.908216376758;6123.448889599868;6126.448619624255;6132.41742034506;5695.259616249861;5850.678212698257;5916.26046744157;6011.248593925759;6033;6059.818205453844;6070.3019152797115;6072.240969878765;6073.878522429527;6077.696115194222;5742.7415766290615;5998.220231369894;6033.215681961405;6047.092936059538;6048.667323297261;5902.468777810018;5983.1623572699855;6087.086936959403;6087.817365479043;6121.785737499166;4578.221702310615;4598.816047358135;4599.701019433726;4617;4622.583967442879;4023.8591649292134;4067.2272268268935;4096.180763847243;4101.630853223224;4148.751074935513;5618.044932361427;5685.403032681592;5699.800506982236;5701.4298570142655;5721.542276617861;5997.0404735242155;6040.818775436744;6057.636541807506;6065.878682426327;6066.090086486974;5536.640118392292;5626.690532020778;5700.201971723962;5717.885642287131;5729.656220626775;5152.768125434364;5200.505951934636;5216.947830521722;5225.242339860775;5234.450382709837;6201.596896201732;6215.378462153839;6217.813465596039;6221.813345599639;6225.657588832567;5818.4763371296785;5987.161797348288;5998.220231369894;6008.068749343859;6008.729607167776;6092.360302168208;6155.72299246526;6185.907211391743;6189.566730328834;6197.81406557804;5914.7338369774325;5926.376817378955;5969.194158788508;5985.670788106696;6012.849678758066;5806.854828629318;5921.5558833086825;5989.850253743692;6066.059760737093;6074.119252708244;5920.141579470866;5924.259467566554;5927.644341339534;5928.318243401892;5928.970355148197;5887.264091988502;5895.056790913605;5898.764049437975;5902.586818922634;5903.0555111182;5644.88710225785;5648.378678345297;5769.769209231584;5778.04662230744;5805.593608447537;5868.354481007171;5907.350191478848;5916.852078697895;5918.556108291809;5923.644581325134;5784.739686714192;5792.247007888947;5795.710214489343;5795.797147099831;5798.82603521895 16:29:41 INFO - 2016-02-01 16:29:41,855 DEBUG : BROWSER_OUTPUT: |1;ext.html;588.4644973074503;624.6689254695011;625.9186305780248;634.5431289471582;636.2460484326077;33531.497027544574;43491.565084349146;43763.12473750527;43970.34044489335;44028;47861.28208076882;48129.278060829114;48357.95525559305;48552.51447485524;48643.02713945719;8097.716579919704;8243.876341854877;9108.453492790437;9252.444853308789;9277.072292770736;10598.258121931467;10833.620823271187;10839.783204335909;10840.837387439195;10853.348799072042;10524.526396312185;10610;10617.203709721764;10622.25644204906;10626.734331641732;10453.477326133701;10524.631637892713;10530.31552949056;10538.262321637489;10559.78880422395;10294.588216471333;10436.321639093434;10437.060664540228;10742.785144297148;10749.83875241872;43442.78278608587;43962;44040.55959440397;44301.55698443006;44395.77802111001;3927.8428862845453;4011.799410029487;4153.0447996960565;4236.237477254093;4243.511996120455;32646.347073058645;33100.33799324024;33284;33285;33473;3938.7439816411843;4003.2994226010624;4019.97990010051;4050.06848424861;4066.1664358806374;304096;305255;306147;306464;309346;59987;61011.69494152589;70697;72215;72335;61492.69253653793;61784;61915.690421548505;61985.38014619886;62009.37990620126;17859.553511162325;18047.819521804875;18062.729059064124;18068.277268909096;18072.18675159621;13516.053876228767;13544.187348759106;13609.931950340382;13646.658833529242;13710.451581936612;22781.772182278295;22824;22930.541389172457;22962.425939351317;22977.276704246913;8615.698450554326;8822.161894620001;8846.33652476054;8866.379353445196;8939.910600893907;957.3155194036298;1032.339302846179;1090.536521978172;1137.8179491281355;1139.9544018239344;28585.805744463625;28596;28955.855220723763;29304;30025.699743002726;1031.83490641497;1146.3694967767694;1162.273579013117;1216.367488905764;1219.6341097670663;14887.330070146869;17315.220815063352;17712.380066697602;17719.114044297734;17979.370722024927;428283;429337;431493;432118;432585;60841.39158608357;61271.6936415324;61281.69359153176;61424;61499.385006149365;1378.3315092180285;1424.2451500704642;1468.067776961637;1468.4493315006873;1491.9627009324636;30062.849685751433;30162.396752065273;30188.547171792;30863.2284192897;31101.066967990995;47745;48269.75865120652;48275;48277.3791965611;48293.275600865316;218018;220884;235956;236008;236098;35515.28969420546;35629.82185089058;36010.27979440449;36077.81961090178;36160.81919590385;45982;46176.30735538902;46745.2988205184;46828;47107.76446117885;37720.62279377171;38286.23427531378;39503.407448888865;39573.2085358297;39747.60252397555;43815.78092109519;45772.7711361441;46076.7696161517;46146.307805383614;47976.28035579399;39096;39824.80087599543;39844.40233396443;39873.20253594854;40027.3995890056;34121.371926249936;34421.31157376788;34603.30793384169;34789.82605086958;35429.82285088661;27293.317667058483;27614.5857812137;27884;28062.57906131369;28071.298217544725 16:29:41 INFO - 2016-02-01 16:29:41,856 DEBUG : BROWSER_OUTPUT: |2;jquery.html;389.65320864430646;392.82519278920876;395.66763918308624;398.5775078416878;398.9401589761534;13561.488213575674;17284.481465556037;18881;19563.608727825434;19584.118714657838;18429.354972575962;18454.354097606585;18829.811701882976;18882.244710211577;18883.62232755344;5884.499817515506;5999.220101386835;6001.639901605896;6004.639721616717;6005.069214271773;5926.496247818941;6013.458788709016;6061.272647282332;6079.604825686316;6087.695615219243;4585.220512512881;4711.764411779414;6168.259808822948;6170.722317495701;6173.41352571505;6812.386885180333;7012.824679383005;7060.435165186824;7062.540934839219;7067.363937245621;6482.93517064828;6500.512461565401;6504.707288172043;6507.186601674791;6565.474762019075;23699.526009479712;23704;23745.168919087748;23954.52090958171;24423.26730198097;2343.55472460232;2366.538524987611;2400.59189937711;2405.87970601471;2454.828162028659;14418.351174197187;14702.117872927658;14750.188739619316;14804.259787010606;14831.629209269746;1956.344624550777;1956.4619729574338;1958.4614231086566;1988.2742798878305;2049.405672355015;61509;62347.376526235064;62693;63391.68304158496;72097.27902720959;10853.131749460024;11231.943840280746;11273.379964101889;11296.887031129583;11368.204225704287;14728.779068313985;14797.852021479648;14813.555593332218;14839.258037098101;14850.405983760744;8502.914970850336;8507.702230421906;8525.95737021311;8529;8536.74389768308;8285.95857020711;8344.541050242173;8365.205305495969;8390.706325278708;8411.663533458715;4680.81276748933;4723.338732577442;4732.905341893212;4798.4001999750035;4808.08646357191;1736.9247379761414;1928.53715108373;1942.1066309497508;1984.583237520137;1989.44295597233;901.900790912999;955.6368579939578;1057.8307470804634;1072.8122578548723;1113.4544073403974;17320.6618650568;17706.822931770774;17856;18049.7292540612;18240.817591824176;960.5869476125193;989.2877128467636;992.5979978108877;999.4253304349883;1017.5624481473037;9313.534323283944;9432.717018489457;9467.715968520955;9673.661421850202;9676.661316854017;587078;640530;652026;662481;663476;84188.57905710554;85852;88345.55827220823;88804;89816.550917245;1185.9644210673694;1194.3729541990524;1213.071999920062;1227.7974134267924;1229.4344601483417;15865;16310.836891630932;16371.099589522215;16431.342746290014;16549.924254923873;34444.65555344515;34503.654963451045;34817.825910870284;38755.03112422212;38841.223175535764;49226.50773492362;51456;51974;53119;53304.73347633237;9972.102510774064;10299.227557933335;10420.791584168426;10444.895551044392;10467.110295624958;12028.63914082579;17952.640947181244;18007.63984720272;18016.819831801513;18614.627707446045;14757.335919883417;14962.850371496146;15066;15197.6200594986;15228.543143705707;14898.32957516936;14909.030912990622;15340.92329538345;15587.454439094578;15924.124173170587;16458.259378327766;16505.752413713562;16547.338106475607;16881.66236675284;16931.91534042322;9965.950170249393;10511.684649460529;10555.683329500127;10896.94551527269;10936;14959.775603366175;15488.457903973307;15812.209389530248;16004;16316.347346106022 16:29:41 INFO - 2016-02-01 16:29:41,858 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1534.3325653340792;1549.0705576654016;1768.9203985820636;1811.2392795026087;1849;7725.61371931404;7914.129445760969;7925.2867241948215;7931.444798864082;7943.761687149395;6988.05661235733;7209.891851622217;7213.89179162313;7590.77227683169;7888.7633370998965;2329;2334.6264597664376;2366.2546297916188;2366.6213405855065;2370.2415227127326;2383.4160630645515;2407.6027455469825;2450.9387265318333;2457.5576396248666;2462.2859370782453;2048.0476578391044;2086.2385229391316;2090.1012564597218;2273.6930514380597;2312.4450131968374;2355.5642206191824;2374.9287521374386;2377.2155188787747;2377.4531857672746;2379.7263314718857;2411.746766589518;2432.72023717273;2433.537627850703;2434.7443518430664;2435.8416702914255;7618.238176182393;7685.807854803675;7698.846023079507;7699.345555627779;7702.422318326148;4621.792019359137;4726.031163611486;4728.739919304437;4729.692569982974;4731.148393289207;3182.840857957093;3234.1752853022563;3240.481522956339;3249.772515923886;3258.4786434170655;1030.2479190191202;1032.0144262229628;1046.5604446132643;1077.407425915751;1081.416035340915;10695.94652026735;10845.132389408907;10850.077743391821;10855.728606784814;10862.02241798234;4446.888827779331;4497.8200871964755;4540.77296135192;4551.112533056023;4554.521775213621;9434;9525.714228573153;9529.809403811885;9548.140667339972;9555.23558115349;4835.419749630067;4846;4865.9756701216975;4872.902541949141;4878.512148785093;4368.825246990084;4446.155230506193;4465.419495465634;4469.9776501117285;4474.149911516802;3796.886093417202;3829.9042523937123;3835.0987517933263;3836.1176929306416;3867.806609669505;1232.950681972729;1258.704204511939;1278.4310981613278;1280.6862318731785;1289.4648720780958;1050.5062620568322;1112.054753459553;1196.0730433913786;1289.7162624222656;1406.9507567235098;7335.303146201102;7438.702451901863;7500.624968751541;7511.136219334737;7517.586532740752;1133.739239974794;1160.3154139057997;1192.356127691037;1205.7950148474781;1210.6973256685828;6195.256569211723;6258.968705156445;6267.749290028348;6282.937170628326;6286.119943207955;8962.058983806737;9201.90798092024;9303.627854885865;9316.627334906529;9322.394044387092;9335.719928402159;9342.486163261086;9366.765830854283;9384.202342800938;9386.671466498776;7313.414926805843;7495.325420712271;7566.546007239584;7570.507916985378;7571.772846814604;7178.676959536729;7684.769456916301;7716.498427602188;7727.961360193163;7743.7676869694;7978.521288722695;8232.464889782144;8327.708530201415;8399.530082235462;8604.354673399395;8705.910581460277;8760.868586971328;8844.73465796027;8951.597178126869;9022.052684468037;7572.81067973283;7610.96194519046;7673.34776544;7673.34776544;7689.615519223905;7473.551586904586;7510.962445187958;7551.773446796605;7563.73526926577;7757.340626046849;6683.699233534599;7133.6076515790055;7176.928230717626;7316.743913962986;7383.630818458948;6593.96703016501;7065.6820443081215;7268.636568171676;7307.671154798153;7362.668679909524;7300.890486642811;7323.963380183065;7340.522866013692;7343.37581305595;7344.853102938018;6799.3200679931615;6838.418734407631;6838.658067096526;6842.418394436529;6846.760363387258;6794.116764820542;6943.826404339729;6962.547434416747;6965.6168910708375;6969.198542167513 16:29:41 INFO - 2016-02-01 16:29:41,861 DEBUG : BROWSER_OUTPUT: |4;prototype.html;358.7273672009275;359.4338916206974;361.7720835873399;364.2787281183259;366.6828193612526;6012.128241404999;6073.058675905237;6178.752849885999;6224.595401298918;6226.066090086478;5839.883202335951;5885.470307672319;5939.109133629947;6042.456178943894;6100.755969761204;4676.7427791471455;4707.317438971347;4717.952820471803;4733.5739783419485;4736.550027747376;4467.486239082498;4723.220668589679;4726.0547890421885;4730.787114579835;4732.905341893177;4497.7526236057;4549.1584056949405;4652.302154676791;4665.463471700764;4684.250519916814;4630.490646028951;4640.443146822403;4650.046740418223;4655.4180727409075;4655.580997710188;4134.069834287291;4137.358709400048;4139.523954745213;4141.979290103561;4142.834286628531;5670;5746;5762.769489220426;5765.106408506688;5766.279215098113;5401.108817045183;5403.783848646089;5407.134858422633;5409.56723462122;5410.837674869721;6394.392532709385;6396.968015159941;6399.744010239585;6401.679916004227;6485.092087107809;4502.864914052584;4507.391502147234;4511.6165125964335;4514.729116253003;4517.616002639779;6596.472282217411;6640.037194606755;6708.228553716336;6718.462522998148;6720.361565651256;4609.562091601292;4610.815567377334;4612.930806037912;4614.330922016289;4614.861554153381;4664.230401983635;4665.066986602693;4666.69666471685;4670.252759558454;4670.369500117508;5352.545033672103;5353.678779273256;5354.464553544614;5360.517553420211;5363.329583802025;6362.554621176473;6486.35136486357;6495;6499.707513161919;6507.34926507355;1630.6820170066724;1652.892561983474;1654.247317470548;1655;1655.5530006898312;1095.309954728516;1114.9331040137617;1115;1134.0530656901415;1134.597217987619;6283.214598175228;6302.527310451735;6302.747890084345;6304.527160462983;6307.621542707452;6513.706883190268;6679.76620818269;6685.197776266782;6690.297518760557;6692.163479565054;6168.290646575611;6257.342978987232;6322.399372059647;6347.936520634735;6355.396237357444;3556.608773034964;3598.298331825269;3599.676029157336;3634.055145662147;3636.2727454509204;6782.660866956731;6942.895856562156;6944.756933507303;6951.7914462566205;6957.582545047313;6875.896861547081;6889.207741109836;6906.965465172742;6915.827104322331;6917.446604271646;6818.556793808387;6820.488463365267;6824.897626535606;6825.624590647462;6831.419329356961;6545.908806995628;6663.400293973566;6717.630530320879;6719.059331693665;6735.730570777114;6606.933930660632;6646.634435106115;6660.400563949268;6660.500462465237;6664.83337916556;6922.10012698346;6933.757318493828;6934.687939042654;6934.895976560457;6936.826579335557;4575.58819706228;4644.117617652635;4648.349231107715;4650.5116962719785;4651.069786042737;6756.256811750605;6762.830929226809;6770.763023294161;6778.627175505395;6783.18601767791;6525.2822189558165;6552.213734351908;6554.573952693059;6554.656295459515;6559.868802623825;6440.0983862260255;6520.250171230369;6534.085228068169;6539.215294164731;6564.540482166202;6437.098806167047;6447.194100737408;6447.2263328400895;6451.838704032437;6455.4512866406885;6592.736290548324;6595.538312318284;6597.538172327891;6605.471562275103;6605.504587155887;6351.809445716636;6433.8713225736155;6455.289918109098;6462.903056454251;6464.353564643498 16:29:41 INFO - 2016-02-01 16:29:41,862 DEBUG : BROWSER_OUTPUT: |5;yui.html;867.1415298854132;889.3907673243824;912.4114945859916;953.6519170502769;961.3414810854564;14652.926735366322;15227.543173704778;15247.085174889515;15256.923715381436;15261.771073433894;13962.58112256634;14172.574822755334;14194.432222711079;14216.928915355435;14226.644333891632;752.9924700753005;756.1493320014984;913.5021413329735;930.8371035068869;931.0875342164683;917.0691747875911;918.8805455290803;924.3575714878162;924.4591913730442;926.9907300926972;967.7000129959699;976.7216343342149;976.8681228034195;977.0571398600349;977.5210147027976;800.8878756974028;801.939854510914;973.8636590877247;984.1782111936542;986.9062439068277;731.8097294703415;733.6588486353871;733.7615275035635;733.7725305155419;734.0750654175777;14583.92708036453;14755.631109222248;14799.852001479954;14801.037932534351;14810.111393316327;12713.491460341575;13261.20432774027;13542.187468751808;13620.659483512893;13660.863391366058;10859.945700271526;10860;10893.673189804318;11014.284071535325;11182.608608698656;3724.0875985383755;3725.9441108383394;3728.571214310362;3760.755550889211;3836.232753449321;50291.74854125706;50530.242046369334;50796.23805642918;50834;51173.232401514004;9949.403035817873;10012;10024.097831195082;10057.949710251549;10068.647597334058;19592.902035489733;19603.31388401399;19615;19615.803841961682;19620.411387658107;7938.364930805522;8017.078036025815;8040.475186627362;8057.113717491071;8086.83826323482;4476.955230447654;4610.124076425468;4682.110399024243;4704.976475117603;4770.403699537558;3336.3160552086765;3379.2396710739886;3381.053305074583;3388.3053973935284;3388.3562123196516;577.6967092276504;604.7369394313488;607.5139888089511;607.5443417436923;615.0865964043392;685.5201359048685;685.8353995041253;713.3651050565002;714.9535280206874;726.3100054947821;8644.654213831376;8808.20726134651;9077.863832042525;9503.334766566411;9663.516824158763;642.4346575013958;657.9342065793383;681.6489507903434;694.7464175575878;732.8204589875514;4062.1875624875142;4131.111810960598;4272.739541835166;4281.7002809803635;4506.729596224237;60358.094628580606;60534;60772.39227607756;60875.69562152161;60904.695476522335;54121;54685.72657136768;55205.44794552003;55323.44676553264;57523.424765751806;50685.49314506882;51245.487545124815;51593.2261016085;52441.475585245185;52647.73676131595;8700.216980471789;8956.641734330553;9083.909160908306;9157.587908543997;9327.720168394959;36326;36858.63141368552;37033.44449833308;37409.43885841769;37988.240235194586;44399.334009990525;48830.511694882596;48882.75558622326;51111.48888511216;51296;11206.719832004264;11818.527258909548;11915.404229788302;11924.880751192377;12027.097967652637;12339.444724987217;12400.503979840705;13218.60344189676;13332.333383330988;13984;11045.72385690332;11214.607488737855;11343.546258149912;11431.142664300378;11621.186516943733;11358.60244891425;11379.829302560634;11546.307221566733;11832.52669893229;12480.750384992069;10780.29928054674;10992.670219893416;11025.228234023542;11402.82895756581;11454.370009649216;9833.655822046194;9955.212031336507;10063.798724025331;10196.541155647865;10614.946925265323;9865.309428340235;10228.130608898327;10257.794844103226;10397.376157430579;10398.168146548107 16:29:41 INFO - 2016-02-01 16:29:41,862 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:29:41 INFO - 2016-02-01 16:29:41,862 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,754 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: __startTimestamp1454372981848__endTimestamp 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5570.12 Median:5792.25 stddev:621.46 (10.7%) stddev-sans-first:596.73 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: Values: 3275.3 3359.3 3382.9 3389.4 3419.3 5549.5 5596.0 5627.3 5631.5 5653.3 5575.9 5605.5 5611.7 5621.8 5630.7 4910.3 5043.4 5062.7 5072.3 5130.6 5027.3 5093.4 5154.9 5158.5 5163.0 5003.5 5090.6 5104.1 5107.8 5108.3 5141.2 5180.9 5182.7 5190.1 5219.7 4966.4 4999.0 5044.5 5094.6 5096.0 5339.8 5341.1 5348.2 5359.2 5371.5 5928.5 5939.2 5979.2 5981.6 5983.6 5860.7 5871.2 5986.2 5998.8 6019.9 5500.7 5553.1 5597.6 5606.1 5614.8 6094.1 6118.9 6123.4 6126.4 6132.4 5695.3 5850.7 5916.3 6011.2 6033.0 6059.8 6070.3 6072.2 6073.9 6077.7 5742.7 5998.2 6033.2 6047.1 6048.7 5902.5 5983.2 6087.1 6087.8 6121.8 4578.2 4598.8 4599.7 4617.0 4622.6 4023.9 4067.2 4096.2 4101.6 4148.8 5618.0 5685.4 5699.8 5701.4 5721.5 5997.0 6040.8 6057.6 6065.9 6066.1 5536.6 5626.7 5700.2 5717.9 5729.7 5152.8 5200.5 5216.9 5225.2 5234.5 6201.6 6215.4 6217.8 6221.8 6225.7 5818.5 5987.2 5998.2 6008.1 6008.7 6092.4 6155.7 6185.9 6189.6 6197.8 5914.7 5926.4 5969.2 5985.7 6012.8 5806.9 5921.6 5989.9 6066.1 6074.1 5920.1 5924.3 5927.6 5928.3 5929.0 5887.3 5895.1 5898.8 5902.6 5903.1 5644.9 5648.4 5769.8 5778.0 5805.6 5868.4 5907.4 5916.9 5918.6 5923.6 5784.7 5792.2 5795.7 5795.8 5798.8 16:29:41 INFO - 2016-02-01 16:29:41,863 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,864 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51262.62 Median:35572.56 stddev:87392.08 (245.7%) stddev-sans-first:87554.41 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: Values: 588.5 624.7 625.9 634.5 636.2 33531.5 43491.6 43763.1 43970.3 44028.0 47861.3 48129.3 48358.0 48552.5 48643.0 8097.7 8243.9 9108.5 9252.4 9277.1 10598.3 10833.6 10839.8 10840.8 10853.3 10524.5 10610.0 10617.2 10622.3 10626.7 10453.5 10524.6 10530.3 10538.3 10559.8 10294.6 10436.3 10437.1 10742.8 10749.8 43442.8 43962.0 44040.6 44301.6 44395.8 3927.8 4011.8 4153.0 4236.2 4243.5 32646.3 33100.3 33284.0 33285.0 33473.0 3938.7 4003.3 4020.0 4050.1 4066.2 304096.0 305255.0 306147.0 306464.0 309346.0 59987.0 61011.7 70697.0 72215.0 72335.0 61492.7 61784.0 61915.7 61985.4 62009.4 17859.6 18047.8 18062.7 18068.3 18072.2 13516.1 13544.2 13609.9 13646.7 13710.5 22781.8 22824.0 22930.5 22962.4 22977.3 8615.7 8822.2 8846.3 8866.4 8939.9 957.3 1032.3 1090.5 1137.8 1140.0 28585.8 28596.0 28955.9 29304.0 30025.7 1031.8 1146.4 1162.3 1216.4 1219.6 14887.3 17315.2 17712.4 17719.1 17979.4 428283.0 429337.0 431493.0 432118.0 432585.0 60841.4 61271.7 61281.7 61424.0 61499.4 1378.3 1424.2 1468.1 1468.4 1492.0 30062.8 30162.4 30188.5 30863.2 31101.1 47745.0 48269.8 48275.0 48277.4 48293.3 218018.0 220884.0 235956.0 236008.0 236098.0 35515.3 35629.8 36010.3 36077.8 36160.8 45982.0 46176.3 46745.3 46828.0 47107.8 37720.6 38286.2 39503.4 39573.2 39747.6 43815.8 45772.8 46076.8 46146.3 47976.3 39096.0 39824.8 39844.4 39873.2 40027.4 34121.4 34421.3 34603.3 34789.8 35429.8 27293.3 27614.6 27884.0 28062.6 28071.3 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33026.65 Median:23724.58 stddev:104738.37 (441.5%) stddev-sans-first:105003.52 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: Values: 389.7 392.8 395.7 398.6 398.9 13561.5 17284.5 18881.0 19563.6 19584.1 18429.4 18454.4 18829.8 18882.2 18883.6 5884.5 5999.2 6001.6 6004.6 6005.1 5926.5 6013.5 6061.3 6079.6 6087.7 4585.2 4711.8 6168.3 6170.7 6173.4 6812.4 7012.8 7060.4 7062.5 7067.4 6482.9 6500.5 6504.7 6507.2 6565.5 23699.5 23704.0 23745.2 23954.5 24423.3 2343.6 2366.5 2400.6 2405.9 2454.8 14418.4 14702.1 14750.2 14804.3 14831.6 1956.3 1956.5 1958.5 1988.3 2049.4 61509.0 62347.4 62693.0 63391.7 72097.3 10853.1 11231.9 11273.4 11296.9 11368.2 14728.8 14797.9 14813.6 14839.3 14850.4 8502.9 8507.7 8526.0 8529.0 8536.7 8286.0 8344.5 8365.2 8390.7 8411.7 4680.8 4723.3 4732.9 4798.4 4808.1 1736.9 1928.5 1942.1 1984.6 1989.4 901.9 955.6 1057.8 1072.8 1113.5 17320.7 17706.8 17856.0 18049.7 18240.8 960.6 989.3 992.6 999.4 1017.6 9313.5 9432.7 9467.7 9673.7 9676.7 587078.0 640530.0 652026.0 662481.0 663476.0 84188.6 85852.0 88345.6 88804.0 89816.6 1186.0 1194.4 1213.1 1227.8 1229.4 15865.0 16310.8 16371.1 16431.3 16549.9 34444.7 34503.7 34817.8 38755.0 38841.2 49226.5 51456.0 51974.0 53119.0 53304.7 9972.1 10299.2 10420.8 10444.9 10467.1 12028.6 17952.6 18007.6 18016.8 18614.6 14757.3 14962.9 15066.0 15197.6 15228.5 14898.3 14909.0 15340.9 15587.5 15924.1 16458.3 16505.8 16547.3 16881.7 16931.9 9966.0 10511.7 10555.7 10896.9 10936.0 14959.8 15488.5 15812.2 16004.0 16316.3 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5616.62 Median:6638.83 stddev:2848.39 (42.9%) stddev-sans-first:2839.85 16:29:41 INFO - 2016-02-01 16:29:41,865 DEBUG : BROWSER_OUTPUT: Values: 1534.3 1549.1 1768.9 1811.2 1849.0 7725.6 7914.1 7925.3 7931.4 7943.8 6988.1 7209.9 7213.9 7590.8 7888.8 2329.0 2334.6 2366.3 2366.6 2370.2 2383.4 2407.6 2450.9 2457.6 2462.3 2048.0 2086.2 2090.1 2273.7 2312.4 2355.6 2374.9 2377.2 2377.5 2379.7 2411.7 2432.7 2433.5 2434.7 2435.8 7618.2 7685.8 7698.8 7699.3 7702.4 4621.8 4726.0 4728.7 4729.7 4731.1 3182.8 3234.2 3240.5 3249.8 3258.5 1030.2 1032.0 1046.6 1077.4 1081.4 10695.9 10845.1 10850.1 10855.7 10862.0 4446.9 4497.8 4540.8 4551.1 4554.5 9434.0 9525.7 9529.8 9548.1 9555.2 4835.4 4846.0 4866.0 4872.9 4878.5 4368.8 4446.2 4465.4 4470.0 4474.1 3796.9 3829.9 3835.1 3836.1 3867.8 1233.0 1258.7 1278.4 1280.7 1289.5 1050.5 1112.1 1196.1 1289.7 1407.0 7335.3 7438.7 7500.6 7511.1 7517.6 1133.7 1160.3 1192.4 1205.8 1210.7 6195.3 6259.0 6267.7 6282.9 6286.1 8962.1 9201.9 9303.6 9316.6 9322.4 9335.7 9342.5 9366.8 9384.2 9386.7 7313.4 7495.3 7566.5 7570.5 7571.8 7178.7 7684.8 7716.5 7728.0 7743.8 7978.5 8232.5 8327.7 8399.5 8604.4 8705.9 8760.9 8844.7 8951.6 9022.1 7572.8 7611.0 7673.3 7673.3 7689.6 7473.6 7511.0 7551.8 7563.7 7757.3 6683.7 7133.6 7176.9 7316.7 7383.6 6594.0 7065.7 7268.6 7307.7 7362.7 7300.9 7324.0 7340.5 7343.4 7344.9 6799.3 6838.4 6838.7 6842.4 6846.8 6794.1 6943.8 6962.5 6965.6 6969.2 16:29:41 INFO - 2016-02-01 16:29:41,866 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,866 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5432.75 Median:6241.70 stddev:1633.02 (26.2%) stddev-sans-first:1592.57 16:29:41 INFO - 2016-02-01 16:29:41,867 DEBUG : BROWSER_OUTPUT: Values: 358.7 359.4 361.8 364.3 366.7 6012.1 6073.1 6178.8 6224.6 6226.1 5839.9 5885.5 5939.1 6042.5 6100.8 4676.7 4707.3 4718.0 4733.6 4736.6 4467.5 4723.2 4726.1 4730.8 4732.9 4497.8 4549.2 4652.3 4665.5 4684.3 4630.5 4640.4 4650.0 4655.4 4655.6 4134.1 4137.4 4139.5 4142.0 4142.8 5670.0 5746.0 5762.8 5765.1 5766.3 5401.1 5403.8 5407.1 5409.6 5410.8 6394.4 6397.0 6399.7 6401.7 6485.1 4502.9 4507.4 4511.6 4514.7 4517.6 6596.5 6640.0 6708.2 6718.5 6720.4 4609.6 4610.8 4612.9 4614.3 4614.9 4664.2 4665.1 4666.7 4670.3 4670.4 5352.5 5353.7 5354.5 5360.5 5363.3 6362.6 6486.4 6495.0 6499.7 6507.3 1630.7 1652.9 1654.2 1655.0 1655.6 1095.3 1114.9 1115.0 1134.1 1134.6 6283.2 6302.5 6302.7 6304.5 6307.6 6513.7 6679.8 6685.2 6690.3 6692.2 6168.3 6257.3 6322.4 6347.9 6355.4 3556.6 3598.3 3599.7 3634.1 3636.3 6782.7 6942.9 6944.8 6951.8 6957.6 6875.9 6889.2 6907.0 6915.8 6917.4 6818.6 6820.5 6824.9 6825.6 6831.4 6545.9 6663.4 6717.6 6719.1 6735.7 6606.9 6646.6 6660.4 6660.5 6664.8 6922.1 6933.8 6934.7 6934.9 6936.8 4575.6 4644.1 4648.3 4650.5 4651.1 6756.3 6762.8 6770.8 6778.6 6783.2 6525.3 6552.2 6554.6 6554.7 6559.9 6440.1 6520.3 6534.1 6539.2 6564.5 6437.1 6447.2 6447.2 6451.8 6455.5 6592.7 6595.5 6597.5 6605.5 6605.5 6351.8 6433.9 6455.3 6462.9 6464.4 16:29:41 INFO - 2016-02-01 16:29:41,867 DEBUG : BROWSER_OUTPUT: 16:29:41 INFO - 2016-02-01 16:29:41,867 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14762.42 Median:4737.69 stddev:17182.21 (362.7%) stddev-sans-first:17198.72 16:29:41 INFO - 2016-02-01 16:29:41,867 DEBUG : BROWSER_OUTPUT: Values: 867.1 889.4 912.4 953.7 961.3 14652.9 15227.5 15247.1 15256.9 15261.8 13962.6 14172.6 14194.4 14216.9 14226.6 753.0 756.1 913.5 930.8 931.1 917.1 918.9 924.4 924.5 927.0 967.7 976.7 976.9 977.1 977.5 800.9 801.9 973.9 984.2 986.9 731.8 733.7 733.8 733.8 734.1 14583.9 14755.6 14799.9 14801.0 14810.1 12713.5 13261.2 13542.2 13620.7 13660.9 10859.9 10860.0 10893.7 11014.3 11182.6 3724.1 3725.9 3728.6 3760.8 3836.2 50291.7 50530.2 50796.2 50834.0 51173.2 9949.4 10012.0 10024.1 10057.9 10068.6 19592.9 19603.3 19615.0 19615.8 19620.4 7938.4 8017.1 8040.5 8057.1 8086.8 4477.0 4610.1 4682.1 4705.0 4770.4 3336.3 3379.2 3381.1 3388.3 3388.4 577.7 604.7 607.5 607.5 615.1 685.5 685.8 713.4 715.0 726.3 8644.7 8808.2 9077.9 9503.3 9663.5 642.4 657.9 681.6 694.7 732.8 4062.2 4131.1 4272.7 4281.7 4506.7 60358.1 60534.0 60772.4 60875.7 60904.7 54121.0 54685.7 55205.4 55323.4 57523.4 50685.5 51245.5 51593.2 52441.5 52647.7 8700.2 8956.6 9083.9 9157.6 9327.7 36326.0 36858.6 37033.4 37409.4 37988.2 44399.3 48830.5 48882.8 51111.5 51296.0 11206.7 11818.5 11915.4 11924.9 12027.1 12339.4 12400.5 13218.6 13332.3 13984.0 11045.7 11214.6 11343.5 11431.1 11621.2 11358.6 11379.8 11546.3 11832.5 12480.8 10780.3 10992.7 11025.2 11402.8 11454.4 9833.7 9955.2 10063.8 10196.5 10614.9 9865.3 10228.1 10257.8 10397.4 10398.2 16:29:41 INFO - 2016-02-01 16:29:41,867 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:29:41 INFO - 2016-02-01 16:29:41,867 DEBUG : BROWSER_OUTPUT: 16:29:42 INFO - 2016-02-01 16:29:42,457 INFO : Browser exited with error code: 0 16:29:42 INFO - 2016-02-01 16:29:42,478 INFO : Completed test dromaeo_css (00:18:29) 16:29:42 INFO - 2016-02-01 16:29:42,478 INFO : Starting test kraken 16:29:42 INFO - 2016-02-01 16:29:42,479 DEBUG : operating with platform_type : linux_ 16:29:42 INFO - 2016-02-01 16:29:42,479 INFO : Initialising browser for kraken test... 16:29:42 INFO - 2016-02-01 16:29:42,485 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8ELWTx/profile http://localhost:54385/getInfo.html 16:29:46 INFO - 2016-02-01 16:29:46,566 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:29:46 INFO - 2016-02-01 16:29:46,566 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:29:46 INFO - 2016-02-01 16:29:46,566 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:29:46 INFO - 2016-02-01 16:29:46,567 DEBUG : BROWSER_OUTPUT: __metrics 16:29:46 INFO - 2016-02-01 16:29:46,744 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] 16:29:47 INFO - 2016-02-01 16:29:47,355 INFO : Browser initialized. 16:29:47 INFO - 2016-02-01 16:29:47,356 INFO : Running cycle 1/1 for kraken test... 16:29:47 INFO - 2016-02-01 16:29:47,356 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8ELWTx/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:29:59 INFO - 2016-02-01 16:29:59,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 134967296 16:29:59 INFO - 2016-02-01 16:29:59,176 DEBUG : BROWSER_OUTPUT: 16:30:01 INFO - 2016-02-01 16:30:01,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54385/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:02 INFO - 2016-02-01 16:30:02,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 166674432 16:30:02 INFO - 2016-02-01 16:30:02,164 DEBUG : BROWSER_OUTPUT: 16:30:04 INFO - 2016-02-01 16:30:04,747 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54385/tests/kraken/driver.html?testName=audio-dft) 16:30:05 INFO - 2016-02-01 16:30:05,314 DEBUG : BROWSER_OUTPUT: RSS: Main: 164659200 16:30:05 INFO - 2016-02-01 16:30:05,314 DEBUG : BROWSER_OUTPUT: 16:30:08 INFO - 2016-02-01 16:30:08,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54385/tests/kraken/driver.html?testName=audio-fft) 16:30:08 INFO - 2016-02-01 16:30:08,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 161796096 16:30:08 INFO - 2016-02-01 16:30:08,659 DEBUG : BROWSER_OUTPUT: 16:30:10 INFO - 2016-02-01 16:30:10,652 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54385/tests/kraken/driver.html?testName=audio-oscillator) 16:30:11 INFO - 2016-02-01 16:30:11,226 DEBUG : BROWSER_OUTPUT: RSS: Main: 160997376 16:30:11 INFO - 2016-02-01 16:30:11,226 DEBUG : BROWSER_OUTPUT: 16:30:13 INFO - 2016-02-01 16:30:13,384 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54385/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:13 INFO - 2016-02-01 16:30:13,946 DEBUG : BROWSER_OUTPUT: RSS: Main: 159977472 16:30:13 INFO - 2016-02-01 16:30:13,946 DEBUG : BROWSER_OUTPUT: 16:30:16 INFO - 2016-02-01 16:30:16,811 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54385/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:17 INFO - 2016-02-01 16:30:17,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 161284096 16:30:17 INFO - 2016-02-01 16:30:17,379 DEBUG : BROWSER_OUTPUT: 16:30:20 INFO - 2016-02-01 16:30:20,693 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54385/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:21 INFO - 2016-02-01 16:30:21,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 161427456 16:30:21 INFO - 2016-02-01 16:30:21,267 DEBUG : BROWSER_OUTPUT: 16:30:23 INFO - 2016-02-01 16:30:23,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54385/tests/kraken/driver.html?testName=json-parse-financial) 16:30:24 INFO - 2016-02-01 16:30:24,511 DEBUG : BROWSER_OUTPUT: RSS: Main: 161185792 16:30:24 INFO - 2016-02-01 16:30:24,511 DEBUG : BROWSER_OUTPUT: 16:30:26 INFO - 2016-02-01 16:30:26,064 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54385/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:26 INFO - 2016-02-01 16:30:26,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 168886272 16:30:26 INFO - 2016-02-01 16:30:26,626 DEBUG : BROWSER_OUTPUT: 16:30:28 INFO - 2016-02-01 16:30:28,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:28 INFO - 2016-02-01 16:30:28,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 166293504 16:30:28 INFO - 2016-02-01 16:30:28,984 DEBUG : BROWSER_OUTPUT: 16:30:30 INFO - 2016-02-01 16:30:30,947 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:31 INFO - 2016-02-01 16:30:31,514 DEBUG : BROWSER_OUTPUT: RSS: Main: 170639360 16:30:31 INFO - 2016-02-01 16:30:31,514 DEBUG : BROWSER_OUTPUT: 16:30:33 INFO - 2016-02-01 16:30:33,881 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:34 INFO - 2016-02-01 16:30:34,464 DEBUG : BROWSER_OUTPUT: RSS: Main: 176738304 16:30:34 INFO - 2016-02-01 16:30:34,465 DEBUG : BROWSER_OUTPUT: 16:30:36 INFO - 2016-02-01 16:30:36,946 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:37 INFO - 2016-02-01 16:30:37,519 DEBUG : BROWSER_OUTPUT: RSS: Main: 173932544 16:30:37 INFO - 2016-02-01 16:30:37,519 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,193 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54385/tests/kraken/driver.html?testName=ai-astar) 16:30:39 INFO - 2016-02-01 16:30:39,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 232042496 16:30:39 INFO - 2016-02-01 16:30:39,444 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,445 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:30:39 INFO - 2016-02-01 16:30:39,446 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:30:39 INFO - 2016-02-01 16:30:39,446 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:30:39 INFO - 2016-02-01 16:30:39,446 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:30:39 INFO - 2016-02-01 16:30:39,447 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;105;108;109;110;98;110;110;108 16:30:39 INFO - 2016-02-01 16:30:39,447 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;162;122;149;153;122;157;150;122;161;122 16:30:39 INFO - 2016-02-01 16:30:39,447 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;160;159;160;161;161;160;161;160 16:30:39 INFO - 2016-02-01 16:30:39,448 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;71;152;71;72;70;73;151;74 16:30:39 INFO - 2016-02-01 16:30:39,448 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;139;92;93;92;144;92;94;135 16:30:39 INFO - 2016-02-01 16:30:39,448 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 16:30:39 INFO - 2016-02-01 16:30:39,448 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;145;147;145;146;144;146;145;145 16:30:39 INFO - 2016-02-01 16:30:39,449 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;96;95;96;95;96;96;95 16:30:39 INFO - 2016-02-01 16:30:39,449 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;57;58;58;59;57;56;58;54;55 16:30:39 INFO - 2016-02-01 16:30:39,449 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;65;64;64;64;64;66;64;64;65 16:30:39 INFO - 2016-02-01 16:30:39,449 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;76;78;77;78;91;79;80;77;78 16:30:39 INFO - 2016-02-01 16:30:39,449 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;126;118;113;120;118;120;119;122;112 16:30:39 INFO - 2016-02-01 16:30:39,449 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;147;156;135;142;149;135;137;144;137;136 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;68;64;65;67;66;62;64;68 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,896 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: __startTimestamp1454373039444__endTimestamp 16:30:39 INFO - 2016-02-01 16:30:39,450 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,451 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:110.00 stddev:3.87 (3.5%) stddev-sans-first:4.03 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 105.0 108.0 109.0 110.0 98.0 110.0 110.0 108.0 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:151.50 stddev:17.70 (11.7%) stddev-sans-first:17.23 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: Values: 162.0 122.0 149.0 153.0 122.0 157.0 150.0 122.0 161.0 122.0 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,452 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 16:30:39 INFO - 2016-02-01 16:30:39,453 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 160.0 159.0 160.0 161.0 161.0 160.0 161.0 160.0 16:30:39 INFO - 2016-02-01 16:30:39,453 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,453 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.00 Median:72.50 stddev:33.49 (46.2%) stddev-sans-first:35.08 16:30:39 INFO - 2016-02-01 16:30:39,453 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 71.0 152.0 71.0 72.0 70.0 73.0 151.0 74.0 16:30:39 INFO - 2016-02-01 16:30:39,454 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,454 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.60 Median:92.00 stddev:22.70 (24.7%) stddev-sans-first:23.45 16:30:39 INFO - 2016-02-01 16:30:39,454 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 139.0 92.0 93.0 92.0 144.0 92.0 94.0 135.0 16:30:39 INFO - 2016-02-01 16:30:39,454 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,454 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 16:30:39 INFO - 2016-02-01 16:30:39,454 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 16:30:39 INFO - 2016-02-01 16:30:39,454 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 145.0 147.0 145.0 146.0 144.0 146.0 145.0 145.0 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 95.0 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.10 Median:58.00 stddev:1.66 (2.9%) stddev-sans-first:1.62 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: Values: 59.0 57.0 58.0 58.0 59.0 57.0 56.0 58.0 54.0 55.0 16:30:39 INFO - 2016-02-01 16:30:39,455 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 64.0 64.0 64.0 64.0 66.0 64.0 64.0 65.0 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:78.00 stddev:4.40 (5.6%) stddev-sans-first:4.53 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 78.0 77.0 78.0 91.0 79.0 80.0 77.0 78.0 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:119.50 stddev:4.12 (3.4%) stddev-sans-first:4.27 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: Values: 116.0 126.0 118.0 113.0 120.0 118.0 120.0 119.0 122.0 112.0 16:30:39 INFO - 2016-02-01 16:30:39,456 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.80 Median:143.00 stddev:7.13 (5.0%) stddev-sans-first:7.31 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: Values: 147.0 156.0 135.0 142.0 149.0 135.0 137.0 144.0 137.0 136.0 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.99 (3.0%) stddev-sans-first:2.06 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 68.0 64.0 65.0 67.0 66.0 62.0 64.0 68.0 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:30:39 INFO - 2016-02-01 16:30:39,457 DEBUG : BROWSER_OUTPUT: 16:30:40 INFO - 2016-02-01 16:30:40,093 INFO : Browser exited with error code: 0 16:30:40 INFO - 2016-02-01 16:30:40,147 INFO : Completed test kraken (00:00:57) 16:30:40 INFO - 2016-02-01 16:30:40,147 INFO : Starting test v8_7 16:30:40 INFO - 2016-02-01 16:30:40,147 DEBUG : operating with platform_type : linux_ 16:30:40 INFO - 2016-02-01 16:30:40,147 INFO : Initialising browser for v8_7 test... 16:30:40 INFO - 2016-02-01 16:30:40,153 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpwYthym/profile http://localhost:54385/getInfo.html 16:30:44 INFO - 2016-02-01 16:30:44,218 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:30:44 INFO - 2016-02-01 16:30:44,219 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:30:44 INFO - 2016-02-01 16:30:44,219 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:30:44 INFO - 2016-02-01 16:30:44,219 DEBUG : BROWSER_OUTPUT: __metrics 16:30:44 INFO - 2016-02-01 16:30:44,361 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] 16:30:45 INFO - 2016-02-01 16:30:45,074 INFO : Browser initialized. 16:30:45 INFO - 2016-02-01 16:30:45,074 INFO : Running cycle 1/1 for v8_7 test... 16:30:45 INFO - 2016-02-01 16:30:45,075 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpwYthym/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:30:56 INFO - 2016-02-01 16:30:56,884 DEBUG : BROWSER_OUTPUT: RSS: Main: 134471680 16:30:56 INFO - 2016-02-01 16:30:56,884 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54385/tests/v8_7/run.html (next: http://localhost:54385/tests/v8_7/run.html) 16:31:18 INFO - 2016-02-01 16:31:18,454 DEBUG : BROWSER_OUTPUT: RSS: Main: 421666816 16:31:18 INFO - 2016-02-01 16:31:18,454 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,455 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:31:18 INFO - 2016-02-01 16:31:18,455 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:31:18 INFO - 2016-02-01 16:31:18,455 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |0;Richards;168.15200941651253 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.18004625041294 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.1120448179272 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5164.948453608247 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |4;RayTrace;938.9671361502348 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |5;Earley;545.5537370430987 16:31:18 INFO - 2016-02-01 16:31:18,456 DEBUG : BROWSER_OUTPUT: |6;Boyer;10610.526315789473 16:31:18 INFO - 2016-02-01 16:31:18,457 DEBUG : BROWSER_OUTPUT: |7;RegExp;26315.78947368421 16:31:18 INFO - 2016-02-01 16:31:18,457 DEBUG : BROWSER_OUTPUT: |8;Splay;523.0125523012553 16:31:18 INFO - 2016-02-01 16:31:18,457 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 16:31:18 INFO - 2016-02-01 16:31:18,457 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:31:18 INFO - 2016-02-01 16:31:18,457 DEBUG : BROWSER_OUTPUT: __startTimestamp1454373078454__endTimestamp 16:31:18 INFO - 2016-02-01 16:31:18,458 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,458 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:31:18 INFO - 2016-02-01 16:31:18,458 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:31:18 INFO - 2016-02-01 16:31:18,458 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,458 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.15 Median:168.15 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,458 DEBUG : BROWSER_OUTPUT: Values: 168.2 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.18 Median:165.18 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: Values: 165.2 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.11 Median:280.11 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: Values: 280.1 16:31:18 INFO - 2016-02-01 16:31:18,460 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: Values: 5164.9 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:938.97 Median:938.97 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: Values: 939.0 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:545.55 Median:545.55 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: Values: 545.6 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10610.53 Median:10610.53 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,461 DEBUG : BROWSER_OUTPUT: Values: 10610.5 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26315.79 Median:26315.79 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: Values: 26315.8 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:523.01 Median:523.01 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: Values: 523.0 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: Values: 4826.9 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:31:18 INFO - 2016-02-01 16:31:18,462 DEBUG : BROWSER_OUTPUT: 16:31:19 INFO - 2016-02-01 16:31:19,114 INFO : Browser exited with error code: 0 16:31:19 INFO - 2016-02-01 16:31:19,125 INFO : Completed test v8_7 (00:00:38) 16:31:19 INFO - 2016-02-01 16:31:19,511 INFO : Completed test suite (00:20:06) 16:31:19 INFO - 2016-02-01 16:31:19,511 DEBUG : Working with test: dromaeo_css 16:31:19 INFO - 2016-02-01 16:31:19,511 DEBUG : Generating results file: dromaeo_css 16:31:19 INFO - 2016-02-01 16:31:19,512 DEBUG : Working with test: kraken 16:31:19 INFO - 2016-02-01 16:31:19,512 DEBUG : Generating results file: kraken 16:31:19 INFO - 2016-02-01 16:31:19,512 INFO : javascript benchmark 16:31:19 INFO - 2016-02-01 16:31:19,513 DEBUG : Working with test: v8_7 16:31:19 INFO - 2016-02-01 16:31:19,513 DEBUG : Generating results file: v8_7 16:31:19 INFO - 2016-02-01 16:31:19,513 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:31:19 INFO - 2016-02-01 16:31:19,565 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:31:19 INFO - 2016-02-01 16:31:19,565 DEBUG : process_Request line: dromaeo_css 7464.16 graph.html#tests=[[72,53,35]] 16:31:19 INFO - 2016-02-01 16:31:19,566 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:31:19 INFO - 2016-02-01 16:31:19,595 DEBUG : process_Request line: kraken 1480.80 graph.html#tests=[[232,53,35]] 16:31:19 INFO - 2016-02-01 16:31:19,595 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:31:19 INFO - 2016-02-01 16:31:19,623 DEBUG : process_Request line: v8_7 23084.07 graph.html#tests=[[230,53,35]] 16:31:19 INFO - 2016-02-01 16:31:19,624 INFO : javascript benchmark 16:31:19 INFO - 2016-02-01 16:31:19,628 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3275.328557645683, 3359.344927739092, 3382.9323413531747, 3389.4407422775244, 3419.316136772645, 5549.472800083996, 5596.048671725806, 5627.268455100836, 5631.493165615094, 5653.293338332704, 5575.944240557593, 5605.495505404513, 5611.719414029292, 5621.831345059644, 5630.662160270386, 4910.337104490884, 5043.369578802641, 5062.721550314731, 5072.3405957225505, 5130.589552835764, 5027.271045698371, 5093.414257360396, 5154.896902061956, 5158.535731784139, 5162.974185129088, 5003.549680528752, 5090.592752579785, 5104.106781313274, 5107.821226257081, 5108.335916330871, 5141.15170996785, 5180.948190518122, 5182.740862956837, 5190.11767999441, 5219.712915789649, 4966.428860681031, 4998.975005124988, 5044.47033061527, 5094.566961808251, 5096.0, 5339.786408543654, 5341.145416733343, 5348.197770334442, 5359.169328754049, 5371.5165635092635, 5928.525717942554, 5939.198208241875, 5979.222701048836, 5981.611195272337, 5983.551233657449, 5860.706964651793, 5871.207387002785, 5986.221791167164, 5998.7600495980105, 6019.879602407971, 5500.724963751796, 5553.139263414178, 5597.636153650041, 5606.074997625387, 5614.775408983676, 6094.11635312886, 6118.908216376758, 6123.448889599868, 6126.448619624255, 6132.41742034506, 5695.259616249861, 5850.678212698257, 5916.26046744157, 6011.248593925759, 6033.0, 6059.818205453844, 6070.3019152797115, 6072.240969878765, 6073.878522429527, 6077.696115194222, 5742.7415766290615, 5998.220231369894, 6033.215681961405, 6047.092936059538, 6048.667323297261, 5902.468777810018, 5983.1623572699855, 6087.086936959403, 6087.817365479043, 6121.785737499166, 4578.221702310615, 4598.816047358135, 4599.701019433726, 4617.0, 4622.583967442879, 4023.8591649292134, 4067.2272268268935, 4096.180763847243, 4101.630853223224, 4148.751074935513, 5618.044932361427, 5685.403032681592, 5699.800506982236, 5701.4298570142655, 5721.542276617861, 5997.0404735242155, 6040.818775436744, 6057.636541807506, 6065.878682426327, 6066.090086486974, 5536.640118392292, 5626.690532020778, 5700.201971723962, 5717.885642287131, 5729.656220626775, 5152.768125434364, 5200.505951934636, 5216.947830521722, 5225.242339860775, 5234.450382709837, 6201.596896201732, 6215.378462153839, 6217.813465596039, 6221.813345599639, 6225.657588832567, 5818.4763371296785, 5987.161797348288, 5998.220231369894, 6008.068749343859, 6008.729607167776, 6092.360302168208, 6155.72299246526, 6185.907211391743, 6189.566730328834, 6197.81406557804, 5914.7338369774325, 5926.376817378955, 5969.194158788508, 5985.670788106696, 6012.849678758066, 5806.854828629318, 5921.5558833086825, 5989.850253743692, 6066.059760737093, 6074.119252708244, 5920.141579470866, 5924.259467566554, 5927.644341339534, 5928.318243401892, 5928.970355148197, 5887.264091988502, 5895.056790913605, 5898.764049437975, 5902.586818922634, 5903.0555111182, 5644.88710225785, 5648.378678345297, 5769.769209231584, 5778.04662230744, 5805.593608447537, 5868.354481007171, 5907.350191478848, 5916.852078697895, 5918.556108291809, 5923.644581325134, 5784.739686714192, 5792.247007888947, 5795.710214489343, 5795.797147099831, 5798.82603521895], "unit": "ms", "name": "dojo.html", "value": 5529.629700691028}, {"lowerIsBetter": false, "replicates": [588.4644973074503, 624.6689254695011, 625.9186305780248, 634.5431289471582, 636.2460484326077, 33531.497027544574, 43491.565084349146, 43763.12473750527, 43970.34044489335, 44028.0, 47861.28208076882, 48129.278060829114, 48357.95525559305, 48552.51447485524, 48643.02713945719, 8097.716579919704, 8243.876341854877, 9108.453492790437, 9252.444853308789, 9277.072292770736, 10598.258121931467, 10833.620823271187, 10839.783204335909, 10840.837387439195, 10853.348799072042, 10524.526396312185, 10610.0, 10617.203709721764, 10622.25644204906, 10626.734331641732, 10453.477326133701, 10524.631637892713, 10530.31552949056, 10538.262321637489, 10559.78880422395, 10294.588216471333, 10436.321639093434, 10437.060664540228, 10742.785144297148, 10749.83875241872, 43442.78278608587, 43962.0, 44040.55959440397, 44301.55698443006, 44395.77802111001, 3927.8428862845453, 4011.799410029487, 4153.0447996960565, 4236.237477254093, 4243.511996120455, 32646.347073058645, 33100.33799324024, 33284.0, 33285.0, 33473.0, 3938.7439816411843, 4003.2994226010624, 4019.97990010051, 4050.06848424861, 4066.1664358806374, 304096.0, 305255.0, 306147.0, 306464.0, 309346.0, 59987.0, 61011.69494152589, 70697.0, 72215.0, 72335.0, 61492.69253653793, 61784.0, 61915.690421548505, 61985.38014619886, 62009.37990620126, 17859.553511162325, 18047.819521804875, 18062.729059064124, 18068.277268909096, 18072.18675159621, 13516.053876228767, 13544.187348759106, 13609.931950340382, 13646.658833529242, 13710.451581936612, 22781.772182278295, 22824.0, 22930.541389172457, 22962.425939351317, 22977.276704246913, 8615.698450554326, 8822.161894620001, 8846.33652476054, 8866.379353445196, 8939.910600893907, 957.3155194036298, 1032.339302846179, 1090.536521978172, 1137.8179491281355, 1139.9544018239344, 28585.805744463625, 28596.0, 28955.855220723763, 29304.0, 30025.699743002726, 1031.83490641497, 1146.3694967767694, 1162.273579013117, 1216.367488905764, 1219.6341097670663, 14887.330070146869, 17315.220815063352, 17712.380066697602, 17719.114044297734, 17979.370722024927, 428283.0, 429337.0, 431493.0, 432118.0, 432585.0, 60841.39158608357, 61271.6936415324, 61281.69359153176, 61424.0, 61499.385006149365, 1378.3315092180285, 1424.2451500704642, 1468.067776961637, 1468.4493315006873, 1491.9627009324636, 30062.849685751433, 30162.396752065273, 30188.547171792, 30863.2284192897, 31101.066967990995, 47745.0, 48269.75865120652, 48275.0, 48277.3791965611, 48293.275600865316, 218018.0, 220884.0, 235956.0, 236008.0, 236098.0, 35515.28969420546, 35629.82185089058, 36010.27979440449, 36077.81961090178, 36160.81919590385, 45982.0, 46176.30735538902, 46745.2988205184, 46828.0, 47107.76446117885, 37720.62279377171, 38286.23427531378, 39503.407448888865, 39573.2085358297, 39747.60252397555, 43815.78092109519, 45772.7711361441, 46076.7696161517, 46146.307805383614, 47976.28035579399, 39096.0, 39824.80087599543, 39844.40233396443, 39873.20253594854, 40027.3995890056, 34121.371926249936, 34421.31157376788, 34603.30793384169, 34789.82605086958, 35429.82285088661, 27293.317667058483, 27614.5857812137, 27884.0, 28062.57906131369, 28071.298217544725], "unit": "ms", "name": "ext.html", "value": 20584.842075501754}, {"lowerIsBetter": false, "replicates": [389.65320864430646, 392.82519278920876, 395.66763918308624, 398.5775078416878, 398.9401589761534, 13561.488213575674, 17284.481465556037, 18881.0, 19563.608727825434, 19584.118714657838, 18429.354972575962, 18454.354097606585, 18829.811701882976, 18882.244710211577, 18883.62232755344, 5884.499817515506, 5999.220101386835, 6001.639901605896, 6004.639721616717, 6005.069214271773, 5926.496247818941, 6013.458788709016, 6061.272647282332, 6079.604825686316, 6087.695615219243, 4585.220512512881, 4711.764411779414, 6168.259808822948, 6170.722317495701, 6173.41352571505, 6812.386885180333, 7012.824679383005, 7060.435165186824, 7062.540934839219, 7067.363937245621, 6482.93517064828, 6500.512461565401, 6504.707288172043, 6507.186601674791, 6565.474762019075, 23699.526009479712, 23704.0, 23745.168919087748, 23954.52090958171, 24423.26730198097, 2343.55472460232, 2366.538524987611, 2400.59189937711, 2405.87970601471, 2454.828162028659, 14418.351174197187, 14702.117872927658, 14750.188739619316, 14804.259787010606, 14831.629209269746, 1956.344624550777, 1956.4619729574338, 1958.4614231086566, 1988.2742798878305, 2049.405672355015, 61509.0, 62347.376526235064, 62693.0, 63391.68304158496, 72097.27902720959, 10853.131749460024, 11231.943840280746, 11273.379964101889, 11296.887031129583, 11368.204225704287, 14728.779068313985, 14797.852021479648, 14813.555593332218, 14839.258037098101, 14850.405983760744, 8502.914970850336, 8507.702230421906, 8525.95737021311, 8529.0, 8536.74389768308, 8285.95857020711, 8344.541050242173, 8365.205305495969, 8390.706325278708, 8411.663533458715, 4680.81276748933, 4723.338732577442, 4732.905341893212, 4798.4001999750035, 4808.08646357191, 1736.9247379761414, 1928.53715108373, 1942.1066309497508, 1984.583237520137, 1989.44295597233, 901.900790912999, 955.6368579939578, 1057.8307470804634, 1072.8122578548723, 1113.4544073403974, 17320.6618650568, 17706.822931770774, 17856.0, 18049.7292540612, 18240.817591824176, 960.5869476125193, 989.2877128467636, 992.5979978108877, 999.4253304349883, 1017.5624481473037, 9313.534323283944, 9432.717018489457, 9467.715968520955, 9673.661421850202, 9676.661316854017, 587078.0, 640530.0, 652026.0, 662481.0, 663476.0, 84188.57905710554, 85852.0, 88345.55827220823, 88804.0, 89816.550917245, 1185.9644210673694, 1194.3729541990524, 1213.071999920062, 1227.7974134267924, 1229.4344601483417, 15865.0, 16310.836891630932, 16371.099589522215, 16431.342746290014, 16549.924254923873, 34444.65555344515, 34503.654963451045, 34817.825910870284, 38755.03112422212, 38841.223175535764, 49226.50773492362, 51456.0, 51974.0, 53119.0, 53304.73347633237, 9972.102510774064, 10299.227557933335, 10420.791584168426, 10444.895551044392, 10467.110295624958, 12028.63914082579, 17952.640947181244, 18007.63984720272, 18016.819831801513, 18614.627707446045, 14757.335919883417, 14962.850371496146, 15066.0, 15197.6200594986, 15228.543143705707, 14898.32957516936, 14909.030912990622, 15340.92329538345, 15587.454439094578, 15924.124173170587, 16458.259378327766, 16505.752413713562, 16547.338106475607, 16881.66236675284, 16931.91534042322, 9965.950170249393, 10511.684649460529, 10555.683329500127, 10896.94551527269, 10936.0, 14959.775603366175, 15488.457903973307, 15812.209389530248, 16004.0, 16316.347346106022], "unit": "ms", "name": "jquery.html", "value": 9838.009656058715}, {"lowerIsBetter": false, "replicates": [1534.3325653340792, 1549.0705576654016, 1768.9203985820636, 1811.2392795026087, 1849.0, 7725.61371931404, 7914.129445760969, 7925.2867241948215, 7931.444798864082, 7943.761687149395, 6988.05661235733, 7209.891851622217, 7213.89179162313, 7590.77227683169, 7888.7633370998965, 2329.0, 2334.6264597664376, 2366.2546297916188, 2366.6213405855065, 2370.2415227127326, 2383.4160630645515, 2407.6027455469825, 2450.9387265318333, 2457.5576396248666, 2462.2859370782453, 2048.0476578391044, 2086.2385229391316, 2090.1012564597218, 2273.6930514380597, 2312.4450131968374, 2355.5642206191824, 2374.9287521374386, 2377.2155188787747, 2377.4531857672746, 2379.7263314718857, 2411.746766589518, 2432.72023717273, 2433.537627850703, 2434.7443518430664, 2435.8416702914255, 7618.238176182393, 7685.807854803675, 7698.846023079507, 7699.345555627779, 7702.422318326148, 4621.792019359137, 4726.031163611486, 4728.739919304437, 4729.692569982974, 4731.148393289207, 3182.840857957093, 3234.1752853022563, 3240.481522956339, 3249.772515923886, 3258.4786434170655, 1030.2479190191202, 1032.0144262229628, 1046.5604446132643, 1077.407425915751, 1081.416035340915, 10695.94652026735, 10845.132389408907, 10850.077743391821, 10855.728606784814, 10862.02241798234, 4446.888827779331, 4497.8200871964755, 4540.77296135192, 4551.112533056023, 4554.521775213621, 9434.0, 9525.714228573153, 9529.809403811885, 9548.140667339972, 9555.23558115349, 4835.419749630067, 4846.0, 4865.9756701216975, 4872.902541949141, 4878.512148785093, 4368.825246990084, 4446.155230506193, 4465.419495465634, 4469.9776501117285, 4474.149911516802, 3796.886093417202, 3829.9042523937123, 3835.0987517933263, 3836.1176929306416, 3867.806609669505, 1232.950681972729, 1258.704204511939, 1278.4310981613278, 1280.6862318731785, 1289.4648720780958, 1050.5062620568322, 1112.054753459553, 1196.0730433913786, 1289.7162624222656, 1406.9507567235098, 7335.303146201102, 7438.702451901863, 7500.624968751541, 7511.136219334737, 7517.586532740752, 1133.739239974794, 1160.3154139057997, 1192.356127691037, 1205.7950148474781, 1210.6973256685828, 6195.256569211723, 6258.968705156445, 6267.749290028348, 6282.937170628326, 6286.119943207955, 8962.058983806737, 9201.90798092024, 9303.627854885865, 9316.627334906529, 9322.394044387092, 9335.719928402159, 9342.486163261086, 9366.765830854283, 9384.202342800938, 9386.671466498776, 7313.414926805843, 7495.325420712271, 7566.546007239584, 7570.507916985378, 7571.772846814604, 7178.676959536729, 7684.769456916301, 7716.498427602188, 7727.961360193163, 7743.7676869694, 7978.521288722695, 8232.464889782144, 8327.708530201415, 8399.530082235462, 8604.354673399395, 8705.910581460277, 8760.868586971328, 8844.73465796027, 8951.597178126869, 9022.052684468037, 7572.81067973283, 7610.96194519046, 7673.34776544, 7673.34776544, 7689.615519223905, 7473.551586904586, 7510.962445187958, 7551.773446796605, 7563.73526926577, 7757.340626046849, 6683.699233534599, 7133.6076515790055, 7176.928230717626, 7316.743913962986, 7383.630818458948, 6593.96703016501, 7065.6820443081215, 7268.636568171676, 7307.671154798153, 7362.668679909524, 7300.890486642811, 7323.963380183065, 7340.522866013692, 7343.37581305595, 7344.853102938018, 6799.3200679931615, 6838.418734407631, 6838.658067096526, 6842.418394436529, 6846.760363387258, 6794.116764820542, 6943.826404339729, 6962.547434416747, 6965.6168910708375, 6969.198542167513], "unit": "ms", "name": "mootools.html", "value": 4648.443701301217}, {"lowerIsBetter": false, "replicates": [358.7273672009275, 359.4338916206974, 361.7720835873399, 364.2787281183259, 366.6828193612526, 6012.128241404999, 6073.058675905237, 6178.752849885999, 6224.595401298918, 6226.066090086478, 5839.883202335951, 5885.470307672319, 5939.109133629947, 6042.456178943894, 6100.755969761204, 4676.7427791471455, 4707.317438971347, 4717.952820471803, 4733.5739783419485, 4736.550027747376, 4467.486239082498, 4723.220668589679, 4726.0547890421885, 4730.787114579835, 4732.905341893177, 4497.7526236057, 4549.1584056949405, 4652.302154676791, 4665.463471700764, 4684.250519916814, 4630.490646028951, 4640.443146822403, 4650.046740418223, 4655.4180727409075, 4655.580997710188, 4134.069834287291, 4137.358709400048, 4139.523954745213, 4141.979290103561, 4142.834286628531, 5670.0, 5746.0, 5762.769489220426, 5765.106408506688, 5766.279215098113, 5401.108817045183, 5403.783848646089, 5407.134858422633, 5409.56723462122, 5410.837674869721, 6394.392532709385, 6396.968015159941, 6399.744010239585, 6401.679916004227, 6485.092087107809, 4502.864914052584, 4507.391502147234, 4511.6165125964335, 4514.729116253003, 4517.616002639779, 6596.472282217411, 6640.037194606755, 6708.228553716336, 6718.462522998148, 6720.361565651256, 4609.562091601292, 4610.815567377334, 4612.930806037912, 4614.330922016289, 4614.861554153381, 4664.230401983635, 4665.066986602693, 4666.69666471685, 4670.252759558454, 4670.369500117508, 5352.545033672103, 5353.678779273256, 5354.464553544614, 5360.517553420211, 5363.329583802025, 6362.554621176473, 6486.35136486357, 6495.0, 6499.707513161919, 6507.34926507355, 1630.6820170066724, 1652.892561983474, 1654.247317470548, 1655.0, 1655.5530006898312, 1095.309954728516, 1114.9331040137617, 1115.0, 1134.0530656901415, 1134.597217987619, 6283.214598175228, 6302.527310451735, 6302.747890084345, 6304.527160462983, 6307.621542707452, 6513.706883190268, 6679.76620818269, 6685.197776266782, 6690.297518760557, 6692.163479565054, 6168.290646575611, 6257.342978987232, 6322.399372059647, 6347.936520634735, 6355.396237357444, 3556.608773034964, 3598.298331825269, 3599.676029157336, 3634.055145662147, 3636.2727454509204, 6782.660866956731, 6942.895856562156, 6944.756933507303, 6951.7914462566205, 6957.582545047313, 6875.896861547081, 6889.207741109836, 6906.965465172742, 6915.827104322331, 6917.446604271646, 6818.556793808387, 6820.488463365267, 6824.897626535606, 6825.624590647462, 6831.419329356961, 6545.908806995628, 6663.400293973566, 6717.630530320879, 6719.059331693665, 6735.730570777114, 6606.933930660632, 6646.634435106115, 6660.400563949268, 6660.500462465237, 6664.83337916556, 6922.10012698346, 6933.757318493828, 6934.687939042654, 6934.895976560457, 6936.826579335557, 4575.58819706228, 4644.117617652635, 4648.349231107715, 4650.5116962719785, 4651.069786042737, 6756.256811750605, 6762.830929226809, 6770.763023294161, 6778.627175505395, 6783.18601767791, 6525.2822189558165, 6552.213734351908, 6554.573952693059, 6554.656295459515, 6559.868802623825, 6440.0983862260255, 6520.250171230369, 6534.085228068169, 6539.215294164731, 6564.540482166202, 6437.098806167047, 6447.194100737408, 6447.2263328400895, 6451.838704032437, 6455.4512866406885, 6592.736290548324, 6595.538312318284, 6597.538172327891, 6605.471562275103, 6605.504587155887, 6351.809445716636, 6433.8713225736155, 6455.289918109098, 6462.903056454251, 6464.353564643498], "unit": "ms", "name": "prototype.html", "value": 4908.782545873383}, {"lowerIsBetter": false, "replicates": [867.1415298854132, 889.3907673243824, 912.4114945859916, 953.6519170502769, 961.3414810854564, 14652.926735366322, 15227.543173704778, 15247.085174889515, 15256.923715381436, 15261.771073433894, 13962.58112256634, 14172.574822755334, 14194.432222711079, 14216.928915355435, 14226.644333891632, 752.9924700753005, 756.1493320014984, 913.5021413329735, 930.8371035068869, 931.0875342164683, 917.0691747875911, 918.8805455290803, 924.3575714878162, 924.4591913730442, 926.9907300926972, 967.7000129959699, 976.7216343342149, 976.8681228034195, 977.0571398600349, 977.5210147027976, 800.8878756974028, 801.939854510914, 973.8636590877247, 984.1782111936542, 986.9062439068277, 731.8097294703415, 733.6588486353871, 733.7615275035635, 733.7725305155419, 734.0750654175777, 14583.92708036453, 14755.631109222248, 14799.852001479954, 14801.037932534351, 14810.111393316327, 12713.491460341575, 13261.20432774027, 13542.187468751808, 13620.659483512893, 13660.863391366058, 10859.945700271526, 10860.0, 10893.673189804318, 11014.284071535325, 11182.608608698656, 3724.0875985383755, 3725.9441108383394, 3728.571214310362, 3760.755550889211, 3836.232753449321, 50291.74854125706, 50530.242046369334, 50796.23805642918, 50834.0, 51173.232401514004, 9949.403035817873, 10012.0, 10024.097831195082, 10057.949710251549, 10068.647597334058, 19592.902035489733, 19603.31388401399, 19615.0, 19615.803841961682, 19620.411387658107, 7938.364930805522, 8017.078036025815, 8040.475186627362, 8057.113717491071, 8086.83826323482, 4476.955230447654, 4610.124076425468, 4682.110399024243, 4704.976475117603, 4770.403699537558, 3336.3160552086765, 3379.2396710739886, 3381.053305074583, 3388.3053973935284, 3388.3562123196516, 577.6967092276504, 604.7369394313488, 607.5139888089511, 607.5443417436923, 615.0865964043392, 685.5201359048685, 685.8353995041253, 713.3651050565002, 714.9535280206874, 726.3100054947821, 8644.654213831376, 8808.20726134651, 9077.863832042525, 9503.334766566411, 9663.516824158763, 642.4346575013958, 657.9342065793383, 681.6489507903434, 694.7464175575878, 732.8204589875514, 4062.1875624875142, 4131.111810960598, 4272.739541835166, 4281.7002809803635, 4506.729596224237, 60358.094628580606, 60534.0, 60772.39227607756, 60875.69562152161, 60904.695476522335, 54121.0, 54685.72657136768, 55205.44794552003, 55323.44676553264, 57523.424765751806, 50685.49314506882, 51245.487545124815, 51593.2261016085, 52441.475585245185, 52647.73676131595, 8700.216980471789, 8956.641734330553, 9083.909160908306, 9157.587908543997, 9327.720168394959, 36326.0, 36858.63141368552, 37033.44449833308, 37409.43885841769, 37988.240235194586, 44399.334009990525, 48830.511694882596, 48882.75558622326, 51111.48888511216, 51296.0, 11206.719832004264, 11818.527258909548, 11915.404229788302, 11924.880751192377, 12027.097967652637, 12339.444724987217, 12400.503979840705, 13218.60344189676, 13332.333383330988, 13984.0, 11045.72385690332, 11214.607488737855, 11343.546258149912, 11431.142664300378, 11621.186516943733, 11358.60244891425, 11379.829302560634, 11546.307221566733, 11832.52669893229, 12480.750384992069, 10780.29928054674, 10992.670219893416, 11025.228234023542, 11402.82895756581, 11454.370009649216, 9833.655822046194, 9955.212031336507, 10063.798724025331, 10196.541155647865, 10614.946925265323, 9865.309428340235, 10228.130608898327, 10257.794844103226, 10397.376157430579, 10398.168146548107], "unit": "ms", "name": "yui.html", "value": 6767.265883627005}], "name": "dromaeo_css", "value": 7464.1615617815605}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 105.0, 108.0, 109.0, 110.0, 98.0, 110.0, 110.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [162.0, 122.0, 149.0, 153.0, 122.0, 157.0, 150.0, 122.0, 161.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 159.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 71.0, 152.0, 71.0, 72.0, 70.0, 73.0, 151.0, 74.0], "unit": "ms", "name": "audio-fft", "value": 88.0}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 139.0, 92.0, 93.0, 92.0, 144.0, 92.0, 94.0, 135.0], "unit": "ms", "name": "audio-oscillator", "value": 106.6}, {"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, 145.0, 145.0, 147.0, 145.0, 146.0, 144.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 58.0, 58.0, 59.0, 57.0, 56.0, 58.0, 54.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.5}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 78.0, 77.0, 78.0, 91.0, 79.0, 80.0, 77.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.0}, {"lowerIsBetter": true, "replicates": [116.0, 126.0, 118.0, 113.0, 120.0, 118.0, 120.0, 119.0, 122.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.4}, {"lowerIsBetter": true, "replicates": [147.0, 156.0, 135.0, 142.0, 149.0, 135.0, 137.0, 144.0, 137.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.8}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 68.0, 64.0, 65.0, 67.0, 66.0, 62.0, 64.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.2}], "name": "kraken", "value": 1480.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.15200941651253], "unit": "score", "name": "Richards", "value": 209.94099399999996}, {"lowerIsBetter": false, "replicates": [165.18004625041294], "unit": "score", "name": "DeltaBlue", "value": 400.27837199999993}, {"lowerIsBetter": false, "replicates": [280.1120448179272], "unit": "score", "name": "Encrypt", "value": 950.2661699999999}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [938.9671361502348], "unit": "score", "name": "RayTrace", "value": 788.0882850000002}, {"lowerIsBetter": false, "replicates": [545.5537370430987], "unit": "score", "name": "Earley", "value": 1221.626679}, {"lowerIsBetter": false, "replicates": [10610.526315789473], "unit": "score", "name": "Boyer", "value": 62.81149305555559}, {"lowerIsBetter": false, "replicates": [26315.78947368421], "unit": "score", "name": "RegExp", "value": 34.61742999999998}, {"lowerIsBetter": false, "replicates": [523.0125523012553], "unit": "score", "name": "Splay", "value": 155.810792}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23084.07044318318}]} 16:31:19 INFO - RETURN: dromaeo_css: 7464.16 16:31:19 INFO - RETURN: kraken: 1480.80 16:31:19 INFO - RETURN: v8_7: 23084.07 16:31:19 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23084.07"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1480.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7464.16"}}} 16:31:19 INFO - Return code: 0 16:31:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:31:19 INFO - # TBPL SUCCESS # 16:31:19 INFO - Running post-action listener: _resource_record_post_action 16:31:19 INFO - Running post-run listener: _resource_record_post_run 16:31:20 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 0; Write bytes: 504844288; Read time: 0; Write time: 374880 16:31:20 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239017984; Read time: 0; Write time: 243172 16:31:20 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 0; Write bytes: 265527296; Read time: 0; Write time: 131656 16:31:20 INFO - Running post-run listener: _upload_blobber_files 16:31:20 INFO - Blob upload gear active. 16:31:20 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:31:20 INFO - Copying logs to upload dir... 16:31:20 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1253.894340 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-02-01 16:31:19.834332) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-01 16:31:19.837884) ========= 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009925 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454362951/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-01 16:31:19.888629) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:31:19.888995) ========= 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=41bf3e9aed84707956354da8000001c5-1454371822.880033-1913563866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004600 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-01 16:31:19.938520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-01 16:31:19.938853) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-01 16:31:19.939154) ========= ========= Total master_lag: 0.38 =========