builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1455728786.2 results: success (0) buildid: 20160217064451 builduid: ec35497b60df4a34b194e2bdf7092de0 revision: e176b12c95c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.197842) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.198325) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.198613) ========= 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006256 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.221331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.221637) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.235048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.235385) ========= 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004713 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.284646) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.284970) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.285365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.285634) ========= 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-17 09:06:26-- 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% 224M=0s 2016-02-17 09:06:26 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100293 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.398504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.398830) ========= 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022529 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:26.449033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 09:06:26.449392) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e176b12c95c8 --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 e176b12c95c8 --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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-17 09:06:27,079 Setting DEBUG logging. 2016-02-17 09:06:27,079 attempt 1/10 2016-02-17 09:06:27,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e176b12c95c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-17 09:06:28,334 unpacking tar archive at: mozilla-beta-e176b12c95c8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520942 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-17 09:06:27.984553) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:27.985039) ========= echo e176b12c95c8 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'e176b12c95c8'] 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False e176b12c95c8 program finished with exit code 0 elapsedTime=0.004657 script_repo_revision: 'e176b12c95c8' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:28.034427) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:28.034729) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 09:06:28.044853) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-02-17 09:06:28.045173) ========= /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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:06:28 INFO - MultiFileLogger online at 20160217 09:06:28 in /builds/slave/test-pgo 09:06:28 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 09:06:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:06:28 INFO - {'append_to_log': False, 09:06:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:06:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:06:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:06:28 INFO - 'branch': 'Mozilla-Beta', 09:06:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:28 INFO - 'config_files': ('talos/linux_config.py',), 09:06:28 INFO - 'default_actions': ('clobber', 09:06:28 INFO - 'read-buildbot-config', 09:06:28 INFO - 'download-and-extract', 09:06:28 INFO - 'populate-webroot', 09:06:28 INFO - 'create-virtualenv', 09:06:28 INFO - 'install', 09:06:28 INFO - 'run-tests'), 09:06:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:28 INFO - 'download_minidump_stackwalk': True, 09:06:28 INFO - 'download_symbols': 'ondemand', 09:06:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:28 INFO - 'installer_path': 'installer.exe', 09:06:28 INFO - 'log_level': 'info', 09:06:28 INFO - 'log_name': 'talos', 09:06:28 INFO - 'log_to_console': True, 09:06:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:06:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:06:28 INFO - 'opt_config_files': (), 09:06:28 INFO - 'pip_index': False, 09:06:28 INFO - 'sps_profile': False, 09:06:28 INFO - 'sps_profile_interval': 0, 09:06:28 INFO - 'suite': 'dromaeojs', 09:06:28 INFO - 'system_bits': '32', 09:06:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:28 INFO - 'title': 'talos-linux64-ix-056', 09:06:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:28 INFO - 'use_talos_json': True, 09:06:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:06:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:28 INFO - 'work_dir': 'build'} 09:06:28 INFO - ##### 09:06:28 INFO - ##### Running clobber step. 09:06:28 INFO - ##### 09:06:28 INFO - Running pre-action listener: _resource_record_pre_action 09:06:28 INFO - Running main action method: clobber 09:06:28 INFO - rmtree: /builds/slave/test-pgo/build 09:06:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:06:29 INFO - Running post-action listener: _resource_record_post_action 09:06:29 INFO - ##### 09:06:29 INFO - ##### Running read-buildbot-config step. 09:06:29 INFO - ##### 09:06:29 INFO - Running pre-action listener: _resource_record_pre_action 09:06:29 INFO - Running main action method: read_buildbot_config 09:06:29 INFO - Using buildbot properties: 09:06:29 INFO - { 09:06:29 INFO - "properties": { 09:06:29 INFO - "buildnumber": 111, 09:06:29 INFO - "product": "firefox", 09:06:29 INFO - "script_repo_revision": "production", 09:06:29 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:06:29 INFO - "repository": "", 09:06:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:06:29 INFO - "buildid": "20160217064451", 09:06:29 INFO - "slavename": "talos-linux64-ix-056", 09:06:29 INFO - "pgo_build": "True", 09:06:29 INFO - "basedir": "/builds/slave/test-pgo", 09:06:29 INFO - "project": "", 09:06:29 INFO - "platform": "ubuntu64_hw", 09:06:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:06:29 INFO - "slavebuilddir": "test", 09:06:29 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:06:29 INFO - "branch": "mozilla-beta", 09:06:29 INFO - "repo_path": "releases/mozilla-beta", 09:06:29 INFO - "stage_platform": "linux64-pgo", 09:06:29 INFO - "builduid": "ec35497b60df4a34b194e2bdf7092de0", 09:06:29 INFO - "revision": "e176b12c95c8" 09:06:29 INFO - }, 09:06:29 INFO - "sourcestamp": { 09:06:29 INFO - "repository": "", 09:06:29 INFO - "hasPatch": false, 09:06:29 INFO - "project": "", 09:06:29 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:06:29 INFO - "changes": [ 09:06:29 INFO - { 09:06:29 INFO - "category": null, 09:06:29 INFO - "files": [ 09:06:29 INFO - { 09:06:29 INFO - "url": null, 09:06:29 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:06:29 INFO - } 09:06:29 INFO - ], 09:06:29 INFO - "repository": "", 09:06:29 INFO - "rev": "e176b12c95c8", 09:06:29 INFO - "who": "sendchange", 09:06:29 INFO - "when": 1455728566, 09:06:29 INFO - "number": 7616498, 09:06:29 INFO - "comments": "Bug 1245970: remove menuitem from default browser chrome to make sure we don_t end up with a duplicate Loop menu entry. r=Standard8, a=Sylvestre", 09:06:29 INFO - "project": "", 09:06:29 INFO - "at": "Wed 17 Feb 2016 09:02:46", 09:06:29 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:06:29 INFO - "revlink": "", 09:06:29 INFO - "properties": [ 09:06:29 INFO - [ 09:06:29 INFO - "buildid", 09:06:29 INFO - "20160217064451", 09:06:29 INFO - "Change" 09:06:29 INFO - ], 09:06:29 INFO - [ 09:06:29 INFO - "builduid", 09:06:29 INFO - "ec35497b60df4a34b194e2bdf7092de0", 09:06:29 INFO - "Change" 09:06:29 INFO - ], 09:06:29 INFO - [ 09:06:29 INFO - "pgo_build", 09:06:29 INFO - "True", 09:06:29 INFO - "Change" 09:06:29 INFO - ] 09:06:29 INFO - ], 09:06:29 INFO - "revision": "e176b12c95c8" 09:06:29 INFO - } 09:06:29 INFO - ], 09:06:29 INFO - "revision": "e176b12c95c8" 09:06:29 INFO - } 09:06:29 INFO - } 09:06:29 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:06:29 INFO - Running post-action listener: _resource_record_post_action 09:06:29 INFO - ##### 09:06:29 INFO - ##### Running download-and-extract step. 09:06:29 INFO - ##### 09:06:29 INFO - Running pre-action listener: _resource_record_pre_action 09:06:29 INFO - Running main action method: download_and_extract 09:06:29 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:06: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')]} 09:06:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/test_packages.json 09:06:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:06:29 INFO - Downloaded 1270 bytes. 09:06:29 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:06:29 INFO - Using the following test package requirements: 09:06:29 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:06:29 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:06:29 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'jsshell-linux-x86_64.zip'], 09:06:29 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:06:29 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:06:29 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:06:29 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:06:29 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:06:29 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:06:29 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:06:29 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:06:29 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:06: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')]} 09:06:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:06:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:29 INFO - Downloaded 22211912 bytes. 09:06:29 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 09:06:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:06:29 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 09:06:30 INFO - Return code: 0 09:06:30 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 09:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:06:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:31 INFO - Downloaded 22211912 bytes. 09:06:31 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 09:06:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:06:31 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 09:06:32 INFO - Return code: 0 09:06:32 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')]} 09:06:32 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:06:32 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:32 INFO - Downloaded 11132038 bytes. 09:06:32 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 09:06:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:06:32 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 09:06:33 INFO - Return code: 0 09:06:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:06:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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 09:06:35 INFO - Downloaded 58304691 bytes. 09:06:35 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:06:35 INFO - mkdir: /builds/slave/test-pgo/properties 09:06:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:06:35 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:06:35 INFO - Contents: 09:06:35 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:06:35 INFO - Running post-action listener: _resource_record_post_action 09:06:35 INFO - Running post-action listener: set_extra_try_arguments 09:06:35 INFO - ##### 09:06:35 INFO - ##### Running populate-webroot step. 09:06:35 INFO - ##### 09:06:35 INFO - Running pre-action listener: _resource_record_pre_action 09:06:35 INFO - Running main action method: populate_webroot 09:06:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:35 INFO - '--tppagecycles', 09:06:35 INFO - '7'], 09:06:35 INFO - 'tests': ['tsvgm']}}, 09:06:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:06:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:06:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:06:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'tests': ['damp', 'tps']}, 09:06:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['damp', 'tps']}, 09:06:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:06:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:35 INFO - 'other': {'tests': ['a11yr', 09:06:35 INFO - 'ts_paint', 09:06:35 INFO - 'tpaint', 09:06:35 INFO - 'sessionrestore', 09:06:35 INFO - 'sessionrestore_no_auto_restore']}, 09:06:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['a11yr', 09:06:35 INFO - 'ts_paint', 09:06:35 INFO - 'tpaint', 09:06:35 INFO - 'sessionrestore', 09:06:35 INFO - 'sessionrestore_no_auto_restore']}, 09:06:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['a11yr', 09:06:35 INFO - 'ts_paint', 09:06:35 INFO - 'tpaint', 09:06:35 INFO - 'sessionrestore', 09:06:35 INFO - 'sessionrestore_no_auto_restore']}, 09:06:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['a11yr', 09:06:35 INFO - 'ts_paint', 09:06:35 INFO - 'tpaint', 09:06:35 INFO - 'sessionrestore', 09:06:35 INFO - 'sessionrestore_no_auto_restore']}, 09:06:35 INFO - 'other_l64': {'tests': ['a11yr', 09:06:35 INFO - 'ts_paint', 09:06:35 INFO - 'tpaint', 09:06:35 INFO - 'sessionrestore', 09:06:35 INFO - 'sessionrestore_no_auto_restore']}, 09:06:35 INFO - 'other_nol64': {'tests': ['a11yr', 09:06:35 INFO - 'ts_paint', 09:06:35 INFO - 'tpaint', 09:06:35 INFO - 'sessionrestore', 09:06:35 INFO - 'sessionrestore_no_auto_restore']}, 09:06:35 INFO - 'svgr': {'tests': ['tsvgx', 09:06:35 INFO - 'tsvgr_opacity', 09:06:35 INFO - 'tart', 09:06:35 INFO - 'tscrollx', 09:06:35 INFO - 'cart']}, 09:06:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['tsvgx', 09:06:35 INFO - 'tsvgr_opacity', 09:06:35 INFO - 'tart', 09:06:35 INFO - 'tscrollx', 09:06:35 INFO - 'cart']}, 09:06:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'tests': ['tp5o']}, 09:06:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'talos_options': ['--e10s'], 09:06:35 INFO - 'tests': ['tp5o']}, 09:06:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'talos_options': ['--xperf_path', 09:06:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:35 INFO - 'tests': ['tp5n']}, 09:06:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:35 INFO - 'talos_options': ['--e10s', 09:06:35 INFO - '--xperf_path', 09:06:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:35 INFO - 'tests': ['tp5n']}}, 09:06:35 INFO - 'talos.zip': {'path': '', 09:06:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:35 INFO - Running post-action listener: _resource_record_post_action 09:06:35 INFO - ##### 09:06:35 INFO - ##### Running create-virtualenv step. 09:06:35 INFO - ##### 09:06:35 INFO - Running pre-action listener: _resource_record_pre_action 09:06:35 INFO - Running main action method: create_virtualenv 09:06:35 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:06:35 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 09:06:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:06:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:35 INFO - Using real prefix '/usr' 09:06:35 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:06:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:38 INFO - Installing pip.................done. 09:06:38 INFO - Return code: 0 09:06:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:06: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')]} 09:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06: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', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:06: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 pip>=1.5 09:06:38 INFO - Using env: {'DISPLAY': ':0', 09:06:38 INFO - 'HOME': '/home/cltbld', 09:06:38 INFO - 'LANG': 'en_US.UTF-8', 09:06:38 INFO - 'LANGUAGE': 'en_US:en', 09:06:38 INFO - 'LOGNAME': 'cltbld', 09:06:38 INFO - 'MAIL': '/var/mail/cltbld', 09:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:38 INFO - 'MOZ_NO_REMOTE': '1', 09:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:38 INFO - 'NO_EM_RESTART': '1', 09:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:38 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:38 INFO - 'SHELL': '/bin/bash', 09:06:38 INFO - 'SHLVL': '1', 09:06:38 INFO - 'TERM': 'linux', 09:06:38 INFO - 'TMOUT': '86400', 09:06:38 INFO - 'USER': 'cltbld', 09:06:38 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:38 INFO - '_': '/tools/buildbot/bin/python'} 09:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:38 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 09:06:38 INFO - Cleaning up... 09:06:38 INFO - Return code: 0 09:06:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:06: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')]} 09:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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', '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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:06: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 psutil>=0.7.1 09:06:38 INFO - Using env: {'DISPLAY': ':0', 09:06:38 INFO - 'HOME': '/home/cltbld', 09:06:38 INFO - 'LANG': 'en_US.UTF-8', 09:06:38 INFO - 'LANGUAGE': 'en_US:en', 09:06:38 INFO - 'LOGNAME': 'cltbld', 09:06:38 INFO - 'MAIL': '/var/mail/cltbld', 09:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:38 INFO - 'MOZ_NO_REMOTE': '1', 09:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:38 INFO - 'NO_EM_RESTART': '1', 09:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:38 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:38 INFO - 'SHELL': '/bin/bash', 09:06:38 INFO - 'SHLVL': '1', 09:06:38 INFO - 'TERM': 'linux', 09:06:38 INFO - 'TMOUT': '86400', 09:06:38 INFO - 'USER': 'cltbld', 09:06:38 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:38 INFO - '_': '/tools/buildbot/bin/python'} 09:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:38 INFO - Downloading/unpacking psutil>=0.7.1 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:40 INFO - Installing collected packages: psutil 09:06:40 INFO - Running setup.py install for psutil 09:06:40 INFO - building 'psutil._psutil_linux' extension 09:06:40 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 09:06:40 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 09:06:40 INFO - building 'psutil._psutil_posix' extension 09:06:40 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 09:06:41 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 09:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:41 INFO - Successfully installed psutil 09:06:41 INFO - Cleaning up... 09:06:41 INFO - Return code: 0 09:06:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:06:41 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')]} 09:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:41 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')]} 09:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:41 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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06:41 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 09:06:41 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 09:06:41 INFO - Using env: {'DISPLAY': ':0', 09:06:41 INFO - 'HOME': '/home/cltbld', 09:06:41 INFO - 'LANG': 'en_US.UTF-8', 09:06:41 INFO - 'LANGUAGE': 'en_US:en', 09:06:41 INFO - 'LOGNAME': 'cltbld', 09:06:41 INFO - 'MAIL': '/var/mail/cltbld', 09:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:41 INFO - 'MOZ_NO_REMOTE': '1', 09:06:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:41 INFO - 'NO_EM_RESTART': '1', 09:06:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:41 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:41 INFO - 'SHELL': '/bin/bash', 09:06:41 INFO - 'SHLVL': '1', 09:06:41 INFO - 'TERM': 'linux', 09:06:41 INFO - 'TMOUT': '86400', 09:06:41 INFO - 'USER': 'cltbld', 09:06:41 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:41 INFO - '_': '/tools/buildbot/bin/python'} 09:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:06:41 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 09:06:41 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 09:06:41 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 09:06:41 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 09:06:41 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 09:06:41 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 09:06:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:06:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:06:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:06:43 INFO - Installing collected packages: mozsystemmonitor 09:06:43 INFO - Running setup.py install for mozsystemmonitor 09:06:43 INFO - Successfully installed mozsystemmonitor 09:06:43 INFO - Cleaning up... 09:06:43 INFO - Return code: 0 09:06:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:06: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')]} 09:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:43 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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06:43 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 09:06:43 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 09:06:43 INFO - Using env: {'DISPLAY': ':0', 09:06:43 INFO - 'HOME': '/home/cltbld', 09:06:43 INFO - 'LANG': 'en_US.UTF-8', 09:06:43 INFO - 'LANGUAGE': 'en_US:en', 09:06:43 INFO - 'LOGNAME': 'cltbld', 09:06:43 INFO - 'MAIL': '/var/mail/cltbld', 09:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:43 INFO - 'MOZ_NO_REMOTE': '1', 09:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:43 INFO - 'NO_EM_RESTART': '1', 09:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:43 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:43 INFO - 'SHELL': '/bin/bash', 09:06:43 INFO - 'SHLVL': '1', 09:06:43 INFO - 'TERM': 'linux', 09:06:43 INFO - 'TMOUT': '86400', 09:06:43 INFO - 'USER': 'cltbld', 09:06:43 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:43 INFO - '_': '/tools/buildbot/bin/python'} 09:06:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:43 INFO - Downloading/unpacking blobuploader==1.2.4 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:06:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:46 INFO - Downloading docopt-0.6.1.tar.gz 09:06:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:46 INFO - Running setup.py install for blobuploader 09:06:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:46 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:06:46 INFO - Running setup.py install for requests 09:06:46 INFO - Running setup.py install for docopt 09:06:46 INFO - Successfully installed blobuploader requests docopt 09:06:46 INFO - Cleaning up... 09:06:46 INFO - Return code: 0 09:06:46 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:06:46 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')]} 09:06:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:46 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')]} 09:06:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:46 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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06:46 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 09:06:46 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 09:06:46 INFO - Using env: {'DISPLAY': ':0', 09:06:46 INFO - 'HOME': '/home/cltbld', 09:06:46 INFO - 'LANG': 'en_US.UTF-8', 09:06:46 INFO - 'LANGUAGE': 'en_US:en', 09:06:46 INFO - 'LOGNAME': 'cltbld', 09:06:46 INFO - 'MAIL': '/var/mail/cltbld', 09:06:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:46 INFO - 'MOZ_NO_REMOTE': '1', 09:06:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:46 INFO - 'NO_EM_RESTART': '1', 09:06:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:46 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:46 INFO - 'SHELL': '/bin/bash', 09:06:46 INFO - 'SHLVL': '1', 09:06:46 INFO - 'TERM': 'linux', 09:06:46 INFO - 'TMOUT': '86400', 09:06:46 INFO - 'USER': 'cltbld', 09:06:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:46 INFO - '_': '/tools/buildbot/bin/python'} 09:06:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:06:46 INFO - Running setup.py (path:/tmp/pip-euxrnC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:06:47 INFO - Running setup.py (path:/tmp/pip-BibnsF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:06:47 INFO - Running setup.py (path:/tmp/pip-49cfrH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:06:47 INFO - Running setup.py (path:/tmp/pip-IffL6V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:06:47 INFO - Running setup.py (path:/tmp/pip-74dphx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:06:47 INFO - Running setup.py (path:/tmp/pip-Wuet1y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:06:47 INFO - Running setup.py (path:/tmp/pip-Sz3675-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:06:47 INFO - Running setup.py (path:/tmp/pip-ZQsZSG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:06:47 INFO - Running setup.py (path:/tmp/pip-qoDubs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:06:47 INFO - Running setup.py (path:/tmp/pip-DapAzO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:06:47 INFO - Running setup.py (path:/tmp/pip-xJ5tsD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:06:47 INFO - Running setup.py (path:/tmp/pip-rcb8Tf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:06:47 INFO - Running setup.py (path:/tmp/pip-MB6sE7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:06:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:06:47 INFO - Running setup.py (path:/tmp/pip-HI4Yj9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:06:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:06:48 INFO - Running setup.py (path:/tmp/pip-rsvSXt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:06:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:06:48 INFO - Running setup.py (path:/tmp/pip-z1VFiv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:06:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:06:48 INFO - Running setup.py (path:/tmp/pip-OuL8_k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:06:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:48 INFO - Running setup.py install for manifestparser 09:06:48 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:06:48 INFO - Running setup.py install for mozcrash 09:06:48 INFO - Running setup.py install for mozdebug 09:06:48 INFO - Running setup.py install for mozdevice 09:06:48 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:06:48 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:06:48 INFO - Running setup.py install for mozfile 09:06:48 INFO - Running setup.py install for mozhttpd 09:06:48 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:06:48 INFO - Running setup.py install for mozinfo 09:06:48 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:06:48 INFO - Running setup.py install for mozInstall 09:06:49 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Running setup.py install for mozleak 09:06:49 INFO - Running setup.py install for mozlog 09:06:49 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Running setup.py install for moznetwork 09:06:49 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Running setup.py install for mozprocess 09:06:49 INFO - Running setup.py install for mozprofile 09:06:49 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Running setup.py install for mozrunner 09:06:49 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Running setup.py install for mozscreenshot 09:06:49 INFO - Running setup.py install for moztest 09:06:49 INFO - Running setup.py install for mozversion 09:06:49 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:06:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:49 INFO - Cleaning up... 09:06:50 INFO - Return code: 0 09:06:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:06: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')]} 09:06:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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/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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06:50 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 09:06:50 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 09:06:50 INFO - Using env: {'DISPLAY': ':0', 09:06:50 INFO - 'HOME': '/home/cltbld', 09:06:50 INFO - 'LANG': 'en_US.UTF-8', 09:06:50 INFO - 'LANGUAGE': 'en_US:en', 09:06:50 INFO - 'LOGNAME': 'cltbld', 09:06:50 INFO - 'MAIL': '/var/mail/cltbld', 09:06:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:50 INFO - 'MOZ_NO_REMOTE': '1', 09:06:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:50 INFO - 'NO_EM_RESTART': '1', 09:06:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:50 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:50 INFO - 'SHELL': '/bin/bash', 09:06:50 INFO - 'SHLVL': '1', 09:06:50 INFO - 'TERM': 'linux', 09:06:50 INFO - 'TMOUT': '86400', 09:06:50 INFO - 'USER': 'cltbld', 09:06:50 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:50 INFO - '_': '/tools/buildbot/bin/python'} 09:06:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:06:50 INFO - Running setup.py (path:/tmp/pip-T2dWjO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:06:50 INFO - Running setup.py (path:/tmp/pip-nz9GSt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:06:50 INFO - Running setup.py (path:/tmp/pip-OAUwMe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:06:50 INFO - Running setup.py (path:/tmp/pip-9MBCT0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:06:50 INFO - Running setup.py (path:/tmp/pip-Dk3UUQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:06:50 INFO - Running setup.py (path:/tmp/pip-xsQ0oT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:06:50 INFO - Running setup.py (path:/tmp/pip-GpbKBp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:06:50 INFO - Running setup.py (path:/tmp/pip-0te4XT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:06:50 INFO - Running setup.py (path:/tmp/pip-PD4cGI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:06:50 INFO - Running setup.py (path:/tmp/pip-iUW_5Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:06:50 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)) 09:06:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:06:50 INFO - Running setup.py (path:/tmp/pip-yLGrbh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:06:51 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)) 09:06:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:06:51 INFO - Running setup.py (path:/tmp/pip-NdEVtw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:06:51 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)) 09:06:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:06:51 INFO - Running setup.py (path:/tmp/pip-6MatJE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:06:51 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)) 09:06:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:06:51 INFO - Running setup.py (path:/tmp/pip-EEma7d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:06:51 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)) 09:06:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:06:51 INFO - Running setup.py (path:/tmp/pip-O8Oay0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:06:51 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)) 09:06:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:06:51 INFO - Running setup.py (path:/tmp/pip-nCgPC5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:06:51 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)) 09:06:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:06:51 INFO - Running setup.py (path:/tmp/pip-Ik6Xxs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:06:51 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)) 09:06:51 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)) 09:06: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.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:51 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)) 09:06: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 mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:51 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)) 09:06:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:53 INFO - Downloading blessings-1.6.tar.gz 09:06:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:53 INFO - Installing collected packages: blessings 09:06:53 INFO - Running setup.py install for blessings 09:06:53 INFO - Successfully installed blessings 09:06:53 INFO - Cleaning up... 09:06:53 INFO - Return code: 0 09:06:53 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:06:53 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:06:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:06:53 INFO - Reading from file tmpfile_stdout 09:06:53 INFO - Current package versions: 09:06:53 INFO - argparse == 1.2.1 09:06:53 INFO - blessings == 1.6 09:06:53 INFO - blobuploader == 1.2.4 09:06:53 INFO - docopt == 0.6.1 09:06:53 INFO - manifestparser == 1.1 09:06:53 INFO - mozInstall == 1.12 09:06:53 INFO - mozcrash == 0.16 09:06:53 INFO - mozdebug == 0.1 09:06:53 INFO - mozdevice == 0.47 09:06:53 INFO - mozfile == 1.2 09:06:53 INFO - mozhttpd == 0.7 09:06:53 INFO - mozinfo == 0.9 09:06:53 INFO - mozleak == 0.1 09:06:53 INFO - mozlog == 3.1 09:06:53 INFO - moznetwork == 0.27 09:06:53 INFO - mozprocess == 0.22 09:06:53 INFO - mozprofile == 0.27 09:06:53 INFO - mozrunner == 6.11 09:06:53 INFO - mozscreenshot == 0.1 09:06:53 INFO - mozsystemmonitor == 0.0 09:06:53 INFO - moztest == 0.7 09:06:53 INFO - mozversion == 1.4 09:06:53 INFO - psutil == 3.1.1 09:06:53 INFO - requests == 1.2.3 09:06:53 INFO - wsgiref == 0.1.2 09:06:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:06: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')]} 09:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06:53 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 09:06:53 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 09:06:53 INFO - Using env: {'DISPLAY': ':0', 09:06:53 INFO - 'HOME': '/home/cltbld', 09:06:53 INFO - 'LANG': 'en_US.UTF-8', 09:06:53 INFO - 'LANGUAGE': 'en_US:en', 09:06:53 INFO - 'LOGNAME': 'cltbld', 09:06:53 INFO - 'MAIL': '/var/mail/cltbld', 09:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:53 INFO - 'MOZ_NO_REMOTE': '1', 09:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:53 INFO - 'NO_EM_RESTART': '1', 09:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:53 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:53 INFO - 'SHELL': '/bin/bash', 09:06:53 INFO - 'SHLVL': '1', 09:06:53 INFO - 'TERM': 'linux', 09:06:53 INFO - 'TMOUT': '86400', 09:06:53 INFO - 'USER': 'cltbld', 09:06:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:53 INFO - '_': '/tools/buildbot/bin/python'} 09:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 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)) 09:06:53 INFO - Cleaning up... 09:06:53 INFO - Return code: 0 09:06:53 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:06: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')]} 09:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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')]} 09:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06: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', 'jsonschema'] in /builds/slave/test-pgo/build 09:06: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 jsonschema 09:06:53 INFO - Using env: {'DISPLAY': ':0', 09:06:53 INFO - 'HOME': '/home/cltbld', 09:06:53 INFO - 'LANG': 'en_US.UTF-8', 09:06:53 INFO - 'LANGUAGE': 'en_US:en', 09:06:53 INFO - 'LOGNAME': 'cltbld', 09:06:53 INFO - 'MAIL': '/var/mail/cltbld', 09:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:53 INFO - 'MOZ_NO_REMOTE': '1', 09:06:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:53 INFO - 'NO_EM_RESTART': '1', 09:06:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:53 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:53 INFO - 'SHELL': '/bin/bash', 09:06:53 INFO - 'SHLVL': '1', 09:06:53 INFO - 'TERM': 'linux', 09:06:53 INFO - 'TMOUT': '86400', 09:06:53 INFO - 'USER': 'cltbld', 09:06:53 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:53 INFO - '_': '/tools/buildbot/bin/python'} 09:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:54 INFO - Downloading/unpacking jsonschema 09:06:54 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 09:06:54 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 09:06:54 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 09:06:54 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 09:06:54 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 09:06:54 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 09:06:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:06:55 INFO - Downloading/unpacking functools32 (from jsonschema) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:56 INFO - Downloading functools32-3.2.3-2.tar.gz 09:06:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:06:56 INFO - warning: no files found matching '*.txt' 09:06:56 INFO - no previously-included directories found matching 'build' 09:06:56 INFO - no previously-included directories found matching 'dist' 09:06:56 INFO - no previously-included directories found matching '.git*' 09:06:56 INFO - Installing collected packages: jsonschema, functools32 09:06:56 INFO - Running setup.py install for functools32 09:06:56 INFO - warning: no files found matching '*.txt' 09:06:56 INFO - no previously-included directories found matching 'build' 09:06:56 INFO - no previously-included directories found matching 'dist' 09:06:56 INFO - no previously-included directories found matching '.git*' 09:06:56 INFO - Successfully installed jsonschema functools32 09:06:56 INFO - Cleaning up... 09:06:56 INFO - Return code: 0 09:06:56 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:06:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:56 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 0x7f1895a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a278a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, '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': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', '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 09:06:56 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 09:06:56 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 09:06:56 INFO - Using env: {'DISPLAY': ':0', 09:06:56 INFO - 'HOME': '/home/cltbld', 09:06:56 INFO - 'LANG': 'en_US.UTF-8', 09:06:56 INFO - 'LANGUAGE': 'en_US:en', 09:06:56 INFO - 'LOGNAME': 'cltbld', 09:06:56 INFO - 'MAIL': '/var/mail/cltbld', 09:06:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:56 INFO - 'MOZ_NO_REMOTE': '1', 09:06:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:56 INFO - 'NO_EM_RESTART': '1', 09:06:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:06:56 INFO - 'PWD': '/builds/slave/test-pgo', 09:06:56 INFO - 'SHELL': '/bin/bash', 09:06:56 INFO - 'SHLVL': '1', 09:06:56 INFO - 'TERM': 'linux', 09:06:56 INFO - 'TMOUT': '86400', 09:06:56 INFO - 'USER': 'cltbld', 09:06:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:06:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:56 INFO - '_': '/tools/buildbot/bin/python'} 09:06:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:56 INFO - Downloading/unpacking flake8 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:56 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 09:06:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:06:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:06:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:58 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 09:06:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:06:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:06:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:06:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:06:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:06:59 INFO - no previously-included directories found matching 'docs/_build' 09:06:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:06:59 INFO - Running setup.py install for pep8 09:06:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:06:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:06:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:06:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:06:59 INFO - no previously-included directories found matching 'docs/_build' 09:06:59 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:06:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:06:59 INFO - Cleaning up... 09:06:59 INFO - Return code: 0 09:06:59 INFO - Running post-action listener: _resource_record_post_action 09:06:59 INFO - Running post-action listener: _start_resource_monitoring 09:06:59 INFO - Starting resource monitoring. 09:06:59 INFO - ##### 09:06:59 INFO - ##### Running install step. 09:06:59 INFO - ##### 09:06:59 INFO - Running pre-action listener: _resource_record_pre_action 09:06:59 INFO - Running main action method: install 09:06:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:06:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:06:59 INFO - Reading from file tmpfile_stdout 09:06:59 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:06:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:06:59 INFO - Reading from file tmpfile_stdout 09:06:59 INFO - Output received: 09:06:59 INFO - Usage: mozinstall [options] installer 09:06:59 INFO - Options: 09:06:59 INFO - -h, --help show this help message and exit 09:06:59 INFO - -d DEST, --destination=DEST 09:06:59 INFO - Directory to install application into. [default: 09:06:59 INFO - "/builds/slave/test-pgo"] 09:06:59 INFO - --app=APP Application being installed. [default: firefox] 09:06:59 INFO - mkdir: /builds/slave/test-pgo/build/application 09:06:59 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'] 09:06:59 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 09:07:14 INFO - Reading from file tmpfile_stdout 09:07:14 INFO - Output received: 09:07:14 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:07:14 INFO - Running post-action listener: _resource_record_post_action 09:07:14 INFO - ##### 09:07:14 INFO - ##### Running run-tests step. 09:07:14 INFO - ##### 09:07:14 INFO - Running pre-action listener: _resource_record_pre_action 09:07:14 INFO - Running main action method: run_tests 09:07:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:07:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:07:14 INFO - Python 2.7.3 09:07:14 INFO - Return code: 0 09:07:14 INFO - grabbing minidump binary from tooltool 09:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:07:14 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 0x1b83350>, '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 0x1b84250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b846e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:07:14 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 09:07:14 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 09:07:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:14 INFO - Return code: 0 09:07:14 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:07:14 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:07:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:07:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:07:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:07:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:07:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:07:15 INFO - Return code: 0 09:07:15 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/1455720291/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:07:15 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/1455720291/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 09:07:15 INFO - Using env: {'DISPLAY': ':0', 09:07:15 INFO - 'HOME': '/home/cltbld', 09:07:15 INFO - 'LANG': 'en_US.UTF-8', 09:07:15 INFO - 'LANGUAGE': 'en_US:en', 09:07:15 INFO - 'LOGNAME': 'cltbld', 09:07:15 INFO - 'MAIL': '/var/mail/cltbld', 09:07:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:07:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:15 INFO - 'MOZ_NO_REMOTE': '1', 09:07:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:07:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:07:15 INFO - 'NO_EM_RESTART': '1', 09:07:15 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:07:15 INFO - 'PWD': '/builds/slave/test-pgo', 09:07:15 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:07:15 INFO - 'SHELL': '/bin/bash', 09:07:15 INFO - 'SHLVL': '1', 09:07:15 INFO - 'TERM': 'linux', 09:07:15 INFO - 'TMOUT': '86400', 09:07:15 INFO - 'USER': 'cltbld', 09:07:15 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1455728785.804572-733833205', 09:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:15 INFO - '_': '/tools/buildbot/bin/python'} 09:07:15 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/1455720291/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 09:07:15 INFO - mozversion INFO | application_buildid: 20160217064451 09:07:15 INFO - mozversion INFO | application_changeset: e176b12c95c88c26e1cdc8fed647bc4c5cc72dbb 09:07:15 INFO - mozversion INFO | application_display_name: Nightly 09:07:15 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:15 INFO - mozversion INFO | application_name: Firefox 09:07:15 INFO - mozversion INFO | application_remotingname: firefox 09:07:15 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:07:15 INFO - mozversion INFO | application_vendor: Mozilla 09:07:15 INFO - mozversion INFO | application_version: 45.0 09:07:15 INFO - mozversion INFO | platform_buildid: 20160217064451 09:07:15 INFO - mozversion INFO | platform_changeset: e176b12c95c88c26e1cdc8fed647bc4c5cc72dbb 09:07:15 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:07:15 INFO - mozversion INFO | platform_version: 45.0 09:07:15 INFO - 2016-02-17 09:07:15,680 DEBUG : using testdate: 1455728835 09:07:15 INFO - 2016-02-17 09:07:15,680 DEBUG : actual date: 1455728835 09:07:15 INFO - 2016-02-17 09:07:15,726 INFO : starting webserver on 'localhost:52947' 09:07:15 INFO - 2016-02-17 09:07:15,727 INFO : Starting test suite talos-linux64-ix-056 09:07:15 INFO - 2016-02-17 09:07:15,728 INFO : Starting test dromaeo_css 09:07:15 INFO - 2016-02-17 09:07:15,728 DEBUG : operating with platform_type : linux_ 09:07:15 INFO - 2016-02-17 09:07:15,728 INFO : Initialising browser for dromaeo_css test... 09:07:15 INFO - 2016-02-17 09:07:15,741 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppGi01F/profile http://localhost:52947/getInfo.html 09:07:19 INFO - 2016-02-17 09:07:19,625 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:07:19 INFO - 2016-02-17 09:07:19,625 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:07:19 INFO - 2016-02-17 09:07:19,625 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:07:19 INFO - 2016-02-17 09:07:19,625 DEBUG : BROWSER_OUTPUT: __metrics 09:07:19 INFO - 2016-02-17 09:07:19,835 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] 09:07:20 INFO - 2016-02-17 09:07:20,907 INFO : Browser initialized. 09:07:20 INFO - 2016-02-17 09:07:20,908 INFO : Running cycle 1/1 for dromaeo_css test... 09:07:20 INFO - 2016-02-17 09:07:20,908 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppGi01F/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:07:32 INFO - 2016-02-17 09:07:32,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 133177344 09:07:32 INFO - 2016-02-17 09:07:32,606 DEBUG : BROWSER_OUTPUT: 09:08:23 INFO - 2016-02-17 09:08:23,563 DEBUG : BROWSER_OUTPUT: 1455728903562 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] 09:10:32 INFO - 2016-02-17 09:10:32,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52947/tests/dromaeo/cssquery-ext.html) 09:10:33 INFO - 2016-02-17 09:10:33,053 DEBUG : BROWSER_OUTPUT: RSS: Main: 216596480 09:10:33 INFO - 2016-02-17 09:10:33,053 DEBUG : BROWSER_OUTPUT: 09:13:35 INFO - 2016-02-17 09:13:35,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/dromaeo/cssquery-ext.html (next: http://localhost:52947/tests/dromaeo/cssquery-jquery.html) 09:13:35 INFO - 2016-02-17 09:13:35,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 215543808 09:13:35 INFO - 2016-02-17 09:13:35,707 DEBUG : BROWSER_OUTPUT: 09:16:37 INFO - 2016-02-17 09:16:37,443 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52947/tests/dromaeo/cssquery-mootools.html) 09:16:38 INFO - 2016-02-17 09:16:38,009 DEBUG : BROWSER_OUTPUT: RSS: Main: 213000192 09:16:38 INFO - 2016-02-17 09:16:38,009 DEBUG : BROWSER_OUTPUT: 09:19:39 INFO - 2016-02-17 09:19:39,784 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52947/tests/dromaeo/cssquery-prototype.html) 09:19:40 INFO - 2016-02-17 09:19:40,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 199118848 09:19:40 INFO - 2016-02-17 09:19:40,358 DEBUG : BROWSER_OUTPUT: 09:22:42 INFO - 2016-02-17 09:22:42,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52947/tests/dromaeo/cssquery-yui.html) 09:22:43 INFO - 2016-02-17 09:22:43,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 200491008 09:22:43 INFO - 2016-02-17 09:22:43,414 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,459 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/dromaeo/cssquery-yui.html (next: http://localhost:52947/tests/dromaeo/cssquery-dojo.html) 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 243142656 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:25:45 INFO - 2016-02-17 09:25:45,709 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3261.8858339958097;3337.516060171277;3373.561437013188;3378.4425569780988;3397.099768561331;5552.888942221153;5563.332400111982;5577.9999999999945;5579.330480342354;5588.4411558844195;5539.002979463695;5594.216809646653;5594.804181853636;5608.186812912125;5624.521915637166;5004.199328107504;5021.698698078127;5034.5468907798295;5106.361704786902;5150.639455238135;4989.875253118665;5082.08522465956;5112.437631860493;5116.590672746172;5117.897642047157;5008.674436161656;5127.359080114986;5136.152534831748;5141.845744627649;5155.639105262633;5112.693238405689;5192.428832828386;5211.42674305826;5211.713355765451;5249.238860365225;4893.681910675795;4991.975040124812;5077.796888124508;5088.618353623456;5098.209777484496;5429.511343979021;5434.782608695687;5441.972790136064;5442.591805614595;5474.808381706621;5952.285725712899;5954.732037058343;5996.580239383245;6003.3996600339615;6013.51891848651;5800.854978625526;5863.442972917609;5943.791967281168;6029.336772954972;6045.153678485016;5645.717714114319;5669.291338582677;5677.630953987978;5678.119891416837;5678.205051292823;6158.907616385758;6163.3528479509905;6169.259688837368;6177.96911015451;6182.1345011698395;5646.943530564724;5760.94239057604;5775.191473193756;5958.314793798682;5977.342492325841;5872.911906321408;5885.234919460443;5885.823425297248;5918.526517878559;5942.257217847769;5816.941830581724;5865.882682346414;5867.178594996704;5870.853228669317;5884;5691.174779657009;5787.016207244778;5793.478586927196;5862.941370586324;5869.4717475427415;4508.458984921765;4568.588827005586;4572.177008138567;4586;4589.908201835945;4088.7751173685733;4157.750534967911;4165.354370072643;4173.998240422337;4198.307279298881;5481.177823326453;5583.553315734732;5802.6808525531505;5839.970800146057;5841.795537156264;5878.941210587925;5938.584299099108;5958.314793798682;5959.642421454727;6001.669908155006;5610.915836262459;5631.774729010794;5632.746526406321;5636.577256705681;5643.717814109278;5009.323741294951;5099.87250318745;5158.92261616076;5188.195829646411;5247.895042099137;6219.0982307566055;6219.689015549205;6228.34602366754;6234;6235.438810507076;6020.759169633166;6022.7289771959495;6025.096235564701;6029.969850150721;6062.302835173835;5930.080837470199;6057.606255593372;6097.054956481752;6155.630662160284;6227.0970709246;5941.9405805943115;5966.3138739043825;5973;5983.401659833982;5997.91003134962;6095.664738439428;6109.297430795337;6116.969415152896;6131.96934015345;6132.969335153296;5672.5461963042035;5780.682062486603;5789.363170051207;5836.708164591669;5919.200907877382;5902.7343769531335;5915.40845915405;5916.881662366814;5922;5927.940720592739;5614.971925140511;5799.565032622486;5841.824745257649;5843.094320380348;5844.590878638454;5780.682062486436;5818.912716309174;5905.881882362414;5907.143464197581;5914.940850591439;5733.684647344436;5786.768529258782;5793.246877905845;5803.216565763737;5813.680247586423 09:25:45 INFO - 2016-02-17 09:25:45,710 DEBUG : BROWSER_OUTPUT: |1;ext.html;638.3903372279474;638.4541217259243;660.000099848729;673.4545018534988;695.3950063444804;39656;40241.79879100604;40279.79860100695;40317.798411007905;40370.394444083366;46797.76601116989;47530;47675;47687;47711.76144119283;7578.924210757905;7674.117476490219;8960.596773145207;9051.14014168653;10268.74328141795;10531.473426328692;10634.9468252659;10661;10675.19936004799;10692.51883665233;10584.629537966173;10761.78476430471;10775.784484310308;10799.946000269987;10802.405867677275;10536.578536858555;10613.893861061368;10628.415437150954;10631.627893023708;10640.361578305326;10467.424291663956;10561.313514621603;10567.841482377771;10569.78860422795;10577.259591828575;42755.57244427547;43017;43430.56569434297;43430.78284608588;43437.34843977343;3728.459373390843;3889.8055097245306;3907.2966865964113;4111.218868414991;4528.977355113237;32658;32789.83605081983;32866.50700239498;33012;33438;4187.392828039917;4269.978650106761;4274.422952901349;4281.892952676177;4285.957140428587;291496;293854;294437;295658;296350;57573.13640295399;58686.11970820441;65499;73013.26986730171;74198;66698.66650666716;67208.6639566799;67490.32509674938;67558;67673.3232667677;14926.179060151795;15069.020513666577;15074.397024119131;15077.246137693071;15093.094414335175;10764;10776;10802.67591972242;10812.837807432894;11018.063464605599;22149.557008859734;22195.33413997583;22295.108195672317;22322.55354892893;22458.55082898333;7850.646720897574;8109.148539403278;8167.877481837777;8172.18278172178;8244.557202489541;902.3232575568323;989.1542730965065;1012.4785735346303;1139.6296203733655;1175.4240422193063;26269.868650656623;26668.866655666596;26704.332391690365;26917.86541067282;26942.191734248005;963.393062370702;1031.9019693129142;1045.1396514204728;1080.103514083309;1167.7197472606513;13666.590002299947;14001.43994240219;16146.515604531884;16174.676506469805;16762;275631;276992;277259;278069;279099;57256.71371643115;58024.4197558019;58393;58398.41601584015;58437.4156258432;1249.7188132670076;1427.5217802036434;1434.9067310624775;1438.8561143885527;1452.8837692984748;23320.984703633483;30502.847485762428;31099;31603.20991975219;31835.840820795747;55364.72317638386;56041.719791400785;56057.439425606855;56101.15848262361;56129.15806262991;329002;330225;331493;331512;333120;29502.85248573829;29589.704102958694;34624.480632791034;35234.64765352416;35656;41003.12978091505;41049.79475102606;41055.58944410518;42040.78979605205;42226.099825507314;34406.48390274198;34420.311593768485;34590.481142783385;35029.12427189341;35345.823270883484;36996.26007479889;37507;37578.2484350306;38369.4244586337;39523.2095358097;35055.47416788699;39006.41490377703;39073.2185356297;39213.60786392214;39232;30006.69993300039;31281.748730051455;31643.84178079187;31951.840240798647;32639.34721305608;25037.12370067039;25044.373890652878;25048.123315683864;25247.74752252454;25539.106131285316 09:25:45 INFO - 2016-02-17 09:25:45,714 DEBUG : BROWSER_OUTPUT: |2;jquery.html;390.7421102072632;391.723834696539;394.09948268207154;396.32426712455265;398.481973434535;16991.83008169918;18514.3519976801;18524.81475185248;18593.62812743744;20078.79921200791;17472.912635436838;17517;17696;18086.81913180868;18104.185311660975;5351.892962140755;5477.370102438231;5499.697516636584;5508.20131080993;5511.641743286674;4490.550944905516;5324.600654950875;5491.725413729318;5518.420565840589;5530.115181570949;4061.1065565575527;4128.256913755523;5497.780088796443;5511.641743286694;5959.642421454705;6067.908981365283;6091.634501929899;6094.664793436359;6118.847028824271;6121.908171377455;5161.3806343238675;5266.394364648077;5269.051570717269;5271.499207575274;5271.947280527184;11616.825747613793;12793.808092878615;13556.389962451713;16911.816172867904;20316.187352505884;2268.965965510519;2269.1490690990877;2377.465070359173;2505.6993160820816;2575.871206439689;10366.274360794747;13049.412776425084;13515.189088654613;13773.104748191336;13857.4457021719;1787.7765279340083;1806.665766833127;1842.060549119945;1966.4592237134902;1972.8421726261865;65846.3415365845;68478.3152168477;70137;70702.2929770706;71011.64494177548;10543.31468454548;11034.393108379116;11140.164487663458;11149.777004459866;11153.776924461465;14940.402383904739;14943.925280373529;14956.401743930339;14968.70062598742;14976.775348369783;7579.242075792376;7674.96162519195;7683.807904802425;7686.923130768732;7720.498167619086;7241.312075352833;7261.092363454568;7279.745208917769;7282.344588987016;7300.12398512182;4686.133065382915;4719.882002949954;4720.173969555314;4774.498677638868;4788.281757736298;1860.2837907405747;1948.6979518174705;1984.4046785964154;1990.2138655231104;1998.7401637787286;914.5792935249859;915.6017132547363;987.244757760314;1029.6344797596896;1107;14106.576802695936;15838.52484425469;16814.91020534458;17061.48815535536;17149.228284727218;934.6401635370296;974.449436068619;995.9950200248952;1013.3869009249445;1029.763154474475;9546.379485333431;9737.513124343754;9805.656802012038;9891.159251463563;10000.499975001221;497703;583499;590228;620961;641719;85287;85855.57072214724;86418;87460;87578;1219.8231256467761;1223.577865636354;1243.8631750507436;1252;1270.326726834779;15423.922880385975;15624;15840.841591583936;16159.43441979525;16319.020858748512;34751.82624086965;34918.47622285617;35025;35071;35492.29015419729;45224.547754522035;46071;46694.76652616715;46976.29535557038;47084.52915470939;9955.850662239927;10292.639757608447;10738.946305268424;10748.785024299314;10752.086072683596;12447.253164810141;14688.115725152762;18948.621027579095;19164.520886977378;19258.90370548138;15881.761773573637;15934.840651593799;15965.361385544913;15998.440054598499;16013.599660008593;15129.924350378178;15261.6184595386;15458;15603.687926241639;15783.605409864385;14979.576940190664;15340.616484587974;15423.305951232445;15600.453984110502;15640.843591563938;10104.343217690826;10360.792784144425;10387.844182337421;10485.528151233366;10559.313644613076;10828.891711083104;11623.476943537393;12740.235585864508;14135.081219720685;14487.420503180176 09:25:45 INFO - 2016-02-17 09:25:45,714 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1586.3892401425448;1619.765134055562;1698.0745493705924;1854.332440321484;1909.5130741660882;7174.784756457315;7400.074990626172;8172.754817355474;8382.790430239247;8394.706185283516;7822.687092516307;7912.287894089531;8020.0776910655295;8089;8095.91904080959;2297.310806757974;2330.0912644068826;2330.7319658239267;2333.4049817296564;2335.754745751697;2005.689118186676;2029.786872378401;2297.4141593893532;2333.0784340185614;2369.5379401016808;2312.075169932024;2317.8261630377788;2341.906323747048;2341.9648705269433;2342.344143639775;2357.57563638545;2380.1074597026113;2386.2125498585424;2388.5700573896693;2389.3668177932864;2289.0614847912443;2370.9525809483885;2371.395294199968;2371.65610986406;2373.703287089114;8167.877481837777;8198;8220.753377398687;8235.176482351717;8252.83494330116;4879.926801097987;4969.453360130383;4978.079055374767;4981.850544483671;4985.302057711923;3245.6105267368066;3253.707166355016;3294.028261662815;3305.140663427502;3321.368939901435;1030.1449796668746;1049.1134990932649;1064.4411683866033;1068.8723396816356;1095.8794532601407;11344.43277836105;11353.886461135366;11365.602203922906;11378.31730096197;11397.373144477133;4451.955480445219;4467.508574056851;4490.663200259994;4501.414816073955;4537.818487260539;9653.565589548487;9667;9670.661526846667;9672.613095476243;9678.612855485842;4879.926801097987;4879.975600121977;4883.731394773322;4887.486813884562;4888.926666100011;4389.385486031958;4397.472303323621;4399.6040356368085;4400.625946794495;4401;3460.1695593057484;3897.0647044709067;3914.8434062637743;3958.9208215835524;3963.4649322341115;1137.113051819582;1232.2606436138246;1255.918365306252;1260.0612543655036;1261.1424231522653;1185.525789684116;1263.444084602772;1409.3094383752093;1470.1326217531707;1474.4249742600393;7943.44395892282;8010.759677209693;8011.519308841488;8013.719519816778;8024.879626805603;1177.8409914661581;1192.272713644676;1196.9521219151136;1209.5282839692527;1232.6302109367155;6152.876942461216;6201.131841542187;6256.843578910563;6271.62370257786;6338.461230795434;9676.9032309676;9900.405975641484;9911.851322230028;9917.603295868228;9930.10629043375;9628.90371096294;9696.757581060388;9770.755731106638;9780.119789218863;9800.411975281364;7921.485103468256;7986.640601172845;7990.600469976478;8075.394345424117;8077.313428358655;7869;7942.563159026309;7981.92018079835;7990.320822730133;7995.920040799518;8320.791980200544;8568.35737319716;8903;9059.139381758723;9068.183863452321;8506.362022848434;8711.39020268575;8713.300906323206;9253;9278.11857873501;7440.813979650552;7566.24337566239;7567.356774674215;7568.962155189189;7569.848603027798;6511.967440162769;6650.900236496554;7988.161243069394;8001.439899207;8113.35093192555;7199.640017999184;7315.743948961761;7344.118705755343;7458.254174582716;7497.812554686177;6994.475414343843;7319.2314806944505;7392.482526223328;7471.775846724607;7495.700171993278;7306.853862922818;7343.963280183565;7357;7365.226651201761;7370.262973702587;6722.159730033746;6936.34104760047;6946.687399067156;6953.165620125617;6959.19969203548;6868.381845633917;6899.103116594811;6899.482538809509;6902.275261097512;6910.101686780687 09:25:45 INFO - 2016-02-17 09:25:45,715 DEBUG : BROWSER_OUTPUT: |4;prototype.html;366.4100797715677;370.342641810786;373.03012168362255;373.19203923505614;380.36099353086803;6114.3579924108;6154.169187159738;6246;6261;6271.498280137595;5989.4010598940085;6154.323024467305;6199.132121502993;6224.159738435321;6228.8131356059275;4842.6368022398465;4845.951540484585;4846.757662116897;4846.830360937368;4848.927266091011;4611.077784443108;4707.24684050552;4710.835120770774;4743.620510359163;4860.878478038042;4577.908441831161;4607.424071991001;4691.788869500863;4794.472608013116;4794.904101917977;4771.260454629538;4773.403324584427;4781.402324709411;4783.808647654089;4784.569388755029;4241.384999175133;4254.680898932593;4256.297710877701;4267.423897773823;4272.145570885836;5877.059670452707;5877.647341159544;5883.176355310217;5888.4700376966275;5902.61632993854;5484;5590.440955904418;5594.160875868612;5594.776208951637;5595.132754423071;6537;6545.9018114728315;6551.672416379162;6552.213734351861;6555.770548030796;4481.820727170909;4591.586757191835;4715.693479923828;4718.60801063687;4723.645726570521;6679.064930909676;6810.965945170243;6823.420009299216;6824.658767061627;6838.48711346646;4799.112164249625;4850.708957462563;4852.223644216909;4860.9513904861205;4861.927071093936;4902.0441014002645;4909.803607855717;4914.213725803854;4914.631402644816;4915.5330243626795;5425.972870135624;5569.248151499496;5570;5581.2186293918885;5581.832545023656;6546.672666366758;6580.80257592273;6584.144061272004;6599.571027883172;6609.636469994197;1727.680379129865;1743.7384392340996;1747.012937690201;1747.484492074841;1748.0822568151823;1203.9759204815855;1207.5290636651712;1208.4622343057256;1228.1464382254248;1239.8202260672151;6278.937210627927;6299.937000630027;6302.401271879165;6308.0537919311555;6325.715342809584;6831.829204269933;6833.077534532775;6846.246912839583;6847.417969472651;6858.833998220314;6333.113364129025;6429.742810287629;6443.742250309935;6446.3553644636095;6452.290248072708;3694.8522059117336;3724.404095344732;3731.7947512887054;3731.8320675569666;3732.0669832541867;7142.4286057115305;7155.713771449084;7173.784786456415;7175.605341706257;7179.066721326299;6931.480138989596;7061.540999835097;7078.433725301964;7078.716851325992;7084.787456376317;7143.535670181422;7150.177729561063;7156.821079473055;7164.068671072727;7170.032045673872;6921.688524016532;6924.238333783179;6925;6926.757563485254;6928.792136235719;6345.8730825384155;6531.150950376421;6660.6336651484635;6691.9962005699535;6807.353301436356;7003;7011.754588589375;7012.333828286304;7163.677634506355;7175.390091842252;4705;4734.597559207506;4740.5733483986605;4741.288806679026;4743.478217396015;6709.127813384229;6839.179298484214;6847.315268473312;6933.5493192942295;6941.687624057031;6708.19501659804;6722.798316050526;6726.764563240263;6727.428168605723;6730;6683.365080317362;6684.364985326386;6687.565308254948;6690.69891854858;6691.46468282546;6469.45009674183;6557.704903279268;6572.047053177358;6575.96712016456;6584.736610535525;6733.7306507741105;6737.191537015589;6737.9326206737305;6740.39336459721;6745.392914637706;6529.7061632227615;6534.444572211225;6536.444402225864;6537.411632953057;6545.050967609765 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: |5;yui.html;831.6008316008312;866.203093154298;908.5094049213425;932.9860052099218;945.1115951006058;13483.730325393486;13886;14689.485867994623;14732.263386830642;14736.557903262907;13720.51978180764;13774.380152893118;13790.862091379084;13868.861311386883;13902.652433689163;730.9561426314411;736.1681300130845;877.9648814047447;882.629295695806;899.8920129584426;902.62992173209;906.3927168796922;911.1571796088625;912.8402529557334;916.6104405627615;759.3166150464598;873.4191762477944;947.5783276441986;959.6497278493334;960.279790157382;760.1828034862544;764.5756605084176;937.3672770879629;950.230313446104;952.0812416018575;705.9435245180373;706.4736771105507;706.5901776969346;706.6219572528672;707.2891743797502;13586.728265434636;13615.18308901469;13672.726545469135;13687.384067716977;13708.65728356789;12716.809247861289;13063;13191.538296159684;13237.470501179941;13392.196468211938;10540.36624323231;10607.469626518721;10609.628662996758;10684.626038088629;10709.25035247533;3206.71139597435;3278.8360581971046;3291.308825146722;3317.701406873393;3332.266901281714;45632;46110;46137.76931115323;47048;48332.27501587479;10817.675469735921;10834.783304333869;10865.348079115278;11007.889921100686;11062.22564420483;19621.743499497454;19825.405237842886;19831.801681983285;19842.60314793725;19855.106520206624;8053.263483937774;8065.395095367753;8226.341892648574;8249.092599814016;8284.958575207085;4276.48682158143;4313.676474264443;4367.432233809584;4410.955890441119;4463.4420697412825;3265.4775235962134;3266.1344743642953;3268.542404063433;3271.0513950954432;3298.1259966109;567.1974156568492;567.7445149682659;570.4010788671878;572.5905977226303;592.7154965616442;665.3213722003529;667.882504360621;680.5712401187319;691.6334342798325;703.1246098607267;8973.551322433852;9678.419294842332;9691.70924872255;9767.755806104762;10034.598616055422;483.1231315162968;633.3666333666333;642.5052709413725;642.6080091144398;691.4572060932144;3815.2560250751394;3953.327934251184;3990.20195960809;4036.374361973899;4192.161567686475;60165;60720.696396517735;61347.69326153341;61714.38285617176;63400.68299658472;52957.7352113237;53702.731486342316;53883;55139.17291240635;56822.7158864203;49010.75494622575;50177.49822501728;50270.48647567607;50286.24570631443;50397.24404133941;8398.538080405637;8890.377673562789;9017.909820901707;9057.501837398742;9412.19996300322;35616.28767424689;36154.63845361618;37303;37973.620263797005;38183.045423863514;46566.76716616531;46717;47717.761411192725;48126;48222.27666585074;11599.304041757521;11637.592684256011;11871.584494542994;12116.27302361861;12122.75754484923;13285;13314.933425333198;13366;13400.798988015382;13417.194968301934;12194.085443591588;12282.631521054383;12384.318862462651;12718.618441446772;12818.679533011376;11015.063719583924;11192.888071119185;11303.321800691985;11401;11478.426078695864;8592.287999840251;12075.094367922617;12193.939030304791;12777.74444511086;12894.67763305925;10200;10432.791344172922;10493.370397775852;10628.468576571295;10755.408452535186;9678;9854;9982.251331150337;9987.750306242402;9989.900100999188 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,792 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: __startTimestamp1455729945706__endTimestamp 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,717 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5555.88 Median:5787.02 stddev:611.53 (10.6%) stddev-sans-first:586.33 09:25:45 INFO - 2016-02-17 09:25:45,718 DEBUG : BROWSER_OUTPUT: Values: 3261.9 3337.5 3373.6 3378.4 3397.1 5552.9 5563.3 5578.0 5579.3 5588.4 5539.0 5594.2 5594.8 5608.2 5624.5 5004.2 5021.7 5034.5 5106.4 5150.6 4989.9 5082.1 5112.4 5116.6 5117.9 5008.7 5127.4 5136.2 5141.8 5155.6 5112.7 5192.4 5211.4 5211.7 5249.2 4893.7 4992.0 5077.8 5088.6 5098.2 5429.5 5434.8 5442.0 5442.6 5474.8 5952.3 5954.7 5996.6 6003.4 6013.5 5800.9 5863.4 5943.8 6029.3 6045.2 5645.7 5669.3 5677.6 5678.1 5678.2 6158.9 6163.4 6169.3 6178.0 6182.1 5646.9 5760.9 5775.2 5958.3 5977.3 5872.9 5885.2 5885.8 5918.5 5942.3 5816.9 5865.9 5867.2 5870.9 5884.0 5691.2 5787.0 5793.5 5862.9 5869.5 4508.5 4568.6 4572.2 4586.0 4589.9 4088.8 4157.8 4165.4 4174.0 4198.3 5481.2 5583.6 5802.7 5840.0 5841.8 5878.9 5938.6 5958.3 5959.6 6001.7 5610.9 5631.8 5632.7 5636.6 5643.7 5009.3 5099.9 5158.9 5188.2 5247.9 6219.1 6219.7 6228.3 6234.0 6235.4 6020.8 6022.7 6025.1 6030.0 6062.3 5930.1 6057.6 6097.1 6155.6 6227.1 5941.9 5966.3 5973.0 5983.4 5997.9 6095.7 6109.3 6117.0 6132.0 6133.0 5672.5 5780.7 5789.4 5836.7 5919.2 5902.7 5915.4 5916.9 5922.0 5927.9 5615.0 5799.6 5841.8 5843.1 5844.6 5780.7 5818.9 5905.9 5907.1 5914.9 5733.7 5786.8 5793.2 5803.2 5813.7 09:25:45 INFO - 2016-02-17 09:25:45,718 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,718 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48546.66 Median:330859.00 stddev:78833.85 (23.8%) stddev-sans-first:78972.93 09:25:45 INFO - 2016-02-17 09:25:45,718 DEBUG : BROWSER_OUTPUT: Values: 638.4 638.5 660.0 673.5 695.4 39656.0 40241.8 40279.8 40317.8 40370.4 46797.8 47530.0 47675.0 47687.0 47711.8 7578.9 7674.1 8960.6 9051.1 10268.7 10531.5 10634.9 10661.0 10675.2 10692.5 10584.6 10761.8 10775.8 10799.9 10802.4 10536.6 10613.9 10628.4 10631.6 10640.4 10467.4 10561.3 10567.8 10569.8 10577.3 42755.6 43017.0 43430.6 43430.8 43437.3 3728.5 3889.8 3907.3 4111.2 4529.0 32658.0 32789.8 32866.5 33012.0 33438.0 4187.4 4270.0 4274.4 4281.9 4286.0 291496.0 293854.0 294437.0 295658.0 296350.0 57573.1 58686.1 65499.0 73013.3 74198.0 66698.7 67208.7 67490.3 67558.0 67673.3 14926.2 15069.0 15074.4 15077.2 15093.1 10764.0 10776.0 10802.7 10812.8 11018.1 22149.6 22195.3 22295.1 22322.6 22458.6 7850.6 8109.1 8167.9 8172.2 8244.6 902.3 989.2 1012.5 1139.6 1175.4 26269.9 26668.9 26704.3 26917.9 26942.2 963.4 1031.9 1045.1 1080.1 1167.7 13666.6 14001.4 16146.5 16174.7 16762.0 275631.0 276992.0 277259.0 278069.0 279099.0 57256.7 58024.4 58393.0 58398.4 58437.4 1249.7 1427.5 1434.9 1438.9 1452.9 23321.0 30502.8 31099.0 31603.2 31835.8 55364.7 56041.7 56057.4 56101.2 56129.2 329002.0 330225.0 331493.0 331512.0 333120.0 29502.9 29589.7 34624.5 35234.6 35656.0 41003.1 41049.8 41055.6 42040.8 42226.1 34406.5 34420.3 34590.5 35029.1 35345.8 36996.3 37507.0 37578.2 38369.4 39523.2 35055.5 39006.4 39073.2 39213.6 39232.0 30006.7 31281.7 31643.8 31951.8 32639.3 25037.1 25044.4 25048.1 25247.7 25539.1 09:25:45 INFO - 2016-02-17 09:25:45,718 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,718 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30836.76 Median:1994.48 stddev:96293.21 (4828.0%) stddev-sans-first:96536.20 09:25:45 INFO - 2016-02-17 09:25:45,719 DEBUG : BROWSER_OUTPUT: Values: 390.7 391.7 394.1 396.3 398.5 16991.8 18514.4 18524.8 18593.6 20078.8 17472.9 17517.0 17696.0 18086.8 18104.2 5351.9 5477.4 5499.7 5508.2 5511.6 4490.6 5324.6 5491.7 5518.4 5530.1 4061.1 4128.3 5497.8 5511.6 5959.6 6067.9 6091.6 6094.7 6118.8 6121.9 5161.4 5266.4 5269.1 5271.5 5271.9 11616.8 12793.8 13556.4 16911.8 20316.2 2269.0 2269.1 2377.5 2505.7 2575.9 10366.3 13049.4 13515.2 13773.1 13857.4 1787.8 1806.7 1842.1 1966.5 1972.8 65846.3 68478.3 70137.0 70702.3 71011.6 10543.3 11034.4 11140.2 11149.8 11153.8 14940.4 14943.9 14956.4 14968.7 14976.8 7579.2 7675.0 7683.8 7686.9 7720.5 7241.3 7261.1 7279.7 7282.3 7300.1 4686.1 4719.9 4720.2 4774.5 4788.3 1860.3 1948.7 1984.4 1990.2 1998.7 914.6 915.6 987.2 1029.6 1107.0 14106.6 15838.5 16814.9 17061.5 17149.2 934.6 974.4 996.0 1013.4 1029.8 9546.4 9737.5 9805.7 9891.2 10000.5 497703.0 583499.0 590228.0 620961.0 641719.0 85287.0 85855.6 86418.0 87460.0 87578.0 1219.8 1223.6 1243.9 1252.0 1270.3 15423.9 15624.0 15840.8 16159.4 16319.0 34751.8 34918.5 35025.0 35071.0 35492.3 45224.5 46071.0 46694.8 46976.3 47084.5 9955.9 10292.6 10738.9 10748.8 10752.1 12447.3 14688.1 18948.6 19164.5 19258.9 15881.8 15934.8 15965.4 15998.4 16013.6 15129.9 15261.6 15458.0 15603.7 15783.6 14979.6 15340.6 15423.3 15600.5 15640.8 10104.3 10360.8 10387.8 10485.5 10559.3 10828.9 11623.5 12740.2 14135.1 14487.4 09:25:45 INFO - 2016-02-17 09:25:45,719 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,719 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5764.34 Median:6581.43 stddev:2986.42 (45.4%) stddev-sans-first:2978.29 09:25:45 INFO - 2016-02-17 09:25:45,719 DEBUG : BROWSER_OUTPUT: Values: 1586.4 1619.8 1698.1 1854.3 1909.5 7174.8 7400.1 8172.8 8382.8 8394.7 7822.7 7912.3 8020.1 8089.0 8095.9 2297.3 2330.1 2330.7 2333.4 2335.8 2005.7 2029.8 2297.4 2333.1 2369.5 2312.1 2317.8 2341.9 2342.0 2342.3 2357.6 2380.1 2386.2 2388.6 2389.4 2289.1 2371.0 2371.4 2371.7 2373.7 8167.9 8198.0 8220.8 8235.2 8252.8 4879.9 4969.5 4978.1 4981.9 4985.3 3245.6 3253.7 3294.0 3305.1 3321.4 1030.1 1049.1 1064.4 1068.9 1095.9 11344.4 11353.9 11365.6 11378.3 11397.4 4452.0 4467.5 4490.7 4501.4 4537.8 9653.6 9667.0 9670.7 9672.6 9678.6 4879.9 4880.0 4883.7 4887.5 4888.9 4389.4 4397.5 4399.6 4400.6 4401.0 3460.2 3897.1 3914.8 3958.9 3963.5 1137.1 1232.3 1255.9 1260.1 1261.1 1185.5 1263.4 1409.3 1470.1 1474.4 7943.4 8010.8 8011.5 8013.7 8024.9 1177.8 1192.3 1197.0 1209.5 1232.6 6152.9 6201.1 6256.8 6271.6 6338.5 9676.9 9900.4 9911.9 9917.6 9930.1 9628.9 9696.8 9770.8 9780.1 9800.4 7921.5 7986.6 7990.6 8075.4 8077.3 7869.0 7942.6 7981.9 7990.3 7995.9 8320.8 8568.4 8903.0 9059.1 9068.2 8506.4 8711.4 8713.3 9253.0 9278.1 7440.8 7566.2 7567.4 7569.0 7569.8 6512.0 6650.9 7988.2 8001.4 8113.4 7199.6 7315.7 7344.1 7458.3 7497.8 6994.5 7319.2 7392.5 7471.8 7495.7 7306.9 7344.0 7357.0 7365.2 7370.3 6722.2 6936.3 6946.7 6953.2 6959.2 6868.4 6899.1 6899.5 6902.3 6910.1 09:25:45 INFO - 2016-02-17 09:25:45,720 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,720 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5567.49 Median:6289.44 stddev:1655.43 (26.3%) stddev-sans-first:1613.38 09:25:45 INFO - 2016-02-17 09:25:45,720 DEBUG : BROWSER_OUTPUT: Values: 366.4 370.3 373.0 373.2 380.4 6114.4 6154.2 6246.0 6261.0 6271.5 5989.4 6154.3 6199.1 6224.2 6228.8 4842.6 4846.0 4846.8 4846.8 4848.9 4611.1 4707.2 4710.8 4743.6 4860.9 4577.9 4607.4 4691.8 4794.5 4794.9 4771.3 4773.4 4781.4 4783.8 4784.6 4241.4 4254.7 4256.3 4267.4 4272.1 5877.1 5877.6 5883.2 5888.5 5902.6 5484.0 5590.4 5594.2 5594.8 5595.1 6537.0 6545.9 6551.7 6552.2 6555.8 4481.8 4591.6 4715.7 4718.6 4723.6 6679.1 6811.0 6823.4 6824.7 6838.5 4799.1 4850.7 4852.2 4861.0 4861.9 4902.0 4909.8 4914.2 4914.6 4915.5 5426.0 5569.2 5570.0 5581.2 5581.8 6546.7 6580.8 6584.1 6599.6 6609.6 1727.7 1743.7 1747.0 1747.5 1748.1 1204.0 1207.5 1208.5 1228.1 1239.8 6278.9 6299.9 6302.4 6308.1 6325.7 6831.8 6833.1 6846.2 6847.4 6858.8 6333.1 6429.7 6443.7 6446.4 6452.3 3694.9 3724.4 3731.8 3731.8 3732.1 7142.4 7155.7 7173.8 7175.6 7179.1 6931.5 7061.5 7078.4 7078.7 7084.8 7143.5 7150.2 7156.8 7164.1 7170.0 6921.7 6924.2 6925.0 6926.8 6928.8 6345.9 6531.2 6660.6 6692.0 6807.4 7003.0 7011.8 7012.3 7163.7 7175.4 4705.0 4734.6 4740.6 4741.3 4743.5 6709.1 6839.2 6847.3 6933.5 6941.7 6708.2 6722.8 6726.8 6727.4 6730.0 6683.4 6684.4 6687.6 6690.7 6691.5 6469.5 6557.7 6572.0 6576.0 6584.7 6733.7 6737.2 6737.9 6740.4 6745.4 6529.7 6534.4 6536.4 6537.4 6545.1 09:25:45 INFO - 2016-02-17 09:25:45,720 DEBUG : BROWSER_OUTPUT: 09:25:45 INFO - 2016-02-17 09:25:45,720 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14532.30 Median:45871.00 stddev:16786.32 (36.6%) stddev-sans-first:16801.88 09:25:45 INFO - 2016-02-17 09:25:45,721 DEBUG : BROWSER_OUTPUT: Values: 831.6 866.2 908.5 933.0 945.1 13483.7 13886.0 14689.5 14732.3 14736.6 13720.5 13774.4 13790.9 13868.9 13902.7 731.0 736.2 878.0 882.6 899.9 902.6 906.4 911.2 912.8 916.6 759.3 873.4 947.6 959.6 960.3 760.2 764.6 937.4 950.2 952.1 705.9 706.5 706.6 706.6 707.3 13586.7 13615.2 13672.7 13687.4 13708.7 12716.8 13063.0 13191.5 13237.5 13392.2 10540.4 10607.5 10609.6 10684.6 10709.3 3206.7 3278.8 3291.3 3317.7 3332.3 45632.0 46110.0 46137.8 47048.0 48332.3 10817.7 10834.8 10865.3 11007.9 11062.2 19621.7 19825.4 19831.8 19842.6 19855.1 8053.3 8065.4 8226.3 8249.1 8285.0 4276.5 4313.7 4367.4 4411.0 4463.4 3265.5 3266.1 3268.5 3271.1 3298.1 567.2 567.7 570.4 572.6 592.7 665.3 667.9 680.6 691.6 703.1 8973.6 9678.4 9691.7 9767.8 10034.6 483.1 633.4 642.5 642.6 691.5 3815.3 3953.3 3990.2 4036.4 4192.2 60165.0 60720.7 61347.7 61714.4 63400.7 52957.7 53702.7 53883.0 55139.2 56822.7 49010.8 50177.5 50270.5 50286.2 50397.2 8398.5 8890.4 9017.9 9057.5 9412.2 35616.3 36154.6 37303.0 37973.6 38183.0 46566.8 46717.0 47717.8 48126.0 48222.3 11599.3 11637.6 11871.6 12116.3 12122.8 13285.0 13314.9 13366.0 13400.8 13417.2 12194.1 12282.6 12384.3 12718.6 12818.7 11015.1 11192.9 11303.3 11401.0 11478.4 8592.3 12075.1 12193.9 12777.7 12894.7 10200.0 10432.8 10493.4 10628.5 10755.4 9678.0 9854.0 9982.3 9987.8 9989.9 09:25:45 INFO - 2016-02-17 09:25:45,721 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:25:45 INFO - 2016-02-17 09:25:45,721 DEBUG : BROWSER_OUTPUT: 09:25:46 INFO - 2016-02-17 09:25:46,199 INFO : Browser exited with error code: 0 09:25:46 INFO - 2016-02-17 09:25:46,222 INFO : Completed test dromaeo_css (00:18:30) 09:25:46 INFO - 2016-02-17 09:25:46,223 INFO : Starting test kraken 09:25:46 INFO - 2016-02-17 09:25:46,223 DEBUG : operating with platform_type : linux_ 09:25:46 INFO - 2016-02-17 09:25:46,223 INFO : Initialising browser for kraken test... 09:25:46 INFO - 2016-02-17 09:25:46,229 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWaVr49/profile http://localhost:52947/getInfo.html 09:25:49 INFO - 2016-02-17 09:25:49,993 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:25:49 INFO - 2016-02-17 09:25:49,993 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:25:49 INFO - 2016-02-17 09:25:49,993 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:25:49 INFO - 2016-02-17 09:25:49,993 DEBUG : BROWSER_OUTPUT: __metrics 09:25:50 INFO - 2016-02-17 09:25:50,155 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] 09:25:51 INFO - 2016-02-17 09:25:51,319 INFO : Browser initialized. 09:25:51 INFO - 2016-02-17 09:25:51,319 INFO : Running cycle 1/1 for kraken test... 09:25:51 INFO - 2016-02-17 09:25:51,320 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWaVr49/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:26:02 INFO - 2016-02-17 09:26:02,945 DEBUG : BROWSER_OUTPUT: RSS: Main: 134152192 09:26:02 INFO - 2016-02-17 09:26:02,946 DEBUG : BROWSER_OUTPUT: 09:26:05 INFO - 2016-02-17 09:26:05,401 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52947/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:05 INFO - 2016-02-17 09:26:05,983 DEBUG : BROWSER_OUTPUT: RSS: Main: 164499456 09:26:05 INFO - 2016-02-17 09:26:05,983 DEBUG : BROWSER_OUTPUT: 09:26:08 INFO - 2016-02-17 09:26:08,547 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52947/tests/kraken/driver.html?testName=audio-dft) 09:26:09 INFO - 2016-02-17 09:26:09,116 DEBUG : BROWSER_OUTPUT: RSS: Main: 162623488 09:26:09 INFO - 2016-02-17 09:26:09,116 DEBUG : BROWSER_OUTPUT: 09:26:11 INFO - 2016-02-17 09:26:11,922 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52947/tests/kraken/driver.html?testName=audio-fft) 09:26:12 INFO - 2016-02-17 09:26:12,488 DEBUG : BROWSER_OUTPUT: RSS: Main: 159571968 09:26:12 INFO - 2016-02-17 09:26:12,488 DEBUG : BROWSER_OUTPUT: 09:26:14 INFO - 2016-02-17 09:26:14,503 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52947/tests/kraken/driver.html?testName=audio-oscillator) 09:26:15 INFO - 2016-02-17 09:26:15,070 DEBUG : BROWSER_OUTPUT: RSS: Main: 161226752 09:26:15 INFO - 2016-02-17 09:26:15,070 DEBUG : BROWSER_OUTPUT: 09:26:17 INFO - 2016-02-17 09:26:17,272 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52947/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:17 INFO - 2016-02-17 09:26:17,834 DEBUG : BROWSER_OUTPUT: RSS: Main: 158736384 09:26:17 INFO - 2016-02-17 09:26:17,834 DEBUG : BROWSER_OUTPUT: 09:26:20 INFO - 2016-02-17 09:26:20,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52947/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:21 INFO - 2016-02-17 09:26:21,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 156995584 09:26:21 INFO - 2016-02-17 09:26:21,267 DEBUG : BROWSER_OUTPUT: 09:26:24 INFO - 2016-02-17 09:26:24,663 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52947/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:25 INFO - 2016-02-17 09:26:25,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 159731712 09:26:25 INFO - 2016-02-17 09:26:25,229 DEBUG : BROWSER_OUTPUT: 09:26:27 INFO - 2016-02-17 09:26:27,973 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52947/tests/kraken/driver.html?testName=json-parse-financial) 09:26:28 INFO - 2016-02-17 09:26:28,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 158461952 09:26:28 INFO - 2016-02-17 09:26:28,542 DEBUG : BROWSER_OUTPUT: 09:26:30 INFO - 2016-02-17 09:26:30,078 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52947/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:30 INFO - 2016-02-17 09:26:30,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 166666240 09:26:30 INFO - 2016-02-17 09:26:30,633 DEBUG : BROWSER_OUTPUT: 09:26:32 INFO - 2016-02-17 09:26:32,370 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:32 INFO - 2016-02-17 09:26:32,978 DEBUG : BROWSER_OUTPUT: RSS: Main: 164311040 09:26:32 INFO - 2016-02-17 09:26:32,978 DEBUG : BROWSER_OUTPUT: 09:26:34 INFO - 2016-02-17 09:26:34,970 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:26:35 INFO - 2016-02-17 09:26:35,537 DEBUG : BROWSER_OUTPUT: RSS: Main: 171077632 09:26:35 INFO - 2016-02-17 09:26:35,537 DEBUG : BROWSER_OUTPUT: 09:26:37 INFO - 2016-02-17 09:26:37,903 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:26:38 INFO - 2016-02-17 09:26:38,485 DEBUG : BROWSER_OUTPUT: RSS: Main: 174628864 09:26:38 INFO - 2016-02-17 09:26:38,485 DEBUG : BROWSER_OUTPUT: 09:26:40 INFO - 2016-02-17 09:26:40,973 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:26:41 INFO - 2016-02-17 09:26:41,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 173862912 09:26:41 INFO - 2016-02-17 09:26:41,538 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52947/tests/kraken/driver.html?testName=ai-astar) 09:26:43 INFO - 2016-02-17 09:26:43,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 231251968 09:26:43 INFO - 2016-02-17 09:26:43,433 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;112;104;109;113;109;99;108;109;111 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;124;145;161;122;143;153;123;162;125 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;160;163;159;162;161;161;160;160 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;79;73;158;71;73;71;72;164;71 09:26:43 INFO - 2016-02-17 09:26:43,434 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;92;132;92;93;92;133;93;93;137 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;109;109;109;110;110;109 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;145;146;147;147;145;146;145 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;94;96;95;95;96;95;96;95;95;96 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;56;56;56;58;57;56;59;54 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;65;64;64;65;64;64;64 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;77;78;78;78;81;78;78;78;77 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;127;118;112;112;114;120;118;111;129;119 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;138;144;139;147;139;139;142;138;130 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;64;64;64;65;65;63;64;66;63 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:26:43 INFO - 2016-02-17 09:26:43,435 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,918 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: __startTimestamp1455730003428__endTimestamp 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.40 Median:110.50 stddev:4.12 (3.7%) stddev-sans-first:4.32 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: Values: 110.0 112.0 104.0 109.0 113.0 109.0 99.0 108.0 109.0 111.0 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.40 Median:145.50 stddev:15.83 (10.9%) stddev-sans-first:16.66 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: Values: 146.0 124.0 145.0 161.0 122.0 143.0 153.0 123.0 162.0 125.0 09:26:43 INFO - 2016-02-17 09:26:43,436 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 160.0 163.0 159.0 162.0 161.0 161.0 160.0 160.0 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:90.50 Median:72.50 stddev:37.26 (51.4%) stddev-sans-first:38.97 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: Values: 73.0 79.0 73.0 158.0 71.0 73.0 71.0 72.0 164.0 71.0 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.00 Median:92.50 stddev:20.06 (21.7%) stddev-sans-first:20.80 09:26:43 INFO - 2016-02-17 09:26:43,438 DEBUG : BROWSER_OUTPUT: Values: 93.0 92.0 132.0 92.0 93.0 92.0 133.0 93.0 93.0 137.0 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 109.0 109.0 109.0 110.0 110.0 109.0 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 145.0 146.0 147.0 147.0 145.0 146.0 145.0 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.53 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: Values: 94.0 96.0 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.00 stddev:1.48 (2.6%) stddev-sans-first:1.50 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 56.0 56.0 56.0 58.0 57.0 56.0 59.0 54.0 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,439 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 65.0 64.0 64.0 65.0 64.0 64.0 64.0 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:1.15 (1.5%) stddev-sans-first:1.17 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: Values: 77.0 77.0 78.0 78.0 78.0 81.0 78.0 78.0 78.0 77.0 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:118.00 Median:118.50 stddev:6.18 (5.2%) stddev-sans-first:5.63 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: Values: 127.0 118.0 112.0 112.0 114.0 120.0 118.0 111.0 129.0 119.0 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.50 Median:139.00 stddev:4.45 (3.2%) stddev-sans-first:4.72 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: Values: 139.0 138.0 144.0 139.0 147.0 139.0 139.0 142.0 138.0 130.0 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:64.50 stddev:1.07 (1.7%) stddev-sans-first:0.97 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: Values: 66.0 64.0 64.0 64.0 65.0 65.0 63.0 64.0 66.0 63.0 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:26:43 INFO - 2016-02-17 09:26:43,440 DEBUG : BROWSER_OUTPUT: 09:26:44 INFO - 2016-02-17 09:26:44,143 INFO : Browser exited with error code: 0 09:26:44 INFO - 2016-02-17 09:26:44,196 INFO : Completed test kraken (00:00:57) 09:26:44 INFO - 2016-02-17 09:26:44,197 INFO : Starting test v8_7 09:26:44 INFO - 2016-02-17 09:26:44,197 DEBUG : operating with platform_type : linux_ 09:26:44 INFO - 2016-02-17 09:26:44,197 INFO : Initialising browser for v8_7 test... 09:26:44 INFO - 2016-02-17 09:26:44,203 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLvDVXO/profile http://localhost:52947/getInfo.html 09:26:47 INFO - 2016-02-17 09:26:47,942 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:26:47 INFO - 2016-02-17 09:26:47,942 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:26:47 INFO - 2016-02-17 09:26:47,942 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:26:47 INFO - 2016-02-17 09:26:47,942 DEBUG : BROWSER_OUTPUT: __metrics 09:26:48 INFO - 2016-02-17 09:26:48,119 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] 09:26:49 INFO - 2016-02-17 09:26:49,279 INFO : Browser initialized. 09:26:49 INFO - 2016-02-17 09:26:49,279 INFO : Running cycle 1/1 for v8_7 test... 09:26:49 INFO - 2016-02-17 09:26:49,280 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLvDVXO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:27:00 INFO - 2016-02-17 09:27:00,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 132657152 09:27:00 INFO - 2016-02-17 09:27:00,904 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,252 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52947/tests/v8_7/run.html (next: http://localhost:52947/tests/v8_7/run.html) 09:27:22 INFO - 2016-02-17 09:27:22,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 422522880 09:27:22 INFO - 2016-02-17 09:27:22,510 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,510 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |0;Richards;168.40687100033682 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.63615409944023 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.78449958182324 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5213.541666666667 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |4;RayTrace;946.073793755913 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |5;Earley;543.4782608695652 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |6;Boyer;10437.5 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |7;RegExp;24190.47619047619 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |8;Splay;527.7044854881267 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: __startTimestamp1455730042504__endTimestamp 09:27:22 INFO - 2016-02-17 09:27:22,511 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:168.41 Median:168.41 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: Values: 168.4 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.64 Median:164.64 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: Values: 164.6 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.78 Median:278.78 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: Values: 278.8 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5213.54 Median:5213.54 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: Values: 5213.5 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,512 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:946.07 Median:946.07 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: Values: 946.1 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:543.48 Median:543.48 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: Values: 543.5 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10437.50 Median:10437.50 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: Values: 10437.5 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24190.48 Median:24190.48 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: Values: 24190.5 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:527.70 Median:527.70 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: Values: 527.7 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: 09:27:22 INFO - 2016-02-17 09:27:22,513 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 09:27:22 INFO - 2016-02-17 09:27:22,514 DEBUG : BROWSER_OUTPUT: Values: 4826.9 09:27:22 INFO - 2016-02-17 09:27:22,514 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:27:22 INFO - 2016-02-17 09:27:22,514 DEBUG : BROWSER_OUTPUT: 09:27:23 INFO - 2016-02-17 09:27:23,151 INFO : Browser exited with error code: 0 09:27:23 INFO - 2016-02-17 09:27:23,169 INFO : Completed test v8_7 (00:00:38) 09:27:23 INFO - 2016-02-17 09:27:23,568 INFO : Completed test suite (00:20:07) 09:27:23 INFO - 2016-02-17 09:27:23,568 DEBUG : Working with test: dromaeo_css 09:27:23 INFO - 2016-02-17 09:27:23,568 DEBUG : Generating results file: dromaeo_css 09:27:23 INFO - 2016-02-17 09:27:23,569 DEBUG : Working with test: kraken 09:27:23 INFO - 2016-02-17 09:27:23,570 DEBUG : Generating results file: kraken 09:27:23 INFO - 2016-02-17 09:27:23,570 INFO : javascript benchmark 09:27:23 INFO - 2016-02-17 09:27:23,570 DEBUG : Working with test: v8_7 09:27:23 INFO - 2016-02-17 09:27:23,570 DEBUG : Generating results file: v8_7 09:27:23 INFO - 2016-02-17 09:27:23,571 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:27:23 INFO - 2016-02-17 09:27:23,654 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:27:23 INFO - 2016-02-17 09:27:23,655 DEBUG : process_Request line: dromaeo_css 7368.97 graph.html#tests=[[72,53,35]] 09:27:23 INFO - 2016-02-17 09:27:23,655 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:27:23 INFO - 2016-02-17 09:27:23,689 DEBUG : process_Request line: kraken 1475.80 graph.html#tests=[[232,53,35]] 09:27:23 INFO - 2016-02-17 09:27:23,689 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:27:23 INFO - 2016-02-17 09:27:23,725 DEBUG : process_Request line: v8_7 23277.00 graph.html#tests=[[230,53,35]] 09:27:23 INFO - 2016-02-17 09:27:23,726 INFO : javascript benchmark 09:27:23 INFO - 2016-02-17 09:27:23,729 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3261.8858339958097, 3337.516060171277, 3373.561437013188, 3378.4425569780988, 3397.099768561331, 5552.888942221153, 5563.332400111982, 5577.9999999999945, 5579.330480342354, 5588.4411558844195, 5539.002979463695, 5594.216809646653, 5594.804181853636, 5608.186812912125, 5624.521915637166, 5004.199328107504, 5021.698698078127, 5034.5468907798295, 5106.361704786902, 5150.639455238135, 4989.875253118665, 5082.08522465956, 5112.437631860493, 5116.590672746172, 5117.897642047157, 5008.674436161656, 5127.359080114986, 5136.152534831748, 5141.845744627649, 5155.639105262633, 5112.693238405689, 5192.428832828386, 5211.42674305826, 5211.713355765451, 5249.238860365225, 4893.681910675795, 4991.975040124812, 5077.796888124508, 5088.618353623456, 5098.209777484496, 5429.511343979021, 5434.782608695687, 5441.972790136064, 5442.591805614595, 5474.808381706621, 5952.285725712899, 5954.732037058343, 5996.580239383245, 6003.3996600339615, 6013.51891848651, 5800.854978625526, 5863.442972917609, 5943.791967281168, 6029.336772954972, 6045.153678485016, 5645.717714114319, 5669.291338582677, 5677.630953987978, 5678.119891416837, 5678.205051292823, 6158.907616385758, 6163.3528479509905, 6169.259688837368, 6177.96911015451, 6182.1345011698395, 5646.943530564724, 5760.94239057604, 5775.191473193756, 5958.314793798682, 5977.342492325841, 5872.911906321408, 5885.234919460443, 5885.823425297248, 5918.526517878559, 5942.257217847769, 5816.941830581724, 5865.882682346414, 5867.178594996704, 5870.853228669317, 5884.0, 5691.174779657009, 5787.016207244778, 5793.478586927196, 5862.941370586324, 5869.4717475427415, 4508.458984921765, 4568.588827005586, 4572.177008138567, 4586.0, 4589.908201835945, 4088.7751173685733, 4157.750534967911, 4165.354370072643, 4173.998240422337, 4198.307279298881, 5481.177823326453, 5583.553315734732, 5802.6808525531505, 5839.970800146057, 5841.795537156264, 5878.941210587925, 5938.584299099108, 5958.314793798682, 5959.642421454727, 6001.669908155006, 5610.915836262459, 5631.774729010794, 5632.746526406321, 5636.577256705681, 5643.717814109278, 5009.323741294951, 5099.87250318745, 5158.92261616076, 5188.195829646411, 5247.895042099137, 6219.0982307566055, 6219.689015549205, 6228.34602366754, 6234.0, 6235.438810507076, 6020.759169633166, 6022.7289771959495, 6025.096235564701, 6029.969850150721, 6062.302835173835, 5930.080837470199, 6057.606255593372, 6097.054956481752, 6155.630662160284, 6227.0970709246, 5941.9405805943115, 5966.3138739043825, 5973.0, 5983.401659833982, 5997.91003134962, 6095.664738439428, 6109.297430795337, 6116.969415152896, 6131.96934015345, 6132.969335153296, 5672.5461963042035, 5780.682062486603, 5789.363170051207, 5836.708164591669, 5919.200907877382, 5902.7343769531335, 5915.40845915405, 5916.881662366814, 5922.0, 5927.940720592739, 5614.971925140511, 5799.565032622486, 5841.824745257649, 5843.094320380348, 5844.590878638454, 5780.682062486436, 5818.912716309174, 5905.881882362414, 5907.143464197581, 5914.940850591439, 5733.684647344436, 5786.768529258782, 5793.246877905845, 5803.216565763737, 5813.680247586423], "unit": "ms", "name": "dojo.html", "value": 5516.596130850927}, {"lowerIsBetter": false, "replicates": [638.3903372279474, 638.4541217259243, 660.000099848729, 673.4545018534988, 695.3950063444804, 39656.0, 40241.79879100604, 40279.79860100695, 40317.798411007905, 40370.394444083366, 46797.76601116989, 47530.0, 47675.0, 47687.0, 47711.76144119283, 7578.924210757905, 7674.117476490219, 8960.596773145207, 9051.14014168653, 10268.74328141795, 10531.473426328692, 10634.9468252659, 10661.0, 10675.19936004799, 10692.51883665233, 10584.629537966173, 10761.78476430471, 10775.784484310308, 10799.946000269987, 10802.405867677275, 10536.578536858555, 10613.893861061368, 10628.415437150954, 10631.627893023708, 10640.361578305326, 10467.424291663956, 10561.313514621603, 10567.841482377771, 10569.78860422795, 10577.259591828575, 42755.57244427547, 43017.0, 43430.56569434297, 43430.78284608588, 43437.34843977343, 3728.459373390843, 3889.8055097245306, 3907.2966865964113, 4111.218868414991, 4528.977355113237, 32658.0, 32789.83605081983, 32866.50700239498, 33012.0, 33438.0, 4187.392828039917, 4269.978650106761, 4274.422952901349, 4281.892952676177, 4285.957140428587, 291496.0, 293854.0, 294437.0, 295658.0, 296350.0, 57573.13640295399, 58686.11970820441, 65499.0, 73013.26986730171, 74198.0, 66698.66650666716, 67208.6639566799, 67490.32509674938, 67558.0, 67673.3232667677, 14926.179060151795, 15069.020513666577, 15074.397024119131, 15077.246137693071, 15093.094414335175, 10764.0, 10776.0, 10802.67591972242, 10812.837807432894, 11018.063464605599, 22149.557008859734, 22195.33413997583, 22295.108195672317, 22322.55354892893, 22458.55082898333, 7850.646720897574, 8109.148539403278, 8167.877481837777, 8172.18278172178, 8244.557202489541, 902.3232575568323, 989.1542730965065, 1012.4785735346303, 1139.6296203733655, 1175.4240422193063, 26269.868650656623, 26668.866655666596, 26704.332391690365, 26917.86541067282, 26942.191734248005, 963.393062370702, 1031.9019693129142, 1045.1396514204728, 1080.103514083309, 1167.7197472606513, 13666.590002299947, 14001.43994240219, 16146.515604531884, 16174.676506469805, 16762.0, 275631.0, 276992.0, 277259.0, 278069.0, 279099.0, 57256.71371643115, 58024.4197558019, 58393.0, 58398.41601584015, 58437.4156258432, 1249.7188132670076, 1427.5217802036434, 1434.9067310624775, 1438.8561143885527, 1452.8837692984748, 23320.984703633483, 30502.847485762428, 31099.0, 31603.20991975219, 31835.840820795747, 55364.72317638386, 56041.719791400785, 56057.439425606855, 56101.15848262361, 56129.15806262991, 329002.0, 330225.0, 331493.0, 331512.0, 333120.0, 29502.85248573829, 29589.704102958694, 34624.480632791034, 35234.64765352416, 35656.0, 41003.12978091505, 41049.79475102606, 41055.58944410518, 42040.78979605205, 42226.099825507314, 34406.48390274198, 34420.311593768485, 34590.481142783385, 35029.12427189341, 35345.823270883484, 36996.26007479889, 37507.0, 37578.2484350306, 38369.4244586337, 39523.2095358097, 35055.47416788699, 39006.41490377703, 39073.2185356297, 39213.60786392214, 39232.0, 30006.69993300039, 31281.748730051455, 31643.84178079187, 31951.840240798647, 32639.34721305608, 25037.12370067039, 25044.373890652878, 25048.123315683864, 25247.74752252454, 25539.106131285316], "unit": "ms", "name": "ext.html", "value": 19712.455545355147}, {"lowerIsBetter": false, "replicates": [390.7421102072632, 391.723834696539, 394.09948268207154, 396.32426712455265, 398.481973434535, 16991.83008169918, 18514.3519976801, 18524.81475185248, 18593.62812743744, 20078.79921200791, 17472.912635436838, 17517.0, 17696.0, 18086.81913180868, 18104.185311660975, 5351.892962140755, 5477.370102438231, 5499.697516636584, 5508.20131080993, 5511.641743286674, 4490.550944905516, 5324.600654950875, 5491.725413729318, 5518.420565840589, 5530.115181570949, 4061.1065565575527, 4128.256913755523, 5497.780088796443, 5511.641743286694, 5959.642421454705, 6067.908981365283, 6091.634501929899, 6094.664793436359, 6118.847028824271, 6121.908171377455, 5161.3806343238675, 5266.394364648077, 5269.051570717269, 5271.499207575274, 5271.947280527184, 11616.825747613793, 12793.808092878615, 13556.389962451713, 16911.816172867904, 20316.187352505884, 2268.965965510519, 2269.1490690990877, 2377.465070359173, 2505.6993160820816, 2575.871206439689, 10366.274360794747, 13049.412776425084, 13515.189088654613, 13773.104748191336, 13857.4457021719, 1787.7765279340083, 1806.665766833127, 1842.060549119945, 1966.4592237134902, 1972.8421726261865, 65846.3415365845, 68478.3152168477, 70137.0, 70702.2929770706, 71011.64494177548, 10543.31468454548, 11034.393108379116, 11140.164487663458, 11149.777004459866, 11153.776924461465, 14940.402383904739, 14943.925280373529, 14956.401743930339, 14968.70062598742, 14976.775348369783, 7579.242075792376, 7674.96162519195, 7683.807904802425, 7686.923130768732, 7720.498167619086, 7241.312075352833, 7261.092363454568, 7279.745208917769, 7282.344588987016, 7300.12398512182, 4686.133065382915, 4719.882002949954, 4720.173969555314, 4774.498677638868, 4788.281757736298, 1860.2837907405747, 1948.6979518174705, 1984.4046785964154, 1990.2138655231104, 1998.7401637787286, 914.5792935249859, 915.6017132547363, 987.244757760314, 1029.6344797596896, 1107.0, 14106.576802695936, 15838.52484425469, 16814.91020534458, 17061.48815535536, 17149.228284727218, 934.6401635370296, 974.449436068619, 995.9950200248952, 1013.3869009249445, 1029.763154474475, 9546.379485333431, 9737.513124343754, 9805.656802012038, 9891.159251463563, 10000.499975001221, 497703.0, 583499.0, 590228.0, 620961.0, 641719.0, 85287.0, 85855.57072214724, 86418.0, 87460.0, 87578.0, 1219.8231256467761, 1223.577865636354, 1243.8631750507436, 1252.0, 1270.326726834779, 15423.922880385975, 15624.0, 15840.841591583936, 16159.43441979525, 16319.020858748512, 34751.82624086965, 34918.47622285617, 35025.0, 35071.0, 35492.29015419729, 45224.547754522035, 46071.0, 46694.76652616715, 46976.29535557038, 47084.52915470939, 9955.850662239927, 10292.639757608447, 10738.946305268424, 10748.785024299314, 10752.086072683596, 12447.253164810141, 14688.115725152762, 18948.621027579095, 19164.520886977378, 19258.90370548138, 15881.761773573637, 15934.840651593799, 15965.361385544913, 15998.440054598499, 16013.599660008593, 15129.924350378178, 15261.6184595386, 15458.0, 15603.687926241639, 15783.605409864385, 14979.576940190664, 15340.616484587974, 15423.305951232445, 15600.453984110502, 15640.843591563938, 10104.343217690826, 10360.792784144425, 10387.844182337421, 10485.528151233366, 10559.313644613076, 10828.891711083104, 11623.476943537393, 12740.235585864508, 14135.081219720685, 14487.420503180176], "unit": "ms", "name": "jquery.html", "value": 9290.052814489703}, {"lowerIsBetter": false, "replicates": [1586.3892401425448, 1619.765134055562, 1698.0745493705924, 1854.332440321484, 1909.5130741660882, 7174.784756457315, 7400.074990626172, 8172.754817355474, 8382.790430239247, 8394.706185283516, 7822.687092516307, 7912.287894089531, 8020.0776910655295, 8089.0, 8095.91904080959, 2297.310806757974, 2330.0912644068826, 2330.7319658239267, 2333.4049817296564, 2335.754745751697, 2005.689118186676, 2029.786872378401, 2297.4141593893532, 2333.0784340185614, 2369.5379401016808, 2312.075169932024, 2317.8261630377788, 2341.906323747048, 2341.9648705269433, 2342.344143639775, 2357.57563638545, 2380.1074597026113, 2386.2125498585424, 2388.5700573896693, 2389.3668177932864, 2289.0614847912443, 2370.9525809483885, 2371.395294199968, 2371.65610986406, 2373.703287089114, 8167.877481837777, 8198.0, 8220.753377398687, 8235.176482351717, 8252.83494330116, 4879.926801097987, 4969.453360130383, 4978.079055374767, 4981.850544483671, 4985.302057711923, 3245.6105267368066, 3253.707166355016, 3294.028261662815, 3305.140663427502, 3321.368939901435, 1030.1449796668746, 1049.1134990932649, 1064.4411683866033, 1068.8723396816356, 1095.8794532601407, 11344.43277836105, 11353.886461135366, 11365.602203922906, 11378.31730096197, 11397.373144477133, 4451.955480445219, 4467.508574056851, 4490.663200259994, 4501.414816073955, 4537.818487260539, 9653.565589548487, 9667.0, 9670.661526846667, 9672.613095476243, 9678.612855485842, 4879.926801097987, 4879.975600121977, 4883.731394773322, 4887.486813884562, 4888.926666100011, 4389.385486031958, 4397.472303323621, 4399.6040356368085, 4400.625946794495, 4401.0, 3460.1695593057484, 3897.0647044709067, 3914.8434062637743, 3958.9208215835524, 3963.4649322341115, 1137.113051819582, 1232.2606436138246, 1255.918365306252, 1260.0612543655036, 1261.1424231522653, 1185.525789684116, 1263.444084602772, 1409.3094383752093, 1470.1326217531707, 1474.4249742600393, 7943.44395892282, 8010.759677209693, 8011.519308841488, 8013.719519816778, 8024.879626805603, 1177.8409914661581, 1192.272713644676, 1196.9521219151136, 1209.5282839692527, 1232.6302109367155, 6152.876942461216, 6201.131841542187, 6256.843578910563, 6271.62370257786, 6338.461230795434, 9676.9032309676, 9900.405975641484, 9911.851322230028, 9917.603295868228, 9930.10629043375, 9628.90371096294, 9696.757581060388, 9770.755731106638, 9780.119789218863, 9800.411975281364, 7921.485103468256, 7986.640601172845, 7990.600469976478, 8075.394345424117, 8077.313428358655, 7869.0, 7942.563159026309, 7981.92018079835, 7990.320822730133, 7995.920040799518, 8320.791980200544, 8568.35737319716, 8903.0, 9059.139381758723, 9068.183863452321, 8506.362022848434, 8711.39020268575, 8713.300906323206, 9253.0, 9278.11857873501, 7440.813979650552, 7566.24337566239, 7567.356774674215, 7568.962155189189, 7569.848603027798, 6511.967440162769, 6650.900236496554, 7988.161243069394, 8001.439899207, 8113.35093192555, 7199.640017999184, 7315.743948961761, 7344.118705755343, 7458.254174582716, 7497.812554686177, 6994.475414343843, 7319.2314806944505, 7392.482526223328, 7471.775846724607, 7495.700171993278, 7306.853862922818, 7343.963280183565, 7357.0, 7365.226651201761, 7370.262973702587, 6722.159730033746, 6936.34104760047, 6946.687399067156, 6953.165620125617, 6959.19969203548, 6868.381845633917, 6899.103116594811, 6899.482538809509, 6902.275261097512, 6910.101686780687], "unit": "ms", "name": "mootools.html", "value": 4742.54815521774}, {"lowerIsBetter": false, "replicates": [366.4100797715677, 370.342641810786, 373.03012168362255, 373.19203923505614, 380.36099353086803, 6114.3579924108, 6154.169187159738, 6246.0, 6261.0, 6271.498280137595, 5989.4010598940085, 6154.323024467305, 6199.132121502993, 6224.159738435321, 6228.8131356059275, 4842.6368022398465, 4845.951540484585, 4846.757662116897, 4846.830360937368, 4848.927266091011, 4611.077784443108, 4707.24684050552, 4710.835120770774, 4743.620510359163, 4860.878478038042, 4577.908441831161, 4607.424071991001, 4691.788869500863, 4794.472608013116, 4794.904101917977, 4771.260454629538, 4773.403324584427, 4781.402324709411, 4783.808647654089, 4784.569388755029, 4241.384999175133, 4254.680898932593, 4256.297710877701, 4267.423897773823, 4272.145570885836, 5877.059670452707, 5877.647341159544, 5883.176355310217, 5888.4700376966275, 5902.61632993854, 5484.0, 5590.440955904418, 5594.160875868612, 5594.776208951637, 5595.132754423071, 6537.0, 6545.9018114728315, 6551.672416379162, 6552.213734351861, 6555.770548030796, 4481.820727170909, 4591.586757191835, 4715.693479923828, 4718.60801063687, 4723.645726570521, 6679.064930909676, 6810.965945170243, 6823.420009299216, 6824.658767061627, 6838.48711346646, 4799.112164249625, 4850.708957462563, 4852.223644216909, 4860.9513904861205, 4861.927071093936, 4902.0441014002645, 4909.803607855717, 4914.213725803854, 4914.631402644816, 4915.5330243626795, 5425.972870135624, 5569.248151499496, 5570.0, 5581.2186293918885, 5581.832545023656, 6546.672666366758, 6580.80257592273, 6584.144061272004, 6599.571027883172, 6609.636469994197, 1727.680379129865, 1743.7384392340996, 1747.012937690201, 1747.484492074841, 1748.0822568151823, 1203.9759204815855, 1207.5290636651712, 1208.4622343057256, 1228.1464382254248, 1239.8202260672151, 6278.937210627927, 6299.937000630027, 6302.401271879165, 6308.0537919311555, 6325.715342809584, 6831.829204269933, 6833.077534532775, 6846.246912839583, 6847.417969472651, 6858.833998220314, 6333.113364129025, 6429.742810287629, 6443.742250309935, 6446.3553644636095, 6452.290248072708, 3694.8522059117336, 3724.404095344732, 3731.7947512887054, 3731.8320675569666, 3732.0669832541867, 7142.4286057115305, 7155.713771449084, 7173.784786456415, 7175.605341706257, 7179.066721326299, 6931.480138989596, 7061.540999835097, 7078.433725301964, 7078.716851325992, 7084.787456376317, 7143.535670181422, 7150.177729561063, 7156.821079473055, 7164.068671072727, 7170.032045673872, 6921.688524016532, 6924.238333783179, 6925.0, 6926.757563485254, 6928.792136235719, 6345.8730825384155, 6531.150950376421, 6660.6336651484635, 6691.9962005699535, 6807.353301436356, 7003.0, 7011.754588589375, 7012.333828286304, 7163.677634506355, 7175.390091842252, 4705.0, 4734.597559207506, 4740.5733483986605, 4741.288806679026, 4743.478217396015, 6709.127813384229, 6839.179298484214, 6847.315268473312, 6933.5493192942295, 6941.687624057031, 6708.19501659804, 6722.798316050526, 6726.764563240263, 6727.428168605723, 6730.0, 6683.365080317362, 6684.364985326386, 6687.565308254948, 6690.69891854858, 6691.46468282546, 6469.45009674183, 6557.704903279268, 6572.047053177358, 6575.96712016456, 6584.736610535525, 6733.7306507741105, 6737.191537015589, 6737.9326206737305, 6740.39336459721, 6745.392914637706, 6529.7061632227615, 6534.444572211225, 6536.444402225864, 6537.411632953057, 6545.050967609765], "unit": "ms", "name": "prototype.html", "value": 5043.256841345469}, {"lowerIsBetter": false, "replicates": [831.6008316008312, 866.203093154298, 908.5094049213425, 932.9860052099218, 945.1115951006058, 13483.730325393486, 13886.0, 14689.485867994623, 14732.263386830642, 14736.557903262907, 13720.51978180764, 13774.380152893118, 13790.862091379084, 13868.861311386883, 13902.652433689163, 730.9561426314411, 736.1681300130845, 877.9648814047447, 882.629295695806, 899.8920129584426, 902.62992173209, 906.3927168796922, 911.1571796088625, 912.8402529557334, 916.6104405627615, 759.3166150464598, 873.4191762477944, 947.5783276441986, 959.6497278493334, 960.279790157382, 760.1828034862544, 764.5756605084176, 937.3672770879629, 950.230313446104, 952.0812416018575, 705.9435245180373, 706.4736771105507, 706.5901776969346, 706.6219572528672, 707.2891743797502, 13586.728265434636, 13615.18308901469, 13672.726545469135, 13687.384067716977, 13708.65728356789, 12716.809247861289, 13063.0, 13191.538296159684, 13237.470501179941, 13392.196468211938, 10540.36624323231, 10607.469626518721, 10609.628662996758, 10684.626038088629, 10709.25035247533, 3206.71139597435, 3278.8360581971046, 3291.308825146722, 3317.701406873393, 3332.266901281714, 45632.0, 46110.0, 46137.76931115323, 47048.0, 48332.27501587479, 10817.675469735921, 10834.783304333869, 10865.348079115278, 11007.889921100686, 11062.22564420483, 19621.743499497454, 19825.405237842886, 19831.801681983285, 19842.60314793725, 19855.106520206624, 8053.263483937774, 8065.395095367753, 8226.341892648574, 8249.092599814016, 8284.958575207085, 4276.48682158143, 4313.676474264443, 4367.432233809584, 4410.955890441119, 4463.4420697412825, 3265.4775235962134, 3266.1344743642953, 3268.542404063433, 3271.0513950954432, 3298.1259966109, 567.1974156568492, 567.7445149682659, 570.4010788671878, 572.5905977226303, 592.7154965616442, 665.3213722003529, 667.882504360621, 680.5712401187319, 691.6334342798325, 703.1246098607267, 8973.551322433852, 9678.419294842332, 9691.70924872255, 9767.755806104762, 10034.598616055422, 483.1231315162968, 633.3666333666333, 642.5052709413725, 642.6080091144398, 691.4572060932144, 3815.2560250751394, 3953.327934251184, 3990.20195960809, 4036.374361973899, 4192.161567686475, 60165.0, 60720.696396517735, 61347.69326153341, 61714.38285617176, 63400.68299658472, 52957.7352113237, 53702.731486342316, 53883.0, 55139.17291240635, 56822.7158864203, 49010.75494622575, 50177.49822501728, 50270.48647567607, 50286.24570631443, 50397.24404133941, 8398.538080405637, 8890.377673562789, 9017.909820901707, 9057.501837398742, 9412.19996300322, 35616.28767424689, 36154.63845361618, 37303.0, 37973.620263797005, 38183.045423863514, 46566.76716616531, 46717.0, 47717.761411192725, 48126.0, 48222.27666585074, 11599.304041757521, 11637.592684256011, 11871.584494542994, 12116.27302361861, 12122.75754484923, 13285.0, 13314.933425333198, 13366.0, 13400.798988015382, 13417.194968301934, 12194.085443591588, 12282.631521054383, 12384.318862462651, 12718.618441446772, 12818.679533011376, 11015.063719583924, 11192.888071119185, 11303.321800691985, 11401.0, 11478.426078695864, 8592.287999840251, 12075.094367922617, 12193.939030304791, 12777.74444511086, 12894.67763305925, 10200.0, 10432.791344172922, 10493.370397775852, 10628.468576571295, 10755.408452535186, 9678.0, 9854.0, 9982.251331150337, 9987.750306242402, 9989.900100999188], "unit": "ms", "name": "yui.html", "value": 6625.96442427755}], "name": "dromaeo_css", "value": 7368.9655298655125}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 112.0, 104.0, 109.0, 113.0, 109.0, 99.0, 108.0, 109.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.4}, {"lowerIsBetter": true, "replicates": [146.0, 124.0, 145.0, 161.0, 122.0, 143.0, 153.0, 123.0, 162.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.4}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 163.0, 159.0, 162.0, 161.0, 161.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [73.0, 79.0, 73.0, 158.0, 71.0, 73.0, 71.0, 72.0, 164.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 90.5}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 132.0, 92.0, 93.0, 92.0, 133.0, 93.0, 93.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 105.0}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 147.0, 147.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [94.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 56.0, 56.0, 56.0, 58.0, 57.0, 56.0, 59.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.2}, {"lowerIsBetter": true, "replicates": [77.0, 77.0, 78.0, 78.0, 78.0, 81.0, 78.0, 78.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [127.0, 118.0, 112.0, 112.0, 114.0, 120.0, 118.0, 111.0, 129.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 118.0}, {"lowerIsBetter": true, "replicates": [139.0, 138.0, 144.0, 139.0, 147.0, 139.0, 139.0, 142.0, 138.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.5}, {"lowerIsBetter": true, "replicates": [66.0, 64.0, 64.0, 64.0, 65.0, 65.0, 63.0, 64.0, 66.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.4}], "name": "kraken", "value": 1475.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.40687100033682], "unit": "score", "name": "Richards", "value": 209.62327599999995}, {"lowerIsBetter": false, "replicates": [164.63615409944023], "unit": "score", "name": "DeltaBlue", "value": 401.60073199999994}, {"lowerIsBetter": false, "replicates": [278.78449958182324], "unit": "score", "name": "Encrypt", "value": 954.7912470000005}, {"lowerIsBetter": false, "replicates": [5213.541666666667], "unit": "score", "name": "Decrypt", "value": 51.05569630369634}, {"lowerIsBetter": false, "replicates": [946.073793755913], "unit": "score", "name": "RayTrace", "value": 782.1683729999996}, {"lowerIsBetter": false, "replicates": [543.4782608695652], "unit": "score", "name": "Earley", "value": 1226.2919200000001}, {"lowerIsBetter": false, "replicates": [10437.5], "unit": "score", "name": "Boyer", "value": 63.85274251497008}, {"lowerIsBetter": false, "replicates": [24190.47619047619], "unit": "score", "name": "RegExp", "value": 37.65882874015749}, {"lowerIsBetter": false, "replicates": [527.7044854881267], "unit": "score", "name": "Splay", "value": 154.42544500000002}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23277.00255016834}]} 09:27:23 INFO - RETURN: dromaeo_css: 7368.97 09:27:23 INFO - RETURN: kraken: 1475.80 09:27:23 INFO - RETURN: v8_7: 23277.00 09:27:23 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23277.00"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1475.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7368.97"}}} 09:27:23 INFO - Return code: 0 09:27:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:27:23 INFO - # TBPL SUCCESS # 09:27:23 INFO - Running post-action listener: _resource_record_post_action 09:27:23 INFO - Running post-run listener: _resource_record_post_run 09:27:24 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 323584; Write bytes: 507641856; Read time: 548; Write time: 531452 09:27:24 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 4096; Write bytes: 242139136; Read time: 256; Write time: 318124 09:27:24 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 319488; Write bytes: 265502720; Read time: 292; Write time: 213328 09:27:24 INFO - Running post-run listener: _upload_blobber_files 09:27:24 INFO - Blob upload gear active. 09:27:24 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:27:24 INFO - Copying logs to upload dir... 09:27:24 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.366033 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-02-17 09:27:24.404831) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-17 09:27:24.408247) ========= 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009893 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455720291/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-17 09:27:24.458489) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:27:24.458828) ========= 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=52faae308a556c440401f8e5000001e8-1455728785.804572-733833205 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 09:27:24.508474) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 09:27:24.508771) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 09:27:24.509054) ========= ========= Total master_lag: 0.24 =========