builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1454523306.32 results: success (0) buildid: 20160203074241 builduid: 5595932e320a4ff2a025ef6b30e8486f revision: 1524bf5cebad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.322688) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.323111) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.323446) ========= 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006283 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.354983) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.355250) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.382551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.382847) ========= 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.026910 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.431739) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.432077) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.432473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.432853) ========= 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-03 10:15:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 83.2M=0s 2016-02-03 10:15:06 (83.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151698 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.600302) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.600622) ========= 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.115907 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.728239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:06.728631) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1524bf5cebad --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 1524bf5cebad --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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-03 10:15:06,829 Setting DEBUG logging. 2016-02-03 10:15:06,829 attempt 1/10 2016-02-03 10:15:06,829 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1524bf5cebad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-03 10:15:07,104 unpacking tar archive at: mozilla-beta-1524bf5cebad/testing/mozharness/ program finished with exit code 0 elapsedTime=0.575409 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:07.317007) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:07.317348) ========= echo 1524bf5cebad in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1524bf5cebad'] 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 1524bf5cebad program finished with exit code 0 elapsedTime=0.004426 script_repo_revision: '1524bf5cebad' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:07.367810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:07.368132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-03 10:15:07.378447) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-03 10:15:07.378797) ========= /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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:15:07 INFO - MultiFileLogger online at 20160203 10:15:07 in /builds/slave/test-pgo 10:15:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:15:07 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:15:07 INFO - {'append_to_log': False, 10:15:07 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:15:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:15:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:15:07 INFO - 'branch': 'Mozilla-Beta', 10:15:07 INFO - 'buildbot_json_path': 'buildprops.json', 10:15:07 INFO - 'config_files': ('talos/linux_config.py',), 10:15:07 INFO - 'default_actions': ('clobber', 10:15:07 INFO - 'read-buildbot-config', 10:15:07 INFO - 'download-and-extract', 10:15:07 INFO - 'populate-webroot', 10:15:07 INFO - 'create-virtualenv', 10:15:07 INFO - 'install', 10:15:07 INFO - 'run-tests'), 10:15:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:15:07 INFO - 'download_minidump_stackwalk': True, 10:15:07 INFO - 'download_symbols': 'ondemand', 10:15:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:15:07 INFO - 'tooltool.py': '/tools/tooltool.py', 10:15:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:15:07 INFO - '/tools/misc-python/virtualenv.py')}, 10:15:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:15:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:15:07 INFO - 'installer_path': 'installer.exe', 10:15:07 INFO - 'log_level': 'info', 10:15:07 INFO - 'log_name': 'talos', 10:15:07 INFO - 'log_to_console': True, 10:15:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:15:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:15:07 INFO - 'opt_config_files': (), 10:15:07 INFO - 'pip_index': False, 10:15:07 INFO - 'sps_profile': False, 10:15:07 INFO - 'sps_profile_interval': 0, 10:15:07 INFO - 'suite': 'dromaeojs', 10:15:07 INFO - 'system_bits': '32', 10:15:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:15:07 INFO - 'title': 'talos-linux64-ix-055', 10:15:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:15:07 INFO - 'use_talos_json': True, 10:15:07 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:15:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:15:07 INFO - 'work_dir': 'build'} 10:15:07 INFO - ##### 10:15:07 INFO - ##### Running clobber step. 10:15:07 INFO - ##### 10:15:07 INFO - Running pre-action listener: _resource_record_pre_action 10:15:07 INFO - Running main action method: clobber 10:15:07 INFO - rmtree: /builds/slave/test-pgo/build 10:15:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:15:08 INFO - Running post-action listener: _resource_record_post_action 10:15:08 INFO - ##### 10:15:08 INFO - ##### Running read-buildbot-config step. 10:15:08 INFO - ##### 10:15:08 INFO - Running pre-action listener: _resource_record_pre_action 10:15:08 INFO - Running main action method: read_buildbot_config 10:15:08 INFO - Using buildbot properties: 10:15:08 INFO - { 10:15:08 INFO - "properties": { 10:15:08 INFO - "buildnumber": 99, 10:15:08 INFO - "product": "firefox", 10:15:08 INFO - "script_repo_revision": "production", 10:15:08 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:15:08 INFO - "repository": "", 10:15:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:15:08 INFO - "buildid": "20160203074241", 10:15:08 INFO - "slavename": "talos-linux64-ix-055", 10:15:08 INFO - "pgo_build": "True", 10:15:08 INFO - "basedir": "/builds/slave/test-pgo", 10:15:08 INFO - "project": "", 10:15:08 INFO - "platform": "ubuntu64_hw", 10:15:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:15:08 INFO - "slavebuilddir": "test", 10:15:08 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:15:08 INFO - "branch": "mozilla-beta", 10:15:08 INFO - "repo_path": "releases/mozilla-beta", 10:15:08 INFO - "stage_platform": "linux64-pgo", 10:15:08 INFO - "builduid": "5595932e320a4ff2a025ef6b30e8486f", 10:15:08 INFO - "revision": "1524bf5cebad" 10:15:08 INFO - }, 10:15:08 INFO - "sourcestamp": { 10:15:08 INFO - "repository": "", 10:15:08 INFO - "hasPatch": false, 10:15:08 INFO - "project": "", 10:15:08 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:15:08 INFO - "changes": [ 10:15:08 INFO - { 10:15:08 INFO - "category": null, 10:15:08 INFO - "files": [ 10:15:08 INFO - { 10:15:08 INFO - "url": null, 10:15:08 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:15:08 INFO - } 10:15:08 INFO - ], 10:15:08 INFO - "repository": "", 10:15:08 INFO - "rev": "1524bf5cebad", 10:15:08 INFO - "who": "sendchange", 10:15:08 INFO - "when": 1454523251, 10:15:08 INFO - "number": 7557583, 10:15:08 INFO - "comments": "Bug 1059469 - Part 2 - When rescheduling the interval timer, cancel it first, and refactor things so that actually does something. r=baku, a=sylvestre", 10:15:08 INFO - "project": "", 10:15:08 INFO - "at": "Wed 03 Feb 2016 10:14:11", 10:15:08 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:15:08 INFO - "revlink": "", 10:15:08 INFO - "properties": [ 10:15:08 INFO - [ 10:15:08 INFO - "buildid", 10:15:08 INFO - "20160203074241", 10:15:08 INFO - "Change" 10:15:08 INFO - ], 10:15:08 INFO - [ 10:15:08 INFO - "builduid", 10:15:08 INFO - "5595932e320a4ff2a025ef6b30e8486f", 10:15:08 INFO - "Change" 10:15:08 INFO - ], 10:15:08 INFO - [ 10:15:08 INFO - "pgo_build", 10:15:08 INFO - "True", 10:15:08 INFO - "Change" 10:15:08 INFO - ] 10:15:08 INFO - ], 10:15:08 INFO - "revision": "1524bf5cebad" 10:15:08 INFO - } 10:15:08 INFO - ], 10:15:08 INFO - "revision": "1524bf5cebad" 10:15:08 INFO - } 10:15:08 INFO - } 10:15:08 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:15:08 INFO - Running post-action listener: _resource_record_post_action 10:15:08 INFO - ##### 10:15:08 INFO - ##### Running download-and-extract step. 10:15:08 INFO - ##### 10:15:08 INFO - Running pre-action listener: _resource_record_pre_action 10:15:08 INFO - Running main action method: download_and_extract 10:15:08 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/test_packages.json 10:15:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:15:08 INFO - Downloaded 1270 bytes. 10:15:08 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:15:08 INFO - Using the following test package requirements: 10:15:08 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:15:08 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:15:08 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'jsshell-linux-x86_64.zip'], 10:15:08 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:15:08 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:15:08 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:15:08 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:15:08 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:15:08 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:15:08 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:15:08 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:15:08 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:08 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:15:08 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:15:09 INFO - Downloaded 21581668 bytes. 10:15:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:15:09 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:15:09 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:15:10 INFO - Return code: 0 10:15:10 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:10 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:15:10 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:15:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:15:11 INFO - Downloaded 21581668 bytes. 10:15:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:15:11 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:15:11 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:15:12 INFO - Return code: 0 10:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:12 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:15:12 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:15:14 INFO - Downloaded 11132038 bytes. 10:15:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 10:15:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:15:14 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 10:15:14 INFO - Return code: 0 10:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:15:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:15:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:15:15 INFO - Downloaded 57940707 bytes. 10:15:15 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:15:15 INFO - mkdir: /builds/slave/test-pgo/properties 10:15:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:15:15 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:15:15 INFO - Contents: 10:15:15 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:15:15 INFO - Running post-action listener: _resource_record_post_action 10:15:15 INFO - Running post-action listener: set_extra_try_arguments 10:15:15 INFO - ##### 10:15:15 INFO - ##### Running populate-webroot step. 10:15:15 INFO - ##### 10:15:15 INFO - Running pre-action listener: _resource_record_pre_action 10:15:15 INFO - Running main action method: populate_webroot 10:15:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:15:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:15:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:15:15 INFO - '--tppagecycles', 10:15:15 INFO - '7'], 10:15:15 INFO - 'tests': ['tsvgm']}}, 10:15:15 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:15:15 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:15:15 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:15:15 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:15:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'tests': ['damp', 'tps']}, 10:15:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['damp', 'tps']}, 10:15:15 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:15:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:15:15 INFO - 'other': {'tests': ['a11yr', 10:15:15 INFO - 'ts_paint', 10:15:15 INFO - 'tpaint', 10:15:15 INFO - 'sessionrestore', 10:15:15 INFO - 'sessionrestore_no_auto_restore']}, 10:15:15 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['a11yr', 10:15:15 INFO - 'ts_paint', 10:15:15 INFO - 'tpaint', 10:15:15 INFO - 'sessionrestore', 10:15:15 INFO - 'sessionrestore_no_auto_restore']}, 10:15:15 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['a11yr', 10:15:15 INFO - 'ts_paint', 10:15:15 INFO - 'tpaint', 10:15:15 INFO - 'sessionrestore', 10:15:15 INFO - 'sessionrestore_no_auto_restore']}, 10:15:15 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['a11yr', 10:15:15 INFO - 'ts_paint', 10:15:15 INFO - 'tpaint', 10:15:15 INFO - 'sessionrestore', 10:15:15 INFO - 'sessionrestore_no_auto_restore']}, 10:15:15 INFO - 'other_l64': {'tests': ['a11yr', 10:15:15 INFO - 'ts_paint', 10:15:15 INFO - 'tpaint', 10:15:15 INFO - 'sessionrestore', 10:15:15 INFO - 'sessionrestore_no_auto_restore']}, 10:15:15 INFO - 'other_nol64': {'tests': ['a11yr', 10:15:15 INFO - 'ts_paint', 10:15:15 INFO - 'tpaint', 10:15:15 INFO - 'sessionrestore', 10:15:15 INFO - 'sessionrestore_no_auto_restore']}, 10:15:15 INFO - 'svgr': {'tests': ['tsvgx', 10:15:15 INFO - 'tsvgr_opacity', 10:15:15 INFO - 'tart', 10:15:15 INFO - 'tscrollx', 10:15:15 INFO - 'cart']}, 10:15:15 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['tsvgx', 10:15:15 INFO - 'tsvgr_opacity', 10:15:15 INFO - 'tart', 10:15:15 INFO - 'tscrollx', 10:15:15 INFO - 'cart']}, 10:15:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'tests': ['tp5o']}, 10:15:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'talos_options': ['--e10s'], 10:15:15 INFO - 'tests': ['tp5o']}, 10:15:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'talos_options': ['--xperf_path', 10:15:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:15 INFO - 'tests': ['tp5n']}, 10:15:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:15:15 INFO - 'talos_options': ['--e10s', 10:15:15 INFO - '--xperf_path', 10:15:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:15 INFO - 'tests': ['tp5n']}}, 10:15:15 INFO - 'talos.zip': {'path': '', 10:15:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:15:15 INFO - Running post-action listener: _resource_record_post_action 10:15:15 INFO - ##### 10:15:15 INFO - ##### Running create-virtualenv step. 10:15:15 INFO - ##### 10:15:15 INFO - Running pre-action listener: _resource_record_pre_action 10:15:15 INFO - Running main action method: create_virtualenv 10:15:15 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:15:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 10:15:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:15:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:15:16 INFO - Using real prefix '/usr' 10:15:16 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:15:16 INFO - Installing distribute.............................................................................................................................................................................................done. 10:15:18 INFO - Installing pip.................done. 10:15:18 INFO - Return code: 0 10:15:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:18 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 10:15:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:15:18 INFO - Using env: {'DISPLAY': ':0', 10:15:18 INFO - 'HOME': '/home/cltbld', 10:15:18 INFO - 'LANG': 'en_US.UTF-8', 10:15:18 INFO - 'LANGUAGE': 'en_US:en', 10:15:18 INFO - 'LOGNAME': 'cltbld', 10:15:18 INFO - 'MAIL': '/var/mail/cltbld', 10:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:18 INFO - 'MOZ_NO_REMOTE': '1', 10:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:18 INFO - 'NO_EM_RESTART': '1', 10:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:18 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:18 INFO - 'SHELL': '/bin/bash', 10:15:18 INFO - 'SHLVL': '1', 10:15:18 INFO - 'TERM': 'linux', 10:15:18 INFO - 'TMOUT': '86400', 10:15:18 INFO - 'USER': 'cltbld', 10:15:18 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:18 INFO - '_': '/tools/buildbot/bin/python'} 10:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:15:19 INFO - Cleaning up... 10:15:19 INFO - Return code: 0 10:15:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:19 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 10:15:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:15:19 INFO - Using env: {'DISPLAY': ':0', 10:15:19 INFO - 'HOME': '/home/cltbld', 10:15:19 INFO - 'LANG': 'en_US.UTF-8', 10:15:19 INFO - 'LANGUAGE': 'en_US:en', 10:15:19 INFO - 'LOGNAME': 'cltbld', 10:15:19 INFO - 'MAIL': '/var/mail/cltbld', 10:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:19 INFO - 'MOZ_NO_REMOTE': '1', 10:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:19 INFO - 'NO_EM_RESTART': '1', 10:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:19 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:19 INFO - 'SHELL': '/bin/bash', 10:15:19 INFO - 'SHLVL': '1', 10:15:19 INFO - 'TERM': 'linux', 10:15:19 INFO - 'TMOUT': '86400', 10:15:19 INFO - 'USER': 'cltbld', 10:15:19 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:19 INFO - '_': '/tools/buildbot/bin/python'} 10:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:19 INFO - Downloading/unpacking psutil>=0.7.1 10:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:21 INFO - Installing collected packages: psutil 10:15:21 INFO - Running setup.py install for psutil 10:15:21 INFO - building 'psutil._psutil_linux' extension 10:15:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:15:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:15:21 INFO - building 'psutil._psutil_posix' extension 10:15:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:15:21 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:21 INFO - Successfully installed psutil 10:15:21 INFO - Cleaning up... 10:15:21 INFO - Return code: 0 10:15:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:21 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 10:15:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 10:15:21 INFO - Using env: {'DISPLAY': ':0', 10:15:21 INFO - 'HOME': '/home/cltbld', 10:15:21 INFO - 'LANG': 'en_US.UTF-8', 10:15:21 INFO - 'LANGUAGE': 'en_US:en', 10:15:21 INFO - 'LOGNAME': 'cltbld', 10:15:21 INFO - 'MAIL': '/var/mail/cltbld', 10:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:21 INFO - 'MOZ_NO_REMOTE': '1', 10:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:21 INFO - 'NO_EM_RESTART': '1', 10:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:21 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:21 INFO - 'SHELL': '/bin/bash', 10:15:21 INFO - 'SHLVL': '1', 10:15:21 INFO - 'TERM': 'linux', 10:15:21 INFO - 'TMOUT': '86400', 10:15:21 INFO - 'USER': 'cltbld', 10:15:21 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:21 INFO - '_': '/tools/buildbot/bin/python'} 10:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:15:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:15:24 INFO - Installing collected packages: mozsystemmonitor 10:15:24 INFO - Running setup.py install for mozsystemmonitor 10:15:24 INFO - Successfully installed mozsystemmonitor 10:15:24 INFO - Cleaning up... 10:15:25 INFO - Return code: 0 10:15:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:25 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 10:15:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:15:25 INFO - Using env: {'DISPLAY': ':0', 10:15:25 INFO - 'HOME': '/home/cltbld', 10:15:25 INFO - 'LANG': 'en_US.UTF-8', 10:15:25 INFO - 'LANGUAGE': 'en_US:en', 10:15:25 INFO - 'LOGNAME': 'cltbld', 10:15:25 INFO - 'MAIL': '/var/mail/cltbld', 10:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:25 INFO - 'MOZ_NO_REMOTE': '1', 10:15:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:25 INFO - 'NO_EM_RESTART': '1', 10:15:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:25 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:25 INFO - 'SHELL': '/bin/bash', 10:15:25 INFO - 'SHLVL': '1', 10:15:25 INFO - 'TERM': 'linux', 10:15:25 INFO - 'TMOUT': '86400', 10:15:25 INFO - 'USER': 'cltbld', 10:15:25 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:25 INFO - '_': '/tools/buildbot/bin/python'} 10:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:25 INFO - Downloading/unpacking blobuploader==1.2.4 10:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:27 INFO - Downloading blobuploader-1.2.4.tar.gz 10:15:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:15:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:15:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:27 INFO - Downloading docopt-0.6.1.tar.gz 10:15:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:15:28 INFO - Installing collected packages: blobuploader, requests, docopt 10:15:28 INFO - Running setup.py install for blobuploader 10:15:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:15:28 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:15:28 INFO - Running setup.py install for requests 10:15:28 INFO - Running setup.py install for docopt 10:15:28 INFO - Successfully installed blobuploader requests docopt 10:15:28 INFO - Cleaning up... 10:15:28 INFO - Return code: 0 10:15:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:28 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:15:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:28 INFO - Using env: {'DISPLAY': ':0', 10:15:28 INFO - 'HOME': '/home/cltbld', 10:15:28 INFO - 'LANG': 'en_US.UTF-8', 10:15:28 INFO - 'LANGUAGE': 'en_US:en', 10:15:28 INFO - 'LOGNAME': 'cltbld', 10:15:28 INFO - 'MAIL': '/var/mail/cltbld', 10:15:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:28 INFO - 'MOZ_NO_REMOTE': '1', 10:15:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:28 INFO - 'NO_EM_RESTART': '1', 10:15:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:28 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:28 INFO - 'SHELL': '/bin/bash', 10:15:28 INFO - 'SHLVL': '1', 10:15:28 INFO - 'TERM': 'linux', 10:15:28 INFO - 'TMOUT': '86400', 10:15:28 INFO - 'USER': 'cltbld', 10:15:28 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:28 INFO - '_': '/tools/buildbot/bin/python'} 10:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:15:28 INFO - Running setup.py (path:/tmp/pip-PcoJt9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:15:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:15:28 INFO - Running setup.py (path:/tmp/pip-KFvG41-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:15:29 INFO - Running setup.py (path:/tmp/pip-H1efBd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:15:29 INFO - Running setup.py (path:/tmp/pip-BlOkeo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:15:29 INFO - Running setup.py (path:/tmp/pip-O9a9KV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:15:29 INFO - Running setup.py (path:/tmp/pip-btQeTA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:15:29 INFO - Running setup.py (path:/tmp/pip-8nEFYv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:15:29 INFO - Running setup.py (path:/tmp/pip-7b2VBj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:15:29 INFO - Running setup.py (path:/tmp/pip-OZz3OI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:15:29 INFO - Running setup.py (path:/tmp/pip-kmG2Rk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:15:29 INFO - Running setup.py (path:/tmp/pip-zbm15Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:15:29 INFO - Running setup.py (path:/tmp/pip-X7pIxX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:15:29 INFO - Running setup.py (path:/tmp/pip-Azt9N1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:15:29 INFO - Running setup.py (path:/tmp/pip-WBq7rw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:15:29 INFO - Running setup.py (path:/tmp/pip-BWu8EZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:15:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:15:29 INFO - Running setup.py (path:/tmp/pip-G2YjsJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:15:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:15:30 INFO - Running setup.py (path:/tmp/pip-f17oKO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:15:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:15:30 INFO - Running setup.py install for manifestparser 10:15:30 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Running setup.py install for mozcrash 10:15:30 INFO - Running setup.py install for mozdebug 10:15:30 INFO - Running setup.py install for mozdevice 10:15:30 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Running setup.py install for mozfile 10:15:30 INFO - Running setup.py install for mozhttpd 10:15:30 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Running setup.py install for mozinfo 10:15:30 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Running setup.py install for mozInstall 10:15:30 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:15:30 INFO - Running setup.py install for mozleak 10:15:30 INFO - Running setup.py install for mozlog 10:15:31 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Running setup.py install for moznetwork 10:15:31 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Running setup.py install for mozprocess 10:15:31 INFO - Running setup.py install for mozprofile 10:15:31 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Running setup.py install for mozrunner 10:15:31 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Running setup.py install for mozscreenshot 10:15:31 INFO - Running setup.py install for moztest 10:15:31 INFO - Running setup.py install for mozversion 10:15:31 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:15:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:15:31 INFO - Cleaning up... 10:15:31 INFO - Return code: 0 10:15:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:31 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:15:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:31 INFO - Using env: {'DISPLAY': ':0', 10:15:31 INFO - 'HOME': '/home/cltbld', 10:15:31 INFO - 'LANG': 'en_US.UTF-8', 10:15:31 INFO - 'LANGUAGE': 'en_US:en', 10:15:31 INFO - 'LOGNAME': 'cltbld', 10:15:31 INFO - 'MAIL': '/var/mail/cltbld', 10:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:31 INFO - 'MOZ_NO_REMOTE': '1', 10:15:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:31 INFO - 'NO_EM_RESTART': '1', 10:15:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:31 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:31 INFO - 'SHELL': '/bin/bash', 10:15:31 INFO - 'SHLVL': '1', 10:15:31 INFO - 'TERM': 'linux', 10:15:31 INFO - 'TMOUT': '86400', 10:15:31 INFO - 'USER': 'cltbld', 10:15:31 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:31 INFO - '_': '/tools/buildbot/bin/python'} 10:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:15:32 INFO - Running setup.py (path:/tmp/pip-k6HFtV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:15:32 INFO - Running setup.py (path:/tmp/pip-9W3tM4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:15:32 INFO - Running setup.py (path:/tmp/pip-VOBUVc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:15:32 INFO - Running setup.py (path:/tmp/pip-dg5AGB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:15:32 INFO - Running setup.py (path:/tmp/pip-tPaAyM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:15:32 INFO - Running setup.py (path:/tmp/pip-sjhVH1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:15:32 INFO - Running setup.py (path:/tmp/pip-A49fBE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:15:32 INFO - Running setup.py (path:/tmp/pip-UAkGly-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:15:32 INFO - Running setup.py (path:/tmp/pip-g3Gc82-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:15:32 INFO - Running setup.py (path:/tmp/pip-EkbfSv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:15:32 INFO - Running setup.py (path:/tmp/pip-jrFVBf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:15:32 INFO - Running setup.py (path:/tmp/pip-sCtv9C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 10:15:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:15:32 INFO - Running setup.py (path:/tmp/pip-yAi_XS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 10:15:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:15:33 INFO - Running setup.py (path:/tmp/pip-y7n0Q5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 10:15:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:15:33 INFO - Running setup.py (path:/tmp/pip-ReN2bB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 10:15:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:15:33 INFO - Running setup.py (path:/tmp/pip-DZGcId-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 10:15:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:15:33 INFO - Running setup.py (path:/tmp/pip-BCG1Ei-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:35 INFO - Downloading blessings-1.6.tar.gz 10:15:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:15:35 INFO - Installing collected packages: blessings 10:15:35 INFO - Running setup.py install for blessings 10:15:35 INFO - Successfully installed blessings 10:15:35 INFO - Cleaning up... 10:15:35 INFO - Return code: 0 10:15:35 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:15:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:15:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:15:35 INFO - Reading from file tmpfile_stdout 10:15:35 INFO - Current package versions: 10:15:35 INFO - argparse == 1.2.1 10:15:35 INFO - blessings == 1.6 10:15:35 INFO - blobuploader == 1.2.4 10:15:35 INFO - docopt == 0.6.1 10:15:35 INFO - manifestparser == 1.1 10:15:35 INFO - mozInstall == 1.12 10:15:35 INFO - mozcrash == 0.16 10:15:35 INFO - mozdebug == 0.1 10:15:35 INFO - mozdevice == 0.47 10:15:35 INFO - mozfile == 1.2 10:15:35 INFO - mozhttpd == 0.7 10:15:35 INFO - mozinfo == 0.9 10:15:35 INFO - mozleak == 0.1 10:15:35 INFO - mozlog == 3.1 10:15:35 INFO - moznetwork == 0.27 10:15:35 INFO - mozprocess == 0.22 10:15:35 INFO - mozprofile == 0.27 10:15:35 INFO - mozrunner == 6.11 10:15:35 INFO - mozscreenshot == 0.1 10:15:35 INFO - mozsystemmonitor == 0.0 10:15:35 INFO - moztest == 0.7 10:15:35 INFO - mozversion == 1.4 10:15:35 INFO - psutil == 3.1.1 10:15:35 INFO - requests == 1.2.3 10:15:35 INFO - wsgiref == 0.1.2 10:15:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:15:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:35 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 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 10:15:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:35 INFO - Using env: {'DISPLAY': ':0', 10:15:35 INFO - 'HOME': '/home/cltbld', 10:15:35 INFO - 'LANG': 'en_US.UTF-8', 10:15:35 INFO - 'LANGUAGE': 'en_US:en', 10:15:35 INFO - 'LOGNAME': 'cltbld', 10:15:35 INFO - 'MAIL': '/var/mail/cltbld', 10:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:35 INFO - 'MOZ_NO_REMOTE': '1', 10:15:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:35 INFO - 'NO_EM_RESTART': '1', 10:15:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:35 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:35 INFO - 'SHELL': '/bin/bash', 10:15:35 INFO - 'SHLVL': '1', 10:15:35 INFO - 'TERM': 'linux', 10:15:35 INFO - 'TMOUT': '86400', 10:15:35 INFO - 'USER': 'cltbld', 10:15:35 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:35 INFO - '_': '/tools/buildbot/bin/python'} 10:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:15:35 INFO - Cleaning up... 10:15:35 INFO - Return code: 0 10:15:35 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:15:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 10:15:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:15:35 INFO - Using env: {'DISPLAY': ':0', 10:15:35 INFO - 'HOME': '/home/cltbld', 10:15:35 INFO - 'LANG': 'en_US.UTF-8', 10:15:35 INFO - 'LANGUAGE': 'en_US:en', 10:15:35 INFO - 'LOGNAME': 'cltbld', 10:15:35 INFO - 'MAIL': '/var/mail/cltbld', 10:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:35 INFO - 'MOZ_NO_REMOTE': '1', 10:15:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:35 INFO - 'NO_EM_RESTART': '1', 10:15:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:35 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:35 INFO - 'SHELL': '/bin/bash', 10:15:35 INFO - 'SHLVL': '1', 10:15:35 INFO - 'TERM': 'linux', 10:15:35 INFO - 'TMOUT': '86400', 10:15:35 INFO - 'USER': 'cltbld', 10:15:35 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:35 INFO - '_': '/tools/buildbot/bin/python'} 10:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:35 INFO - Downloading/unpacking jsonschema 10:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:15:37 INFO - Downloading/unpacking functools32 (from jsonschema) 10:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:37 INFO - Downloading functools32-3.2.3-2.tar.gz 10:15:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:15:38 INFO - warning: no files found matching '*.txt' 10:15:38 INFO - no previously-included directories found matching 'build' 10:15:38 INFO - no previously-included directories found matching 'dist' 10:15:38 INFO - no previously-included directories found matching '.git*' 10:15:38 INFO - Installing collected packages: jsonschema, functools32 10:15:38 INFO - Running setup.py install for functools32 10:15:38 INFO - warning: no files found matching '*.txt' 10:15:38 INFO - no previously-included directories found matching 'build' 10:15:38 INFO - no previously-included directories found matching 'dist' 10:15:38 INFO - no previously-included directories found matching '.git*' 10:15:38 INFO - Successfully installed jsonschema functools32 10:15:38 INFO - Cleaning up... 10:15:38 INFO - Return code: 0 10:15:38 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:15: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe19a60cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x203b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, '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': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:15: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', 'flake8'] in /builds/slave/test-pgo/build 10:15: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 flake8 10:15:38 INFO - Using env: {'DISPLAY': ':0', 10:15:38 INFO - 'HOME': '/home/cltbld', 10:15:38 INFO - 'LANG': 'en_US.UTF-8', 10:15:38 INFO - 'LANGUAGE': 'en_US:en', 10:15:38 INFO - 'LOGNAME': 'cltbld', 10:15:38 INFO - 'MAIL': '/var/mail/cltbld', 10:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:38 INFO - 'MOZ_NO_REMOTE': '1', 10:15:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:38 INFO - 'NO_EM_RESTART': '1', 10:15:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:38 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:38 INFO - 'SHELL': '/bin/bash', 10:15:38 INFO - 'SHLVL': '1', 10:15:38 INFO - 'TERM': 'linux', 10:15:38 INFO - 'TMOUT': '86400', 10:15:38 INFO - 'USER': 'cltbld', 10:15:38 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:38 INFO - '_': '/tools/buildbot/bin/python'} 10:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:38 INFO - Downloading/unpacking flake8 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15: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 10:15:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:15:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:15:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:15:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:15:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:15:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:15:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:15:40 INFO - no previously-included directories found matching 'docs/_build' 10:15:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:15:40 INFO - Running setup.py install for pep8 10:15:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:15:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:15:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:15:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:15:41 INFO - no previously-included directories found matching 'docs/_build' 10:15:41 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:15:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:15:41 INFO - Cleaning up... 10:15:41 INFO - Return code: 0 10:15:41 INFO - Running post-action listener: _resource_record_post_action 10:15:41 INFO - Running post-action listener: _start_resource_monitoring 10:15:41 INFO - Starting resource monitoring. 10:15:41 INFO - ##### 10:15:41 INFO - ##### Running install step. 10:15:41 INFO - ##### 10:15:41 INFO - Running pre-action listener: _resource_record_pre_action 10:15:41 INFO - Running main action method: install 10:15:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:15:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:15:41 INFO - Reading from file tmpfile_stdout 10:15:41 INFO - Detecting whether we're running mozinstall >=1.0... 10:15:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:15:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:15:41 INFO - Reading from file tmpfile_stdout 10:15:41 INFO - Output received: 10:15:41 INFO - Usage: mozinstall [options] installer 10:15:41 INFO - Options: 10:15:41 INFO - -h, --help show this help message and exit 10:15:41 INFO - -d DEST, --destination=DEST 10:15:41 INFO - Directory to install application into. [default: 10:15:41 INFO - "/builds/slave/test-pgo"] 10:15:41 INFO - --app=APP Application being installed. [default: firefox] 10:15:41 INFO - mkdir: /builds/slave/test-pgo/build/application 10:15:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:15:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:15:55 INFO - Reading from file tmpfile_stdout 10:15:55 INFO - Output received: 10:15:55 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:15:55 INFO - Running post-action listener: _resource_record_post_action 10:15:55 INFO - ##### 10:15:55 INFO - ##### Running run-tests step. 10:15:55 INFO - ##### 10:15:55 INFO - Running pre-action listener: _resource_record_pre_action 10:15:55 INFO - Running main action method: run_tests 10:15:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:15:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:15:55 INFO - Python 2.7.3 10:15:55 INFO - Return code: 0 10:15:55 INFO - grabbing minidump binary from tooltool 10:15:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:15:55 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 0x20ca350>, '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 0x20cb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:15:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 10:15:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 10:15:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:15:56 INFO - Return code: 0 10:15:56 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:15:56 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:15:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:15:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:15:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:15:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:15:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:15:57 INFO - Return code: 0 10:15:57 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/1454514161/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:15:57 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/1454514161/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --webServer localhost 10:15:57 INFO - Using env: {'DISPLAY': ':0', 10:15:57 INFO - 'HOME': '/home/cltbld', 10:15:57 INFO - 'LANG': 'en_US.UTF-8', 10:15:57 INFO - 'LANGUAGE': 'en_US:en', 10:15:57 INFO - 'LOGNAME': 'cltbld', 10:15:57 INFO - 'MAIL': '/var/mail/cltbld', 10:15:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:15:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:15:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:57 INFO - 'MOZ_NO_REMOTE': '1', 10:15:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:15:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:15:57 INFO - 'NO_EM_RESTART': '1', 10:15:57 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:15:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:15:57 INFO - 'PWD': '/builds/slave/test-pgo', 10:15:57 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:15:57 INFO - 'SHELL': '/bin/bash', 10:15:57 INFO - 'SHLVL': '1', 10:15:57 INFO - 'TERM': 'linux', 10:15:57 INFO - 'TMOUT': '86400', 10:15:57 INFO - 'USER': 'cltbld', 10:15:57 INFO - 'XDG_SESSION_COOKIE': '82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296', 10:15:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:57 INFO - '_': '/tools/buildbot/bin/python'} 10:15:57 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/1454514161/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--webServer', 'localhost'] with output_timeout 3600 10:15:57 INFO - mozversion INFO | application_buildid: 20160203074241 10:15:57 INFO - mozversion INFO | application_changeset: 1524bf5cebadaf175d2dcb2028448ba204d846da 10:15:57 INFO - mozversion INFO | application_display_name: Nightly 10:15:57 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:15:57 INFO - mozversion INFO | application_name: Firefox 10:15:57 INFO - mozversion INFO | application_remotingname: firefox 10:15:57 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:57 INFO - mozversion INFO | application_vendor: Mozilla 10:15:57 INFO - mozversion INFO | application_version: 45.0 10:15:57 INFO - mozversion INFO | platform_buildid: 20160203074241 10:15:57 INFO - mozversion INFO | platform_changeset: 1524bf5cebadaf175d2dcb2028448ba204d846da 10:15:57 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:57 INFO - mozversion INFO | platform_version: 45.0 10:15:57 INFO - 2016-02-03 10:15:57,285 DEBUG : using testdate: 1454523357 10:15:57 INFO - 2016-02-03 10:15:57,285 DEBUG : actual date: 1454523357 10:15:57 INFO - 2016-02-03 10:15:57,294 INFO : starting webserver on 'localhost:57613' 10:15:57 INFO - 2016-02-03 10:15:57,295 INFO : Starting test suite talos-linux64-ix-055 10:15:57 INFO - 2016-02-03 10:15:57,295 INFO : Starting test dromaeo_css 10:15:57 INFO - 2016-02-03 10:15:57,295 DEBUG : operating with platform_type : linux_ 10:15:57 INFO - 2016-02-03 10:15:57,296 INFO : Initialising browser for dromaeo_css test... 10:15:57 INFO - 2016-02-03 10:15:57,307 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppZW1oJ/profile http://localhost:57613/getInfo.html 10:16:01 INFO - 2016-02-03 10:16:01,455 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:16:01 INFO - 2016-02-03 10:16:01,455 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:16:01 INFO - 2016-02-03 10:16:01,455 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:16:01 INFO - 2016-02-03 10:16:01,455 DEBUG : BROWSER_OUTPUT: __metrics 10:16:01 INFO - 2016-02-03 10:16:01,604 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:16:02 INFO - 2016-02-03 10:16:02,228 INFO : Browser initialized. 10:16:02 INFO - 2016-02-03 10:16:02,228 INFO : Running cycle 1/1 for dromaeo_css test... 10:16:02 INFO - 2016-02-03 10:16:02,228 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppZW1oJ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:16:13 INFO - 2016-02-03 10:16:13,988 DEBUG : BROWSER_OUTPUT: RSS: Main: 134344704 10:16:13 INFO - 2016-02-03 10:16:13,988 DEBUG : BROWSER_OUTPUT: 10:17:04 INFO - 2016-02-03 10:17:04,754 DEBUG : BROWSER_OUTPUT: 1454523424751 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:19:12 INFO - 2016-02-03 10:19:12,727 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57613/tests/dromaeo/cssquery-ext.html) 10:19:13 INFO - 2016-02-03 10:19:13,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 211255296 10:19:13 INFO - 2016-02-03 10:19:13,833 DEBUG : BROWSER_OUTPUT: 10:22:15 INFO - 2016-02-03 10:22:15,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/dromaeo/cssquery-ext.html (next: http://localhost:57613/tests/dromaeo/cssquery-jquery.html) 10:22:16 INFO - 2016-02-03 10:22:16,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 207511552 10:22:16 INFO - 2016-02-03 10:22:16,369 DEBUG : BROWSER_OUTPUT: 10:25:18 INFO - 2016-02-03 10:25:18,173 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57613/tests/dromaeo/cssquery-mootools.html) 10:25:18 INFO - 2016-02-03 10:25:18,734 DEBUG : BROWSER_OUTPUT: RSS: Main: 213667840 10:25:18 INFO - 2016-02-03 10:25:18,735 DEBUG : BROWSER_OUTPUT: 10:28:20 INFO - 2016-02-03 10:28:20,500 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57613/tests/dromaeo/cssquery-prototype.html) 10:28:21 INFO - 2016-02-03 10:28:21,061 DEBUG : BROWSER_OUTPUT: RSS: Main: 195969024 10:28:21 INFO - 2016-02-03 10:28:21,061 DEBUG : BROWSER_OUTPUT: 10:31:23 INFO - 2016-02-03 10:31:23,514 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57613/tests/dromaeo/cssquery-yui.html) 10:31:24 INFO - 2016-02-03 10:31:24,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 198164480 10:31:24 INFO - 2016-02-03 10:31:24,096 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,352 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/dromaeo/cssquery-yui.html (next: http://localhost:57613/tests/dromaeo/cssquery-dojo.html) 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 211206144 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3296.0606227225244;3321.7674762766615;3325.401427743005;3349.66503349665;3356.580427446569;5435.130379139338;5515.200295957084;5530.640508366963;5549.750261238239;5560.165975103737;5521.806736764214;5539.556835453164;5573.749181286842;5579.358373787016;5589.301337332833;4946.084974279752;5031.496850314967;5073.3404657394485;5151.253068305094;5154.716490593016;5081.237814327862;5094.363204599425;5125.897482050356;5129.6409251352425;5139.974300128494;5004.674696144757;5008.12357837377;5094.261332106842;5111.258867464216;5145.0481660892665;5076.46697096805;5105.489451054902;5106.617003724717;5108.897822043576;5141;5023.447420783711;5080.237964305346;5133;5143.125668636303;5151;5224.764885580158;5232;5322.520973112439;5329.840104796862;5339.5461385782255;5987.9101813472835;6017.69911504423;6030.9396906030815;6033.09503574463;6033.788817391414;5755.251817263771;6074.088886667036;6078.756849726005;6080.787172448943;6086.239220097488;5432.348118225838;5490.450954904558;5506.476884695948;5533.557315414757;5539.8892022159735;6084.452399284086;6095.299040610298;6105.755769769248;6110;6119.2350956130485;5728.713564321767;5848.444397782204;5917.704114794243;5917.792877249276;5920.763169473173;5975.940240597625;6060.575759696779;6064.545159112996;6073.51411887048;6116.3577824328695;5931.169636250928;5972.58191926561;5972.611780234271;5979.252593425822;5991.55063370249;5783.624064435799;5889.705514724246;5921.851953701192;5967;5983.730732117066;4592.265237562041;4600.769961501912;4648.3259927310355;4648.7443190624845;4672.883177920511;4176.0395109124465;4178.686598505064;4179.728317659343;4201.936970945377;4215.178040282116;5472.398036216013;5597.720113994284;5602.775888964477;5720.799772007959;5790.565707571949;6059.0608455689435;6070.908936365958;6071.089336599545;6080.0575910733905;6103.298120716175;5690.573207009491;5691.601587888891;5692.203091567184;5693.9430605693415;5719.628224165416;5036.395632524121;5150.690958542499;5227.320448341768;5229.790808367623;5231.110711179109;6076.483498902554;6197.5351848611535;6203.2556093268195;6203.4416902479;6209.068639704081;6070.2412198475195;6090.23872015998;6091.695415229132;6095.75616975316;6102.206713127265;5993.550483713826;6100.938990610037;6151.046587778901;6265.96867015662;6290.402411770874;5957.6127551709;5973.1338955852025;6001.7299221536005;6006.759729610767;6009.489193418608;6050.515958723204;6062.878742425215;6068.817935461943;6073.331933487401;6073.817785466443;5658.943410565842;5756.366799652119;5852.912206316823;5899.528037757055;6004;5914.792982245601;5928.555358348055;5934.050551911673;5935.258092738408;5936.614120082181;5828.53371730269;5831.8833623326445;5837.09525023622;5841.824745257649;5843.970780146072;5652.236948012131;5682.659040457585;5786.074228123648;5962.731677074629;5971.880562388815;5793.565482588907;5814.592978491465;5821.76712931478;5825.038868586806;5825.737841797045 10:34:26 INFO - 2016-02-03 10:34:26,611 DEBUG : BROWSER_OUTPUT: |1;ext.html;583.8073435766195;629.2983323594192;632.162384840087;635.9491240700744;636.7071147272256;33031.66968330316;41002.53984760913;41638.37542436858;41748;41794.79102604486;45975.310370344385;46421.30368044482;46456.535434645644;46462.53537464624;46495.76752116243;7946.562939038351;8123.106458289583;8136.186381361846;9056.09439056094;9220.815583688322;10737.570497180104;11107.389093599848;11113.277636953571;11113.555457781678;11118.555257789678;10726.570937162503;10843.728906777314;10856.240063195579;10856.511456984415;10861.294015888981;10679.199060070527;10780.137588992862;10785.245032847704;10793.784124317548;10796.352218866894;10385.740356491073;10427.530761115691;10507.684769456928;10508.106810921081;10518.947405263001;43043.78478107621;43175.78412107951;43304.3504347435;43441.78279108616;43478.60867652278;3669.743117981742;3742.4573436851506;3790;3792.715546334036;4053.8783836484954;33123;33316;33415.331693365995;33442.33115337704;33792.49311260333;4099.3031184698675;4155.750654960712;4231.014805060056;4265;4270.359446083113;294047;294231.5288423566;294339;294489;294546.99999999785;56091;57152.714236428554;63299.36700633027;71268.64365678243;72494;64501.354986450475;64527.67736161383;64716;64748.35251647518;64842.67578662076;16907.83092169087;17110.315587376615;17157.227924743416;17362.65274694499;17364.39224627132;12443.128980971425;12501.749965000648;12515.687107822196;12540.81188782169;12633.810492842615;21269;21293.78706212949;21333.46666333323;21341;21610.45973850635;7781.338586220121;7863.33161681252;7891.684332626631;7935.801604959806;8005.759827205193;1030.2015937648382;1126.2172789910933;1132.224426268009;1139.9373034483017;1185.857697076339;26686.866565667435;26749.598756018677;27714.5842812358;28257.293567660974;28468.85765571159;1093.1418836213534;1103.696483467053;1126.9887301127048;1160.3270103340042;1181.0374544746005;13123;14374.35315410809;14794.852051479347;14969.700605987819;15319.770203446957;345665;352796;353016;354024;354082;58955.11567326493;59128.408715912294;59256.70371648114;59341.70329148327;59348;1317.8484474285387;1326.2440408966988;1342.8187194728587;1402.656349194454;1439.0574173916102;27543.586846196922;27705.307367315978;27845.721542785122;28404;30045.248868778457;45643.77178114088;45876.31185532286;46163.538364615924;46203;46224.30663539982;234413;234940;235779;235871;235905;35009.64990350064;35364.29271414506;36012.45981310335;36154;36165.09587260339;47137.76431117822;47535;47739.76130119466;47979.760101199274;48956.2656560159;39708.55374787926;40316.596834032454;40829.38755918604;40852;41278;39920.2015959685;40257.39613905735;40621.87453582309;40879.1824163521;41151.176976459705;39337.60662393454;40343.79828100841;40587.1882562353;40634;40688.79655601703;31530.527042093927;32353.514697280032;32762;32801.67198327986;33342.49986250257;27256.591151133147;27350.179494615193;27364;27415.17754467369;27474 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: |2;jquery.html;395.30031843636766;396.6152831753199;397.11047254150714;398.6033896273208;401.8934982229708;15450.995685280448;15861.3655453782;18274.269029238814;18716.4385068448;18733.84948592199;13645.454181832714;16796.32814687414;16865.07242101687;17158.31366745332;17185.140742962863;5506.394296627368;5567.582431317647;5652.830415087554;5669.886602267952;5672.971635141839;5604.579656525777;5658.179563963223;5675.091985282335;5677.261955945722;5679.148127780824;5385.81149659762;5492.890142197154;5562.943040822251;5565.582581306418;5617;5399.519487719967;5435.483629055253;5508.476694713996;6137.140800287963;6166.660833654148;5557.944420555783;5561.165825126223;5561.221428999943;5562.082256427685;5564.749586268627;12469.563565275263;19592.80407195924;21537.353879383645;21591.2443064492;21939.903004849693;2297.9195728149434;2353.3881190890493;2452.1540068676277;2482.5283196192845;2502.737212592688;9891.604335826558;10394.428306443142;10468.633597824115;11443.313401195956;14350.569482915529;1938.37002974034;1941.2332128809182;1945.7762357328947;1953.980460195394;2009.6884982827685;62897.371026289606;63711;64621.67689161524;65148;65175.3482465174;10832.512536935857;10979.341239525655;11072.003519683107;11076.778464430667;11091.279066860629;13342;13644.93177534106;13787.310634468437;14801.851981480047;14847.183404912834;7599.164091949881;7744.574048427278;7780.84438311242;7783.766487005399;7794.454388192772;6950.443964482931;7053.435725141976;7054;7073.893891591631;7119.1457025157315;4612.354270402079;4674.415698037746;4674.7896344663895;4696.365990591226;4704.1062198182235;1892.2809332453855;1925.8555608329432;1966.822985931273;1971.5662554238045;1998.060911371653;475.82870166739946;487.381026096855;959.6065613098596;1045.414567842011;1055.3984228989557;11438.828417573743;11796.410179490991;11830.349330786717;11977.580784672495;11989.460474278678;950.7147855643418;962.6005207838808;975.4781192062212;976.5312649928075;998.2313618513849;7785.1436342002335;8478.872816907751;9000.234980026771;9380.95309523448;9387.249020078378;568574;606848;627795;629513;633391;77631;81276;81575.59212203902;83909.58045209735;85545.14454855371;1178.8762179971152;1202.7654607351658;1207.8671346151916;1230.6861750253806;1243.9675069692134;14453.277336133362;14537.273136343352;15507.457238996582;16015.199240038184;16397.09815960086;36652.26695466023;37397.439038413904;37454.25091498209;37669.62330376661;37717.245655087296;43996;45591.31613025741;46276.53723462722;46470.767646161556;47680.7615961918;10223.079922806983;10626;10664.200184986003;10673.199510036624;10778.08386287143;12281.078919081283;12404.069694772748;14693.779593305895;18163.909180454015;18389.90805045966;14632.634184145481;14753.114813111248;14816.629584260048;15044.097354158786;15152.924235378752;14729.410823567367;14835.629109272355;15050.172240526876;15364.308606112976;15449.150296733787;15648.217589120271;15670.294836732146;15952.202389880691;16121.596960076093;16161.353545858034;10231.51643011774;10455.895441045492;10611.840822387825;10614.1508679307;10638.73403164927;11079.751230111953;14981.625459363604;15241.847581524486;15508.68982620364;15710.371585136923 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1589.9920500397495;1602.5192442267316;1795.434438151983;1810.7555480010196;1842.2446796813306;7905.288524032836;8318.459300145487;8319.792005199874;8321.5007099574;8330.291925186359;6968.895466568006;7092.255313192118;7831.569263690509;7999.880001799977;8176.386770992184;2296.322584837477;2304.781045800646;2322.7328857181387;2327.6857624220684;2329.1964272326104;2048.7439070116234;2342.2387723989686;2364.0543782487057;2398.9160379386726;2406.193925035115;2056.557840064386;2313.826463015272;2350.7296660884044;2352.9176478823247;2355.611324131516;2325.1513197682807;2327.127327252289;2331.114176612893;2376.726676432198;2376.809855211596;2365.6924599802087;2365.8580485170946;2404.579198640231;2405.3866264102544;2407.0853075831246;8239.423240373177;8281.54451505167;8315.8336833263;8318.70884519039;8331.250187483156;4817.783199755984;4907.067657145167;4908.386451693538;4917.680350777188;4918.065567542156;3124.703153200442;3126.6873312668777;3158.3999040182293;3324.036029551427;3349.547811045502;1109.295597295715;2060.8248298894614;2111.556573119631;2119.2476670781803;2158.287765037534;11276.32342059479;11339.14956378275;11355.602553910574;11384;11400.771984560346;4266.570698815932;4281.079567892918;4309.439772829513;4377.430933978562;4389.912201755947;9379.671711490066;9429.434233945985;9448.291378146667;9455.385399949118;9455.669051583161;4916.655834091579;4917.0903382874285;4938.2839488274;4942.357493525818;4950.727709975986;4527.705699129546;4577.198990176706;4586.564276393738;4592.127495775792;4597.885052873705;3600.1899572595958;3624.0939765058733;3635.4183330667497;3667.339878821784;3669.2478042001326;1004.6433516101679;1023.3859684189426;1041.4532370505544;1067.685032915292;1080.4597701149426;1206.4751832952693;1233.2292317301687;1237.2514628649717;1473.1676602719645;1474.9041312314664;7568.5458872467825;7672.271134242238;7985.720499782596;8006.359491240687;8016.559089250147;685.1675214614168;686.0772261308532;689.3244620272161;695.6104581434413;827.8368891707161;6299.212598425197;6344.873102538016;6368.681565921685;6369.331220221903;6370.554061215763;9555.569999350046;9633.662821801203;9917.603295868084;9921.40471571708;9925.15636170919;9888.802223955481;10002;10102;10104.29269951111;10134.898651013544;7405.1854296027395;7568.281013303727;7582.431317651198;7587.848243035219;7588.468807183444;7604.391648668204;7898.960505197438;7962.562059086574;7966.28303452692;7967.960160199162;8792.076831932554;8834.602442889956;8933.508657023665;9025.864612030693;9110.453372797654;8936.374453788432;9054.728358149267;9250.537473126182;9257.166854983085;9415.293852960918;7216.855662886818;7295.562266263829;7304.306090921354;7304.671289791866;7311.305425984523;7554.584497852671;7834.099078606033;7860.882086768589;8035.678572857254;8110.391720620859;6456.225252969486;7154.749583764543;7482.289182527651;7599.392048636207;7653.617319133909;7216.855662886818;7309.5248808827255;7314.122305323395;7380.852382952204;7535.73624923147;7476.476646634901;7584.848303033798;7622.085349758065;7630.389568834591;7638.579878106758;7004.859902802017;7056.54132481387;7057.964710176417;7059.788206353817;7067.964660176666;6785.219699734396;6877.828054298682;6931;6947.722091116501;6948.33990770876 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: |4;prototype.html;367.42497990644637;370.5460810507129;372.26477706529624;373.50137566348917;378.34546235013426;6056.63660180389;6060.3939606039485;6081.057436097401;6085.4218849209365;6196.628202307865;5966.79116230932;6076.2708474983065;6098.298695650002;6111.786087486938;6118.265808103034;4789.880252993668;4798.616110711135;4800.543948324921;4801.591864691488;4810.25441056635;4609.769511524427;4684.648651351145;4732.2428411454175;4736.9526304736855;4809.398825146856;4748.192807222763;4772.976135119319;4775.832845850396;4779.16364636189;4785.952140478603;4756.334113224151;4757.26262429324;4760.166970780134;4763.404574428179;4769.95230047702;4243.045314804175;4247.554006829269;4250.936235956463;4252.362145678142;4260.062786187033;5716.685582292972;5814.215080964058;5824.504917082053;5881.176635271066;5906.940930590682;5548.472895074961;5550.361708403545;5559.610827242094;5559.722013899289;5560.77756889724;6260.686965651699;6395.456386207132;6553.475721942234;6568.507361947825;6572.572782769104;4674.182018146811;4721.244600863845;4721.480637129913;4737.597304229144;4749.4775574686755;6596.868062638769;6685.164354455693;6688.498362622823;6744.426723728489;6756.966215168942;4870.318155458239;4875.975620121948;4881.780319885543;4882.902341953212;4887.73117478535;4770.594499467584;4910.607151427877;4918.48355922623;4921.680090794087;4939.407271127488;5581.6930068846605;5592.245046918614;5594;5598.3282006159525;5600.831975040755;6568.441682457044;6569.211694596679;6610.3720146586;6610.504212184105;6611.933880661132;1693.644334689729;1725.2322716391086;1746.519707080563;1750.5098572399747;1763.365188532153;1184.5735535207318;1197.0304053716518;1204.722913729847;1218.622227109599;1220.1641875315438;6315.936840631627;6323.241211054611;6337.493000559944;6340.175777148941;6346.365363463709;6717.798466046026;6729.798106056826;6735.360140786617;6737.157855268091;6740.460763139035;6194.0708893666315;6331.923572992602;6332.68336583169;6335.841603959938;6339.556231063873;3610.891673249807;3630.655087766711;3650.470681751131;3653.4702468141973;3654.543182102237;6849.863002740017;6854.862902742017;6857.382835544725;6860.245373008965;6862.245153033163;6735.764248251287;6882.139732533434;6889.517733758689;6897.724091036403;6900.7929762107215;6929.1684997800585;6944.479164062715;6951.89572156418;6954.3045695430055;6961.93038069623;6616.735330586723;6678.49911256648;6739.494537909578;6838.692258848463;6840.623765692736;6732.259451460433;6752.628605426748;6753.966230168818;6758.121444212221;6759.526833121634;6873.072135261676;7043;7047.929520704933;7050.224475307815;7055;4652.13935421948;4679.368285281444;4684.110019096361;4685;4686.601638860735;6830.795076147723;6831.897521537082;6835.521513494008;6840.863182736218;6844.5208835381045;6459.483241340776;6646.368594983469;6652.567583107082;6659.833504162435;6660.367265109807;6609.900851487328;6618.238902526078;6667.633280169637;6675.132232809898;6675.766348177791;6508.446782023391;6540.117084193573;6545.803625891231;6556.8360790980605;6565.409113179837;6526.967365163334;6648.16897887764;6648.7340506379205;6656;6717.16035495563;6546.901796473152;6547.312532184243;6556.704948277435;6558.573692709959;6559.868802623825 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: |5;yui.html;828.8135169586843;852.9744107676768;853.8761879527468;903.5346796399854;968.9777284964359;14293.356798944047;15931.840681593181;15932.601684957854;15956.680866382665;15958.28187731552;14868.553943381688;14882.553523394288;14901.850981490183;14929.92535037326;14988.325525351358;742.5062333548184;754.9962250188742;901.6934242357597;901.7069452427954;916.2166347772659;891.5809569502323;897.8473659477904;902.5216635183364;903.9276857851389;904.900460949295;778.4395235430482;918.2516249256868;930.1396208507103;933.4772527384687;933.63121566981;775.5966897213474;937.9671711490099;954.2366107114316;955.5126885288483;956.4548207521715;698.4203111417511;704.4540481127116;705.0939542687622;710.314546462666;710.8329542557495;14323.212223327713;14700.411983520646;14702.411903523953;14719.558413247512;14750.262486875614;13206.207627542282;13361.66595835112;13505.12216705911;13924.58226253214;14154.92922535391;11573.594924177612;11586.246893951866;11650.475728592233;11669.533218671242;11855.703607409798;3962.5641179470235;4010.4786377770993;4013.759174449513;4049.5545489996075;4072.429859819628;52536.73731631317;52664.21003684948;52925.2061219082;53019.20471192935;53115.734421327645;10955.630546181726;11119.166062545342;11281.94359028216;11371.147163962736;11616.186866919403;19238.422847314603;19275.132619032178;19290.22839086421;19297.807021929882;19303;8161.836763264701;8245.505269683837;8312.25189732927;8327.125651806595;8434.536100514531;4955.405351357788;5034.169362055293;5036.420811606712;5055;5094.847154585368;3301.273719781644;3324.7672662913847;3328.384248913959;3328.73370130389;3330.2506935939705;623.5043140703152;628.2210059526246;636.1126228910643;636.1634450697318;640.6444423345088;617.5337620096798;642.1972534332085;648.2221334398646;667.3493343989668;765.74730338988;8817.779555511164;9370.39092458988;9537.133264049873;9538.570764315622;9602.135807777333;690.3062422265591;696.4498046543275;718.9101362329709;773.2460850670551;812.6749300279936;4318.719283246579;4500.617447516997;4575.450945886515;4605.0559635274685;4733.124371991193;61283.387166127766;63745.68127159335;64310.67844660747;64668;64764.676176618814;53963.73018134963;55027.724861376235;55326.17010744842;55430.16854747182;55600.44399555953;48444.27333589999;48762.5123748765;54327.45672543224;54497.72751136219;56011.999999999185;9626.085521875411;9691.370060946016;9771.804563908825;9921.900780992386;9979.9541094077;37954.81022594869;38875.80562097171;39239.803800980815;39262.4110638335;39308.23112959981;44316.33525497056;44352;48666.5133348662;50180.498195017586;51172;12068.577599784317;12142.271463712206;12199.51201951912;12284.877151228373;12512.562060327844;13606.931965340505;13673.453061877413;13678.110922789987;13771.655708607364;13791.862081379059;12396.194247373893;12764.29796361209;12905.09664323526;12946.158499697489;12976.935115324362;11995.220310679779;12062.336571488653;12349.3825308736;12600.306983116017;12652.936735316265;9877.50612469388;10997.670069897917;11043.779124417628;12531.122821402414;12806.35968201605;10454.372737635766;10864.782704346027;11299.661010169708;11329.773404531697;11429.28535732134;10486;10802.67591972242;10819.729506762395;10873.673789786319;10886.782264354826 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,866 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: __startTimestamp1454524466604__endTimestamp 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,612 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5567.72 Median:5790.57 stddev:621.16 (10.7%) stddev-sans-first:596.96 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: Values: 3296.1 3321.8 3325.4 3349.7 3356.6 5435.1 5515.2 5530.6 5549.8 5560.2 5521.8 5539.6 5573.7 5579.4 5589.3 4946.1 5031.5 5073.3 5151.3 5154.7 5081.2 5094.4 5125.9 5129.6 5140.0 5004.7 5008.1 5094.3 5111.3 5145.0 5076.5 5105.5 5106.6 5108.9 5141.0 5023.4 5080.2 5133.0 5143.1 5151.0 5224.8 5232.0 5322.5 5329.8 5339.5 5987.9 6017.7 6030.9 6033.1 6033.8 5755.3 6074.1 6078.8 6080.8 6086.2 5432.3 5490.5 5506.5 5533.6 5539.9 6084.5 6095.3 6105.8 6110.0 6119.2 5728.7 5848.4 5917.7 5917.8 5920.8 5975.9 6060.6 6064.5 6073.5 6116.4 5931.2 5972.6 5972.6 5979.3 5991.6 5783.6 5889.7 5921.9 5967.0 5983.7 4592.3 4600.8 4648.3 4648.7 4672.9 4176.0 4178.7 4179.7 4201.9 4215.2 5472.4 5597.7 5602.8 5720.8 5790.6 6059.1 6070.9 6071.1 6080.1 6103.3 5690.6 5691.6 5692.2 5693.9 5719.6 5036.4 5150.7 5227.3 5229.8 5231.1 6076.5 6197.5 6203.3 6203.4 6209.1 6070.2 6090.2 6091.7 6095.8 6102.2 5993.6 6100.9 6151.0 6266.0 6290.4 5957.6 5973.1 6001.7 6006.8 6009.5 6050.5 6062.9 6068.8 6073.3 6073.8 5658.9 5756.4 5852.9 5899.5 6004.0 5914.8 5928.6 5934.1 5935.3 5936.6 5828.5 5831.9 5837.1 5841.8 5844.0 5652.2 5682.7 5786.1 5962.7 5971.9 5793.6 5814.6 5821.8 5825.0 5825.7 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48243.11 Median:189728.75 stddev:77751.03 (41.0%) stddev-sans-first:77886.79 10:34:26 INFO - 2016-02-03 10:34:26,614 DEBUG : BROWSER_OUTPUT: Values: 583.8 629.3 632.2 635.9 636.7 33031.7 41002.5 41638.4 41748.0 41794.8 45975.3 46421.3 46456.5 46462.5 46495.8 7946.6 8123.1 8136.2 9056.1 9220.8 10737.6 11107.4 11113.3 11113.6 11118.6 10726.6 10843.7 10856.2 10856.5 10861.3 10679.2 10780.1 10785.2 10793.8 10796.4 10385.7 10427.5 10507.7 10508.1 10518.9 43043.8 43175.8 43304.4 43441.8 43478.6 3669.7 3742.5 3790.0 3792.7 4053.9 33123.0 33316.0 33415.3 33442.3 33792.5 4099.3 4155.8 4231.0 4265.0 4270.4 294047.0 294231.5 294339.0 294489.0 294547.0 56091.0 57152.7 63299.4 71268.6 72494.0 64501.4 64527.7 64716.0 64748.4 64842.7 16907.8 17110.3 17157.2 17362.7 17364.4 12443.1 12501.7 12515.7 12540.8 12633.8 21269.0 21293.8 21333.5 21341.0 21610.5 7781.3 7863.3 7891.7 7935.8 8005.8 1030.2 1126.2 1132.2 1139.9 1185.9 26686.9 26749.6 27714.6 28257.3 28468.9 1093.1 1103.7 1127.0 1160.3 1181.0 13123.0 14374.4 14794.9 14969.7 15319.8 345665.0 352796.0 353016.0 354024.0 354082.0 58955.1 59128.4 59256.7 59341.7 59348.0 1317.8 1326.2 1342.8 1402.7 1439.1 27543.6 27705.3 27845.7 28404.0 30045.2 45643.8 45876.3 46163.5 46203.0 46224.3 234413.0 234940.0 235779.0 235871.0 235905.0 35009.6 35364.3 36012.5 36154.0 36165.1 47137.8 47535.0 47739.8 47979.8 48956.3 39708.6 40316.6 40829.4 40852.0 41278.0 39920.2 40257.4 40621.9 40879.2 41151.2 39337.6 40343.8 40587.2 40634.0 40688.8 31530.5 32353.5 32762.0 32801.7 33342.5 27256.6 27350.2 27364.0 27415.2 27474.0 10:34:26 INFO - 2016-02-03 10:34:26,615 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,615 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31177.89 Median:12118.91 stddev:100218.40 (827.0%) stddev-sans-first:100472.88 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: Values: 395.3 396.6 397.1 398.6 401.9 15451.0 15861.4 18274.3 18716.4 18733.8 13645.5 16796.3 16865.1 17158.3 17185.1 5506.4 5567.6 5652.8 5669.9 5673.0 5604.6 5658.2 5675.1 5677.3 5679.1 5385.8 5492.9 5562.9 5565.6 5617.0 5399.5 5435.5 5508.5 6137.1 6166.7 5557.9 5561.2 5561.2 5562.1 5564.7 12469.6 19592.8 21537.4 21591.2 21939.9 2297.9 2353.4 2452.2 2482.5 2502.7 9891.6 10394.4 10468.6 11443.3 14350.6 1938.4 1941.2 1945.8 1954.0 2009.7 62897.4 63711.0 64621.7 65148.0 65175.3 10832.5 10979.3 11072.0 11076.8 11091.3 13342.0 13644.9 13787.3 14801.9 14847.2 7599.2 7744.6 7780.8 7783.8 7794.5 6950.4 7053.4 7054.0 7073.9 7119.1 4612.4 4674.4 4674.8 4696.4 4704.1 1892.3 1925.9 1966.8 1971.6 1998.1 475.8 487.4 959.6 1045.4 1055.4 11438.8 11796.4 11830.3 11977.6 11989.5 950.7 962.6 975.5 976.5 998.2 7785.1 8478.9 9000.2 9381.0 9387.2 568574.0 606848.0 627795.0 629513.0 633391.0 77631.0 81276.0 81575.6 83909.6 85545.1 1178.9 1202.8 1207.9 1230.7 1244.0 14453.3 14537.3 15507.5 16015.2 16397.1 36652.3 37397.4 37454.3 37669.6 37717.2 43996.0 45591.3 46276.5 46470.8 47680.8 10223.1 10626.0 10664.2 10673.2 10778.1 12281.1 12404.1 14693.8 18163.9 18389.9 14632.6 14753.1 14816.6 15044.1 15152.9 14729.4 14835.6 15050.2 15364.3 15449.2 15648.2 15670.3 15952.2 16121.6 16161.4 10231.5 10455.9 10611.8 10614.2 10638.7 11079.8 14981.6 15241.8 15508.7 15710.4 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5772.39 Median:3735.19 stddev:2997.00 (80.2%) stddev-sans-first:2988.92 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: Values: 1590.0 1602.5 1795.4 1810.8 1842.2 7905.3 8318.5 8319.8 8321.5 8330.3 6968.9 7092.3 7831.6 7999.9 8176.4 2296.3 2304.8 2322.7 2327.7 2329.2 2048.7 2342.2 2364.1 2398.9 2406.2 2056.6 2313.8 2350.7 2352.9 2355.6 2325.2 2327.1 2331.1 2376.7 2376.8 2365.7 2365.9 2404.6 2405.4 2407.1 8239.4 8281.5 8315.8 8318.7 8331.3 4817.8 4907.1 4908.4 4917.7 4918.1 3124.7 3126.7 3158.4 3324.0 3349.5 1109.3 2060.8 2111.6 2119.2 2158.3 11276.3 11339.1 11355.6 11384.0 11400.8 4266.6 4281.1 4309.4 4377.4 4389.9 9379.7 9429.4 9448.3 9455.4 9455.7 4916.7 4917.1 4938.3 4942.4 4950.7 4527.7 4577.2 4586.6 4592.1 4597.9 3600.2 3624.1 3635.4 3667.3 3669.2 1004.6 1023.4 1041.5 1067.7 1080.5 1206.5 1233.2 1237.3 1473.2 1474.9 7568.5 7672.3 7985.7 8006.4 8016.6 685.2 686.1 689.3 695.6 827.8 6299.2 6344.9 6368.7 6369.3 6370.6 9555.6 9633.7 9917.6 9921.4 9925.2 9888.8 10002.0 10102.0 10104.3 10134.9 7405.2 7568.3 7582.4 7587.8 7588.5 7604.4 7899.0 7962.6 7966.3 7968.0 8792.1 8834.6 8933.5 9025.9 9110.5 8936.4 9054.7 9250.5 9257.2 9415.3 7216.9 7295.6 7304.3 7304.7 7311.3 7554.6 7834.1 7860.9 8035.7 8110.4 6456.2 7154.7 7482.3 7599.4 7653.6 7216.9 7309.5 7314.1 7380.9 7535.7 7476.5 7584.8 7622.1 7630.4 7638.6 7004.9 7056.5 7058.0 7059.8 7068.0 6785.2 6877.8 6931.0 6947.7 6948.3 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5519.84 Median:6228.66 stddev:1624.53 (26.1%) stddev-sans-first:1582.39 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: Values: 367.4 370.5 372.3 373.5 378.3 6056.6 6060.4 6081.1 6085.4 6196.6 5966.8 6076.3 6098.3 6111.8 6118.3 4789.9 4798.6 4800.5 4801.6 4810.3 4609.8 4684.6 4732.2 4737.0 4809.4 4748.2 4773.0 4775.8 4779.2 4786.0 4756.3 4757.3 4760.2 4763.4 4770.0 4243.0 4247.6 4250.9 4252.4 4260.1 5716.7 5814.2 5824.5 5881.2 5906.9 5548.5 5550.4 5559.6 5559.7 5560.8 6260.7 6395.5 6553.5 6568.5 6572.6 4674.2 4721.2 4721.5 4737.6 4749.5 6596.9 6685.2 6688.5 6744.4 6757.0 4870.3 4876.0 4881.8 4882.9 4887.7 4770.6 4910.6 4918.5 4921.7 4939.4 5581.7 5592.2 5594.0 5598.3 5600.8 6568.4 6569.2 6610.4 6610.5 6611.9 1693.6 1725.2 1746.5 1750.5 1763.4 1184.6 1197.0 1204.7 1218.6 1220.2 6315.9 6323.2 6337.5 6340.2 6346.4 6717.8 6729.8 6735.4 6737.2 6740.5 6194.1 6331.9 6332.7 6335.8 6339.6 3610.9 3630.7 3650.5 3653.5 3654.5 6849.9 6854.9 6857.4 6860.2 6862.2 6735.8 6882.1 6889.5 6897.7 6900.8 6929.2 6944.5 6951.9 6954.3 6961.9 6616.7 6678.5 6739.5 6838.7 6840.6 6732.3 6752.6 6754.0 6758.1 6759.5 6873.1 7043.0 7047.9 7050.2 7055.0 4652.1 4679.4 4684.1 4685.0 4686.6 6830.8 6831.9 6835.5 6840.9 6844.5 6459.5 6646.4 6652.6 6659.8 6660.4 6609.9 6618.2 6667.6 6675.1 6675.8 6508.4 6540.1 6545.8 6556.8 6565.4 6527.0 6648.2 6648.7 6656.0 6717.2 6546.9 6547.3 6556.7 6558.6 6559.9 10:34:26 INFO - 2016-02-03 10:34:26,616 DEBUG : BROWSER_OUTPUT: 10:34:26 INFO - 2016-02-03 10:34:26,617 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15214.49 Median:26588.70 stddev:17511.07 (65.9%) stddev-sans-first:17526.87 10:34:26 INFO - 2016-02-03 10:34:26,618 DEBUG : BROWSER_OUTPUT: Values: 828.8 853.0 853.9 903.5 969.0 14293.4 15931.8 15932.6 15956.7 15958.3 14868.6 14882.6 14901.9 14929.9 14988.3 742.5 755.0 901.7 901.7 916.2 891.6 897.8 902.5 903.9 904.9 778.4 918.3 930.1 933.5 933.6 775.6 938.0 954.2 955.5 956.5 698.4 704.5 705.1 710.3 710.8 14323.2 14700.4 14702.4 14719.6 14750.3 13206.2 13361.7 13505.1 13924.6 14154.9 11573.6 11586.2 11650.5 11669.5 11855.7 3962.6 4010.5 4013.8 4049.6 4072.4 52536.7 52664.2 52925.2 53019.2 53115.7 10955.6 11119.2 11281.9 11371.1 11616.2 19238.4 19275.1 19290.2 19297.8 19303.0 8161.8 8245.5 8312.3 8327.1 8434.5 4955.4 5034.2 5036.4 5055.0 5094.8 3301.3 3324.8 3328.4 3328.7 3330.3 623.5 628.2 636.1 636.2 640.6 617.5 642.2 648.2 667.3 765.7 8817.8 9370.4 9537.1 9538.6 9602.1 690.3 696.4 718.9 773.2 812.7 4318.7 4500.6 4575.5 4605.1 4733.1 61283.4 63745.7 64310.7 64668.0 64764.7 53963.7 55027.7 55326.2 55430.2 55600.4 48444.3 48762.5 54327.5 54497.7 56012.0 9626.1 9691.4 9771.8 9921.9 9980.0 37954.8 38875.8 39239.8 39262.4 39308.2 44316.3 44352.0 48666.5 50180.5 51172.0 12068.6 12142.3 12199.5 12284.9 12512.6 13606.9 13673.5 13678.1 13771.7 13791.9 12396.2 12764.3 12905.1 12946.2 12976.9 11995.2 12062.3 12349.4 12600.3 12652.9 9877.5 10997.7 11043.8 12531.1 12806.4 10454.4 10864.8 11299.7 11329.8 11429.3 10486.0 10802.7 10819.7 10873.7 10886.8 10:34:26 INFO - 2016-02-03 10:34:26,618 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:34:26 INFO - 2016-02-03 10:34:26,618 DEBUG : BROWSER_OUTPUT: 10:34:27 INFO - 2016-02-03 10:34:27,192 INFO : Browser exited with error code: 0 10:34:27 INFO - 2016-02-03 10:34:27,212 INFO : Completed test dromaeo_css (00:18:29) 10:34:27 INFO - 2016-02-03 10:34:27,212 INFO : Starting test kraken 10:34:27 INFO - 2016-02-03 10:34:27,212 DEBUG : operating with platform_type : linux_ 10:34:27 INFO - 2016-02-03 10:34:27,213 INFO : Initialising browser for kraken test... 10:34:27 INFO - 2016-02-03 10:34:27,219 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppk6BRc/profile http://localhost:57613/getInfo.html 10:34:30 INFO - 2016-02-03 10:34:30,987 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:34:30 INFO - 2016-02-03 10:34:30,987 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:34:30 INFO - 2016-02-03 10:34:30,987 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:34:30 INFO - 2016-02-03 10:34:30,987 DEBUG : BROWSER_OUTPUT: __metrics 10:34:31 INFO - 2016-02-03 10:34:31,144 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:34:31 INFO - 2016-02-03 10:34:31,838 INFO : Browser initialized. 10:34:31 INFO - 2016-02-03 10:34:31,839 INFO : Running cycle 1/1 for kraken test... 10:34:31 INFO - 2016-02-03 10:34:31,839 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppk6BRc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:34:43 INFO - 2016-02-03 10:34:43,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 134832128 10:34:43 INFO - 2016-02-03 10:34:43,576 DEBUG : BROWSER_OUTPUT: 10:34:45 INFO - 2016-02-03 10:34:45,991 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57613/tests/kraken/driver.html?testName=audio-beat-detection) 10:34:46 INFO - 2016-02-03 10:34:46,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 167088128 10:34:46 INFO - 2016-02-03 10:34:46,558 DEBUG : BROWSER_OUTPUT: 10:34:49 INFO - 2016-02-03 10:34:49,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57613/tests/kraken/driver.html?testName=audio-dft) 10:34:49 INFO - 2016-02-03 10:34:49,663 DEBUG : BROWSER_OUTPUT: RSS: Main: 162721792 10:34:49 INFO - 2016-02-03 10:34:49,663 DEBUG : BROWSER_OUTPUT: 10:34:52 INFO - 2016-02-03 10:34:52,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57613/tests/kraken/driver.html?testName=audio-fft) 10:34:53 INFO - 2016-02-03 10:34:53,011 DEBUG : BROWSER_OUTPUT: RSS: Main: 159711232 10:34:53 INFO - 2016-02-03 10:34:53,011 DEBUG : BROWSER_OUTPUT: 10:34:54 INFO - 2016-02-03 10:34:54,984 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57613/tests/kraken/driver.html?testName=audio-oscillator) 10:34:55 INFO - 2016-02-03 10:34:55,546 DEBUG : BROWSER_OUTPUT: RSS: Main: 158711808 10:34:55 INFO - 2016-02-03 10:34:55,546 DEBUG : BROWSER_OUTPUT: 10:34:57 INFO - 2016-02-03 10:34:57,744 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57613/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:34:58 INFO - 2016-02-03 10:34:58,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 160497664 10:34:58 INFO - 2016-02-03 10:34:58,305 DEBUG : BROWSER_OUTPUT: 10:35:01 INFO - 2016-02-03 10:35:01,130 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57613/tests/kraken/driver.html?testName=imaging-darkroom) 10:35:01 INFO - 2016-02-03 10:35:01,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 160780288 10:35:01 INFO - 2016-02-03 10:35:01,695 DEBUG : BROWSER_OUTPUT: 10:35:05 INFO - 2016-02-03 10:35:05,016 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57613/tests/kraken/driver.html?testName=imaging-desaturate) 10:35:05 INFO - 2016-02-03 10:35:05,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 161107968 10:35:05 INFO - 2016-02-03 10:35:05,582 DEBUG : BROWSER_OUTPUT: 10:35:08 INFO - 2016-02-03 10:35:08,271 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57613/tests/kraken/driver.html?testName=json-parse-financial) 10:35:08 INFO - 2016-02-03 10:35:08,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 161783808 10:35:08 INFO - 2016-02-03 10:35:08,845 DEBUG : BROWSER_OUTPUT: 10:35:10 INFO - 2016-02-03 10:35:10,362 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57613/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:35:10 INFO - 2016-02-03 10:35:10,923 DEBUG : BROWSER_OUTPUT: RSS: Main: 166510592 10:35:10 INFO - 2016-02-03 10:35:10,924 DEBUG : BROWSER_OUTPUT: 10:35:12 INFO - 2016-02-03 10:35:12,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:35:13 INFO - 2016-02-03 10:35:13,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 163586048 10:35:13 INFO - 2016-02-03 10:35:13,259 DEBUG : BROWSER_OUTPUT: 10:35:15 INFO - 2016-02-03 10:35:15,272 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:35:15 INFO - 2016-02-03 10:35:15,842 DEBUG : BROWSER_OUTPUT: RSS: Main: 168464384 10:35:15 INFO - 2016-02-03 10:35:15,842 DEBUG : BROWSER_OUTPUT: 10:35:18 INFO - 2016-02-03 10:35:18,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:35:18 INFO - 2016-02-03 10:35:18,750 DEBUG : BROWSER_OUTPUT: RSS: Main: 173842432 10:35:18 INFO - 2016-02-03 10:35:18,750 DEBUG : BROWSER_OUTPUT: 10:35:21 INFO - 2016-02-03 10:35:21,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:35:21 INFO - 2016-02-03 10:35:21,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 172883968 10:35:21 INFO - 2016-02-03 10:35:21,786 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,404 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57613/tests/kraken/driver.html?testName=ai-astar) 10:35:23 INFO - 2016-02-03 10:35:23,654 DEBUG : BROWSER_OUTPUT: RSS: Main: 232075264 10:35:23 INFO - 2016-02-03 10:35:23,654 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,656 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:35:23 INFO - 2016-02-03 10:35:23,656 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:35:23 INFO - 2016-02-03 10:35:23,656 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:35:23 INFO - 2016-02-03 10:35:23,656 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:35:23 INFO - 2016-02-03 10:35:23,656 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;103;107;109;108;98;107;110;109 10:35:23 INFO - 2016-02-03 10:35:23,657 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;122;157;155;131;151;151;121;163;121 10:35:23 INFO - 2016-02-03 10:35:23,658 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;158;160;160;159;161;163;161;157 10:35:23 INFO - 2016-02-03 10:35:23,658 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;73;70;149;71;78;71;71;151;72 10:35:23 INFO - 2016-02-03 10:35:23,658 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;102;92;138;93;93;93;141;92;92;137 10:35:23 INFO - 2016-02-03 10:35:23,658 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 10:35:23 INFO - 2016-02-03 10:35:23,659 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;148;145;145;146;146;145;145;145;146 10:35:23 INFO - 2016-02-03 10:35:23,659 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;94;95;95;95;95;94;95;95;95;96 10:35:23 INFO - 2016-02-03 10:35:23,659 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;54;56;55;55;54;58;55;54;54 10:35:23 INFO - 2016-02-03 10:35:23,659 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;65;64;64;64;64;64;65;64;64 10:35:23 INFO - 2016-02-03 10:35:23,660 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;80;79;76;79;77;83;78;79;80;86 10:35:23 INFO - 2016-02-03 10:35:23,660 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;109;112;114;111;112;123;114;117;110 10:35:23 INFO - 2016-02-03 10:35:23,660 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;154;140;140;143;134;146;143;140;131 10:35:23 INFO - 2016-02-03 10:35:23,660 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;66;69;63;64;63;64;62;65;63 10:35:23 INFO - 2016-02-03 10:35:23,660 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:35:23 INFO - 2016-02-03 10:35:23,660 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:35:23 INFO - 2016-02-03 10:35:23,661 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,868 10:35:23 INFO - 2016-02-03 10:35:23,661 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:35:23 INFO - 2016-02-03 10:35:23,661 DEBUG : BROWSER_OUTPUT: __startTimestamp1454524523655__endTimestamp 10:35:23 INFO - 2016-02-03 10:35:23,661 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,663 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:35:23 INFO - 2016-02-03 10:35:23,663 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:35:23 INFO - 2016-02-03 10:35:23,663 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,663 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.80 Median:109.00 stddev:3.65 (3.3%) stddev-sans-first:3.84 10:35:23 INFO - 2016-02-03 10:35:23,664 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 103.0 107.0 109.0 108.0 98.0 107.0 110.0 109.0 10:35:23 INFO - 2016-02-03 10:35:23,665 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,665 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:151.00 stddev:16.45 (10.9%) stddev-sans-first:17.31 10:35:23 INFO - 2016-02-03 10:35:23,665 DEBUG : BROWSER_OUTPUT: Values: 148.0 122.0 157.0 155.0 131.0 151.0 151.0 121.0 163.0 121.0 10:35:23 INFO - 2016-02-03 10:35:23,666 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,666 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.69 (1.1%) stddev-sans-first:1.76 10:35:23 INFO - 2016-02-03 10:35:23,666 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 158.0 160.0 160.0 159.0 161.0 163.0 161.0 157.0 10:35:23 INFO - 2016-02-03 10:35:23,666 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,667 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.60 Median:71.00 stddev:32.97 (46.4%) stddev-sans-first:34.35 10:35:23 INFO - 2016-02-03 10:35:23,667 DEBUG : BROWSER_OUTPUT: Values: 70.0 73.0 70.0 149.0 71.0 78.0 71.0 71.0 151.0 72.0 10:35:23 INFO - 2016-02-03 10:35:23,667 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,667 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.30 Median:92.00 stddev:21.87 (23.8%) stddev-sans-first:23.11 10:35:23 INFO - 2016-02-03 10:35:23,668 DEBUG : BROWSER_OUTPUT: Values: 102.0 92.0 138.0 93.0 93.0 93.0 141.0 92.0 92.0 137.0 10:35:23 INFO - 2016-02-03 10:35:23,668 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,668 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 10:35:23 INFO - 2016-02-03 10:35:23,668 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 10:35:23 INFO - 2016-02-03 10:35:23,668 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,668 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 10:35:23 INFO - 2016-02-03 10:35:23,669 DEBUG : BROWSER_OUTPUT: Values: 146.0 148.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 146.0 10:35:23 INFO - 2016-02-03 10:35:23,669 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,669 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 10:35:23 INFO - 2016-02-03 10:35:23,669 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 10:35:23 INFO - 2016-02-03 10:35:23,669 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,670 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:1.40 (2.5%) stddev-sans-first:1.32 10:35:23 INFO - 2016-02-03 10:35:23,670 DEBUG : BROWSER_OUTPUT: Values: 57.0 54.0 56.0 55.0 55.0 54.0 58.0 55.0 54.0 54.0 10:35:23 INFO - 2016-02-03 10:35:23,670 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,670 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 10:35:23 INFO - 2016-02-03 10:35:23,671 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 64.0 64.0 64.0 64.0 64.0 65.0 64.0 64.0 10:35:23 INFO - 2016-02-03 10:35:23,671 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,671 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:79.50 stddev:2.91 (3.7%) stddev-sans-first:3.08 10:35:23 INFO - 2016-02-03 10:35:23,671 DEBUG : BROWSER_OUTPUT: Values: 80.0 79.0 76.0 79.0 77.0 83.0 78.0 79.0 80.0 86.0 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.80 Median:114.00 stddev:4.10 (3.6%) stddev-sans-first:4.28 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: Values: 116.0 109.0 112.0 114.0 111.0 112.0 123.0 114.0 117.0 110.0 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.80 Median:141.50 stddev:6.41 (4.5%) stddev-sans-first:6.65 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: Values: 137.0 154.0 140.0 140.0 143.0 134.0 146.0 143.0 140.0 131.0 10:35:23 INFO - 2016-02-03 10:35:23,672 DEBUG : BROWSER_OUTPUT: 10:35:23 INFO - 2016-02-03 10:35:23,673 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:2.00 (3.1%) stddev-sans-first:2.12 10:35:23 INFO - 2016-02-03 10:35:23,673 DEBUG : BROWSER_OUTPUT: Values: 64.0 66.0 69.0 63.0 64.0 63.0 64.0 62.0 65.0 63.0 10:35:23 INFO - 2016-02-03 10:35:23,673 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:35:23 INFO - 2016-02-03 10:35:23,673 DEBUG : BROWSER_OUTPUT: 10:35:24 INFO - 2016-02-03 10:35:24,348 INFO : Browser exited with error code: 0 10:35:24 INFO - 2016-02-03 10:35:24,400 INFO : Completed test kraken (00:00:57) 10:35:24 INFO - 2016-02-03 10:35:24,400 INFO : Starting test v8_7 10:35:24 INFO - 2016-02-03 10:35:24,400 DEBUG : operating with platform_type : linux_ 10:35:24 INFO - 2016-02-03 10:35:24,400 INFO : Initialising browser for v8_7 test... 10:35:24 INFO - 2016-02-03 10:35:24,406 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpb4Xr1P/profile http://localhost:57613/getInfo.html 10:35:28 INFO - 2016-02-03 10:35:28,249 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:35:28 INFO - 2016-02-03 10:35:28,249 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:35:28 INFO - 2016-02-03 10:35:28,249 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:35:28 INFO - 2016-02-03 10:35:28,249 DEBUG : BROWSER_OUTPUT: __metrics 10:35:28 INFO - 2016-02-03 10:35:28,406 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:35:29 INFO - 2016-02-03 10:35:29,026 INFO : Browser initialized. 10:35:29 INFO - 2016-02-03 10:35:29,027 INFO : Running cycle 1/1 for v8_7 test... 10:35:29 INFO - 2016-02-03 10:35:29,027 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpb4Xr1P/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:35:40 INFO - 2016-02-03 10:35:40,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 135106560 10:35:40 INFO - 2016-02-03 10:35:40,775 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,155 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57613/tests/v8_7/run.html (next: http://localhost:57613/tests/v8_7/run.html) 10:36:02 INFO - 2016-02-03 10:36:02,406 DEBUG : BROWSER_OUTPUT: RSS: Main: 434327552 10:36:02 INFO - 2016-02-03 10:36:02,406 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,406 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:36:02 INFO - 2016-02-03 10:36:02,406 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:36:02 INFO - 2016-02-03 10:36:02,406 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:36:02 INFO - 2016-02-03 10:36:02,406 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |0;Richards;171.67381974248926 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.74464579901152 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5148.717948717948 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |4;RayTrace;957.8544061302682 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |5;Earley;540.8328826392644 10:36:02 INFO - 2016-02-03 10:36:02,407 DEBUG : BROWSER_OUTPUT: |6;Boyer;10610.526315789473 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: |7;RegExp;24536.585365853658 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: |8;Splay;586.1664712778429 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: __startTimestamp1454524562403__endTimestamp 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,408 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.67 Median:171.67 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: Values: 171.7 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.74 Median:164.74 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: Values: 164.7 10:36:02 INFO - 2016-02-03 10:36:02,409 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: Values: 278.4 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5148.72 Median:5148.72 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: Values: 5148.7 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,410 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:957.85 Median:957.85 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: Values: 957.9 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:540.83 Median:540.83 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: Values: 540.8 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10610.53 Median:10610.53 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: Values: 10610.5 10:36:02 INFO - 2016-02-03 10:36:02,411 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24536.59 Median:24536.59 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: Values: 24536.6 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:586.17 Median:586.17 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: Values: 586.2 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: 10:36:02 INFO - 2016-02-03 10:36:02,412 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 10:36:02 INFO - 2016-02-03 10:36:02,413 DEBUG : BROWSER_OUTPUT: Values: 4822.1 10:36:02 INFO - 2016-02-03 10:36:02,413 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:36:02 INFO - 2016-02-03 10:36:02,413 DEBUG : BROWSER_OUTPUT: 10:36:03 INFO - 2016-02-03 10:36:03,120 INFO : Browser exited with error code: 0 10:36:03 INFO - 2016-02-03 10:36:03,136 INFO : Completed test v8_7 (00:00:38) 10:36:03 INFO - 2016-02-03 10:36:03,467 INFO : Completed test suite (00:20:06) 10:36:03 INFO - 2016-02-03 10:36:03,467 DEBUG : Working with test: dromaeo_css 10:36:03 INFO - 2016-02-03 10:36:03,467 DEBUG : Generating results file: dromaeo_css 10:36:03 INFO - 2016-02-03 10:36:03,468 DEBUG : Working with test: kraken 10:36:03 INFO - 2016-02-03 10:36:03,468 DEBUG : Generating results file: kraken 10:36:03 INFO - 2016-02-03 10:36:03,469 INFO : javascript benchmark 10:36:03 INFO - 2016-02-03 10:36:03,469 DEBUG : Working with test: v8_7 10:36:03 INFO - 2016-02-03 10:36:03,469 DEBUG : Generating results file: v8_7 10:36:03 INFO - 2016-02-03 10:36:03,469 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:36:03 INFO - 2016-02-03 10:36:03,613 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:36:03 INFO - 2016-02-03 10:36:03,613 DEBUG : process_Request line: dromaeo_css 7411.19 graph.html#tests=[[72,53,35]] 10:36:03 INFO - 2016-02-03 10:36:03,613 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:36:03 INFO - 2016-02-03 10:36:03,654 DEBUG : process_Request line: kraken 1471.00 graph.html#tests=[[232,53,35]] 10:36:03 INFO - 2016-02-03 10:36:03,654 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:36:03 INFO - 2016-02-03 10:36:03,685 DEBUG : process_Request line: v8_7 22935.92 graph.html#tests=[[230,53,35]] 10:36:03 INFO - 2016-02-03 10:36:03,686 INFO : javascript benchmark 10:36:03 INFO - 2016-02-03 10:36:03,690 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3296.0606227225244, 3321.7674762766615, 3325.401427743005, 3349.66503349665, 3356.580427446569, 5435.130379139338, 5515.200295957084, 5530.640508366963, 5549.750261238239, 5560.165975103737, 5521.806736764214, 5539.556835453164, 5573.749181286842, 5579.358373787016, 5589.301337332833, 4946.084974279752, 5031.496850314967, 5073.3404657394485, 5151.253068305094, 5154.716490593016, 5081.237814327862, 5094.363204599425, 5125.897482050356, 5129.6409251352425, 5139.974300128494, 5004.674696144757, 5008.12357837377, 5094.261332106842, 5111.258867464216, 5145.0481660892665, 5076.46697096805, 5105.489451054902, 5106.617003724717, 5108.897822043576, 5141.0, 5023.447420783711, 5080.237964305346, 5133.0, 5143.125668636303, 5151.0, 5224.764885580158, 5232.0, 5322.520973112439, 5329.840104796862, 5339.5461385782255, 5987.9101813472835, 6017.69911504423, 6030.9396906030815, 6033.09503574463, 6033.788817391414, 5755.251817263771, 6074.088886667036, 6078.756849726005, 6080.787172448943, 6086.239220097488, 5432.348118225838, 5490.450954904558, 5506.476884695948, 5533.557315414757, 5539.8892022159735, 6084.452399284086, 6095.299040610298, 6105.755769769248, 6110.0, 6119.2350956130485, 5728.713564321767, 5848.444397782204, 5917.704114794243, 5917.792877249276, 5920.763169473173, 5975.940240597625, 6060.575759696779, 6064.545159112996, 6073.51411887048, 6116.3577824328695, 5931.169636250928, 5972.58191926561, 5972.611780234271, 5979.252593425822, 5991.55063370249, 5783.624064435799, 5889.705514724246, 5921.851953701192, 5967.0, 5983.730732117066, 4592.265237562041, 4600.769961501912, 4648.3259927310355, 4648.7443190624845, 4672.883177920511, 4176.0395109124465, 4178.686598505064, 4179.728317659343, 4201.936970945377, 4215.178040282116, 5472.398036216013, 5597.720113994284, 5602.775888964477, 5720.799772007959, 5790.565707571949, 6059.0608455689435, 6070.908936365958, 6071.089336599545, 6080.0575910733905, 6103.298120716175, 5690.573207009491, 5691.601587888891, 5692.203091567184, 5693.9430605693415, 5719.628224165416, 5036.395632524121, 5150.690958542499, 5227.320448341768, 5229.790808367623, 5231.110711179109, 6076.483498902554, 6197.5351848611535, 6203.2556093268195, 6203.4416902479, 6209.068639704081, 6070.2412198475195, 6090.23872015998, 6091.695415229132, 6095.75616975316, 6102.206713127265, 5993.550483713826, 6100.938990610037, 6151.046587778901, 6265.96867015662, 6290.402411770874, 5957.6127551709, 5973.1338955852025, 6001.7299221536005, 6006.759729610767, 6009.489193418608, 6050.515958723204, 6062.878742425215, 6068.817935461943, 6073.331933487401, 6073.817785466443, 5658.943410565842, 5756.366799652119, 5852.912206316823, 5899.528037757055, 6004.0, 5914.792982245601, 5928.555358348055, 5934.050551911673, 5935.258092738408, 5936.614120082181, 5828.53371730269, 5831.8833623326445, 5837.09525023622, 5841.824745257649, 5843.970780146072, 5652.236948012131, 5682.659040457585, 5786.074228123648, 5962.731677074629, 5971.880562388815, 5793.565482588907, 5814.592978491465, 5821.76712931478, 5825.038868586806, 5825.737841797045], "unit": "ms", "name": "dojo.html", "value": 5527.391535645966}, {"lowerIsBetter": false, "replicates": [583.8073435766195, 629.2983323594192, 632.162384840087, 635.9491240700744, 636.7071147272256, 33031.66968330316, 41002.53984760913, 41638.37542436858, 41748.0, 41794.79102604486, 45975.310370344385, 46421.30368044482, 46456.535434645644, 46462.53537464624, 46495.76752116243, 7946.562939038351, 8123.106458289583, 8136.186381361846, 9056.09439056094, 9220.815583688322, 10737.570497180104, 11107.389093599848, 11113.277636953571, 11113.555457781678, 11118.555257789678, 10726.570937162503, 10843.728906777314, 10856.240063195579, 10856.511456984415, 10861.294015888981, 10679.199060070527, 10780.137588992862, 10785.245032847704, 10793.784124317548, 10796.352218866894, 10385.740356491073, 10427.530761115691, 10507.684769456928, 10508.106810921081, 10518.947405263001, 43043.78478107621, 43175.78412107951, 43304.3504347435, 43441.78279108616, 43478.60867652278, 3669.743117981742, 3742.4573436851506, 3790.0, 3792.715546334036, 4053.8783836484954, 33123.0, 33316.0, 33415.331693365995, 33442.33115337704, 33792.49311260333, 4099.3031184698675, 4155.750654960712, 4231.014805060056, 4265.0, 4270.359446083113, 294047.0, 294231.5288423566, 294339.0, 294489.0, 294546.99999999785, 56091.0, 57152.714236428554, 63299.36700633027, 71268.64365678243, 72494.0, 64501.354986450475, 64527.67736161383, 64716.0, 64748.35251647518, 64842.67578662076, 16907.83092169087, 17110.315587376615, 17157.227924743416, 17362.65274694499, 17364.39224627132, 12443.128980971425, 12501.749965000648, 12515.687107822196, 12540.81188782169, 12633.810492842615, 21269.0, 21293.78706212949, 21333.46666333323, 21341.0, 21610.45973850635, 7781.338586220121, 7863.33161681252, 7891.684332626631, 7935.801604959806, 8005.759827205193, 1030.2015937648382, 1126.2172789910933, 1132.224426268009, 1139.9373034483017, 1185.857697076339, 26686.866565667435, 26749.598756018677, 27714.5842812358, 28257.293567660974, 28468.85765571159, 1093.1418836213534, 1103.696483467053, 1126.9887301127048, 1160.3270103340042, 1181.0374544746005, 13123.0, 14374.35315410809, 14794.852051479347, 14969.700605987819, 15319.770203446957, 345665.0, 352796.0, 353016.0, 354024.0, 354082.0, 58955.11567326493, 59128.408715912294, 59256.70371648114, 59341.70329148327, 59348.0, 1317.8484474285387, 1326.2440408966988, 1342.8187194728587, 1402.656349194454, 1439.0574173916102, 27543.586846196922, 27705.307367315978, 27845.721542785122, 28404.0, 30045.248868778457, 45643.77178114088, 45876.31185532286, 46163.538364615924, 46203.0, 46224.30663539982, 234413.0, 234940.0, 235779.0, 235871.0, 235905.0, 35009.64990350064, 35364.29271414506, 36012.45981310335, 36154.0, 36165.09587260339, 47137.76431117822, 47535.0, 47739.76130119466, 47979.760101199274, 48956.2656560159, 39708.55374787926, 40316.596834032454, 40829.38755918604, 40852.0, 41278.0, 39920.2015959685, 40257.39613905735, 40621.87453582309, 40879.1824163521, 41151.176976459705, 39337.60662393454, 40343.79828100841, 40587.1882562353, 40634.0, 40688.79655601703, 31530.527042093927, 32353.514697280032, 32762.0, 32801.67198327986, 33342.49986250257, 27256.591151133147, 27350.179494615193, 27364.0, 27415.17754467369, 27474.0], "unit": "ms", "name": "ext.html", "value": 19935.022691079375}, {"lowerIsBetter": false, "replicates": [395.30031843636766, 396.6152831753199, 397.11047254150714, 398.6033896273208, 401.8934982229708, 15450.995685280448, 15861.3655453782, 18274.269029238814, 18716.4385068448, 18733.84948592199, 13645.454181832714, 16796.32814687414, 16865.07242101687, 17158.31366745332, 17185.140742962863, 5506.394296627368, 5567.582431317647, 5652.830415087554, 5669.886602267952, 5672.971635141839, 5604.579656525777, 5658.179563963223, 5675.091985282335, 5677.261955945722, 5679.148127780824, 5385.81149659762, 5492.890142197154, 5562.943040822251, 5565.582581306418, 5617.0, 5399.519487719967, 5435.483629055253, 5508.476694713996, 6137.140800287963, 6166.660833654148, 5557.944420555783, 5561.165825126223, 5561.221428999943, 5562.082256427685, 5564.749586268627, 12469.563565275263, 19592.80407195924, 21537.353879383645, 21591.2443064492, 21939.903004849693, 2297.9195728149434, 2353.3881190890493, 2452.1540068676277, 2482.5283196192845, 2502.737212592688, 9891.604335826558, 10394.428306443142, 10468.633597824115, 11443.313401195956, 14350.569482915529, 1938.37002974034, 1941.2332128809182, 1945.7762357328947, 1953.980460195394, 2009.6884982827685, 62897.371026289606, 63711.0, 64621.67689161524, 65148.0, 65175.3482465174, 10832.512536935857, 10979.341239525655, 11072.003519683107, 11076.778464430667, 11091.279066860629, 13342.0, 13644.93177534106, 13787.310634468437, 14801.851981480047, 14847.183404912834, 7599.164091949881, 7744.574048427278, 7780.84438311242, 7783.766487005399, 7794.454388192772, 6950.443964482931, 7053.435725141976, 7054.0, 7073.893891591631, 7119.1457025157315, 4612.354270402079, 4674.415698037746, 4674.7896344663895, 4696.365990591226, 4704.1062198182235, 1892.2809332453855, 1925.8555608329432, 1966.822985931273, 1971.5662554238045, 1998.060911371653, 475.82870166739946, 487.381026096855, 959.6065613098596, 1045.414567842011, 1055.3984228989557, 11438.828417573743, 11796.410179490991, 11830.349330786717, 11977.580784672495, 11989.460474278678, 950.7147855643418, 962.6005207838808, 975.4781192062212, 976.5312649928075, 998.2313618513849, 7785.1436342002335, 8478.872816907751, 9000.234980026771, 9380.95309523448, 9387.249020078378, 568574.0, 606848.0, 627795.0, 629513.0, 633391.0, 77631.0, 81276.0, 81575.59212203902, 83909.58045209735, 85545.14454855371, 1178.8762179971152, 1202.7654607351658, 1207.8671346151916, 1230.6861750253806, 1243.9675069692134, 14453.277336133362, 14537.273136343352, 15507.457238996582, 16015.199240038184, 16397.09815960086, 36652.26695466023, 37397.439038413904, 37454.25091498209, 37669.62330376661, 37717.245655087296, 43996.0, 45591.31613025741, 46276.53723462722, 46470.767646161556, 47680.7615961918, 10223.079922806983, 10626.0, 10664.200184986003, 10673.199510036624, 10778.08386287143, 12281.078919081283, 12404.069694772748, 14693.779593305895, 18163.909180454015, 18389.90805045966, 14632.634184145481, 14753.114813111248, 14816.629584260048, 15044.097354158786, 15152.924235378752, 14729.410823567367, 14835.629109272355, 15050.172240526876, 15364.308606112976, 15449.150296733787, 15648.217589120271, 15670.294836732146, 15952.202389880691, 16121.596960076093, 16161.353545858034, 10231.51643011774, 10455.895441045492, 10611.840822387825, 10614.1508679307, 10638.73403164927, 11079.751230111953, 14981.625459363604, 15241.847581524486, 15508.68982620364, 15710.371585136923], "unit": "ms", "name": "jquery.html", "value": 9129.922586904178}, {"lowerIsBetter": false, "replicates": [1589.9920500397495, 1602.5192442267316, 1795.434438151983, 1810.7555480010196, 1842.2446796813306, 7905.288524032836, 8318.459300145487, 8319.792005199874, 8321.5007099574, 8330.291925186359, 6968.895466568006, 7092.255313192118, 7831.569263690509, 7999.880001799977, 8176.386770992184, 2296.322584837477, 2304.781045800646, 2322.7328857181387, 2327.6857624220684, 2329.1964272326104, 2048.7439070116234, 2342.2387723989686, 2364.0543782487057, 2398.9160379386726, 2406.193925035115, 2056.557840064386, 2313.826463015272, 2350.7296660884044, 2352.9176478823247, 2355.611324131516, 2325.1513197682807, 2327.127327252289, 2331.114176612893, 2376.726676432198, 2376.809855211596, 2365.6924599802087, 2365.8580485170946, 2404.579198640231, 2405.3866264102544, 2407.0853075831246, 8239.423240373177, 8281.54451505167, 8315.8336833263, 8318.70884519039, 8331.250187483156, 4817.783199755984, 4907.067657145167, 4908.386451693538, 4917.680350777188, 4918.065567542156, 3124.703153200442, 3126.6873312668777, 3158.3999040182293, 3324.036029551427, 3349.547811045502, 1109.295597295715, 2060.8248298894614, 2111.556573119631, 2119.2476670781803, 2158.287765037534, 11276.32342059479, 11339.14956378275, 11355.602553910574, 11384.0, 11400.771984560346, 4266.570698815932, 4281.079567892918, 4309.439772829513, 4377.430933978562, 4389.912201755947, 9379.671711490066, 9429.434233945985, 9448.291378146667, 9455.385399949118, 9455.669051583161, 4916.655834091579, 4917.0903382874285, 4938.2839488274, 4942.357493525818, 4950.727709975986, 4527.705699129546, 4577.198990176706, 4586.564276393738, 4592.127495775792, 4597.885052873705, 3600.1899572595958, 3624.0939765058733, 3635.4183330667497, 3667.339878821784, 3669.2478042001326, 1004.6433516101679, 1023.3859684189426, 1041.4532370505544, 1067.685032915292, 1080.4597701149426, 1206.4751832952693, 1233.2292317301687, 1237.2514628649717, 1473.1676602719645, 1474.9041312314664, 7568.5458872467825, 7672.271134242238, 7985.720499782596, 8006.359491240687, 8016.559089250147, 685.1675214614168, 686.0772261308532, 689.3244620272161, 695.6104581434413, 827.8368891707161, 6299.212598425197, 6344.873102538016, 6368.681565921685, 6369.331220221903, 6370.554061215763, 9555.569999350046, 9633.662821801203, 9917.603295868084, 9921.40471571708, 9925.15636170919, 9888.802223955481, 10002.0, 10102.0, 10104.29269951111, 10134.898651013544, 7405.1854296027395, 7568.281013303727, 7582.431317651198, 7587.848243035219, 7588.468807183444, 7604.391648668204, 7898.960505197438, 7962.562059086574, 7966.28303452692, 7967.960160199162, 8792.076831932554, 8834.602442889956, 8933.508657023665, 9025.864612030693, 9110.453372797654, 8936.374453788432, 9054.728358149267, 9250.537473126182, 9257.166854983085, 9415.293852960918, 7216.855662886818, 7295.562266263829, 7304.306090921354, 7304.671289791866, 7311.305425984523, 7554.584497852671, 7834.099078606033, 7860.882086768589, 8035.678572857254, 8110.391720620859, 6456.225252969486, 7154.749583764543, 7482.289182527651, 7599.392048636207, 7653.617319133909, 7216.855662886818, 7309.5248808827255, 7314.122305323395, 7380.852382952204, 7535.73624923147, 7476.476646634901, 7584.848303033798, 7622.085349758065, 7630.389568834591, 7638.579878106758, 7004.859902802017, 7056.54132481387, 7057.964710176417, 7059.788206353817, 7067.964660176666, 6785.219699734396, 6877.828054298682, 6931.0, 6947.722091116501, 6948.33990770876], "unit": "ms", "name": "mootools.html", "value": 4725.445588329879}, {"lowerIsBetter": false, "replicates": [367.42497990644637, 370.5460810507129, 372.26477706529624, 373.50137566348917, 378.34546235013426, 6056.63660180389, 6060.3939606039485, 6081.057436097401, 6085.4218849209365, 6196.628202307865, 5966.79116230932, 6076.2708474983065, 6098.298695650002, 6111.786087486938, 6118.265808103034, 4789.880252993668, 4798.616110711135, 4800.543948324921, 4801.591864691488, 4810.25441056635, 4609.769511524427, 4684.648651351145, 4732.2428411454175, 4736.9526304736855, 4809.398825146856, 4748.192807222763, 4772.976135119319, 4775.832845850396, 4779.16364636189, 4785.952140478603, 4756.334113224151, 4757.26262429324, 4760.166970780134, 4763.404574428179, 4769.95230047702, 4243.045314804175, 4247.554006829269, 4250.936235956463, 4252.362145678142, 4260.062786187033, 5716.685582292972, 5814.215080964058, 5824.504917082053, 5881.176635271066, 5906.940930590682, 5548.472895074961, 5550.361708403545, 5559.610827242094, 5559.722013899289, 5560.77756889724, 6260.686965651699, 6395.456386207132, 6553.475721942234, 6568.507361947825, 6572.572782769104, 4674.182018146811, 4721.244600863845, 4721.480637129913, 4737.597304229144, 4749.4775574686755, 6596.868062638769, 6685.164354455693, 6688.498362622823, 6744.426723728489, 6756.966215168942, 4870.318155458239, 4875.975620121948, 4881.780319885543, 4882.902341953212, 4887.73117478535, 4770.594499467584, 4910.607151427877, 4918.48355922623, 4921.680090794087, 4939.407271127488, 5581.6930068846605, 5592.245046918614, 5594.0, 5598.3282006159525, 5600.831975040755, 6568.441682457044, 6569.211694596679, 6610.3720146586, 6610.504212184105, 6611.933880661132, 1693.644334689729, 1725.2322716391086, 1746.519707080563, 1750.5098572399747, 1763.365188532153, 1184.5735535207318, 1197.0304053716518, 1204.722913729847, 1218.622227109599, 1220.1641875315438, 6315.936840631627, 6323.241211054611, 6337.493000559944, 6340.175777148941, 6346.365363463709, 6717.798466046026, 6729.798106056826, 6735.360140786617, 6737.157855268091, 6740.460763139035, 6194.0708893666315, 6331.923572992602, 6332.68336583169, 6335.841603959938, 6339.556231063873, 3610.891673249807, 3630.655087766711, 3650.470681751131, 3653.4702468141973, 3654.543182102237, 6849.863002740017, 6854.862902742017, 6857.382835544725, 6860.245373008965, 6862.245153033163, 6735.764248251287, 6882.139732533434, 6889.517733758689, 6897.724091036403, 6900.7929762107215, 6929.1684997800585, 6944.479164062715, 6951.89572156418, 6954.3045695430055, 6961.93038069623, 6616.735330586723, 6678.49911256648, 6739.494537909578, 6838.692258848463, 6840.623765692736, 6732.259451460433, 6752.628605426748, 6753.966230168818, 6758.121444212221, 6759.526833121634, 6873.072135261676, 7043.0, 7047.929520704933, 7050.224475307815, 7055.0, 4652.13935421948, 4679.368285281444, 4684.110019096361, 4685.0, 4686.601638860735, 6830.795076147723, 6831.897521537082, 6835.521513494008, 6840.863182736218, 6844.5208835381045, 6459.483241340776, 6646.368594983469, 6652.567583107082, 6659.833504162435, 6660.367265109807, 6609.900851487328, 6618.238902526078, 6667.633280169637, 6675.132232809898, 6675.766348177791, 6508.446782023391, 6540.117084193573, 6545.803625891231, 6556.8360790980605, 6565.409113179837, 6526.967365163334, 6648.16897887764, 6648.7340506379205, 6656.0, 6717.16035495563, 6546.901796473152, 6547.312532184243, 6556.704948277435, 6558.573692709959, 6559.868802623825], "unit": "ms", "name": "prototype.html", "value": 5004.965970042387}, {"lowerIsBetter": false, "replicates": [828.8135169586843, 852.9744107676768, 853.8761879527468, 903.5346796399854, 968.9777284964359, 14293.356798944047, 15931.840681593181, 15932.601684957854, 15956.680866382665, 15958.28187731552, 14868.553943381688, 14882.553523394288, 14901.850981490183, 14929.92535037326, 14988.325525351358, 742.5062333548184, 754.9962250188742, 901.6934242357597, 901.7069452427954, 916.2166347772659, 891.5809569502323, 897.8473659477904, 902.5216635183364, 903.9276857851389, 904.900460949295, 778.4395235430482, 918.2516249256868, 930.1396208507103, 933.4772527384687, 933.63121566981, 775.5966897213474, 937.9671711490099, 954.2366107114316, 955.5126885288483, 956.4548207521715, 698.4203111417511, 704.4540481127116, 705.0939542687622, 710.314546462666, 710.8329542557495, 14323.212223327713, 14700.411983520646, 14702.411903523953, 14719.558413247512, 14750.262486875614, 13206.207627542282, 13361.66595835112, 13505.12216705911, 13924.58226253214, 14154.92922535391, 11573.594924177612, 11586.246893951866, 11650.475728592233, 11669.533218671242, 11855.703607409798, 3962.5641179470235, 4010.4786377770993, 4013.759174449513, 4049.5545489996075, 4072.429859819628, 52536.73731631317, 52664.21003684948, 52925.2061219082, 53019.20471192935, 53115.734421327645, 10955.630546181726, 11119.166062545342, 11281.94359028216, 11371.147163962736, 11616.186866919403, 19238.422847314603, 19275.132619032178, 19290.22839086421, 19297.807021929882, 19303.0, 8161.836763264701, 8245.505269683837, 8312.25189732927, 8327.125651806595, 8434.536100514531, 4955.405351357788, 5034.169362055293, 5036.420811606712, 5055.0, 5094.847154585368, 3301.273719781644, 3324.7672662913847, 3328.384248913959, 3328.73370130389, 3330.2506935939705, 623.5043140703152, 628.2210059526246, 636.1126228910643, 636.1634450697318, 640.6444423345088, 617.5337620096798, 642.1972534332085, 648.2221334398646, 667.3493343989668, 765.74730338988, 8817.779555511164, 9370.39092458988, 9537.133264049873, 9538.570764315622, 9602.135807777333, 690.3062422265591, 696.4498046543275, 718.9101362329709, 773.2460850670551, 812.6749300279936, 4318.719283246579, 4500.617447516997, 4575.450945886515, 4605.0559635274685, 4733.124371991193, 61283.387166127766, 63745.68127159335, 64310.67844660747, 64668.0, 64764.676176618814, 53963.73018134963, 55027.724861376235, 55326.17010744842, 55430.16854747182, 55600.44399555953, 48444.27333589999, 48762.5123748765, 54327.45672543224, 54497.72751136219, 56011.999999999185, 9626.085521875411, 9691.370060946016, 9771.804563908825, 9921.900780992386, 9979.9541094077, 37954.81022594869, 38875.80562097171, 39239.803800980815, 39262.4110638335, 39308.23112959981, 44316.33525497056, 44352.0, 48666.5133348662, 50180.498195017586, 51172.0, 12068.577599784317, 12142.271463712206, 12199.51201951912, 12284.877151228373, 12512.562060327844, 13606.931965340505, 13673.453061877413, 13678.110922789987, 13771.655708607364, 13791.862081379059, 12396.194247373893, 12764.29796361209, 12905.09664323526, 12946.158499697489, 12976.935115324362, 11995.220310679779, 12062.336571488653, 12349.3825308736, 12600.306983116017, 12652.936735316265, 9877.50612469388, 10997.670069897917, 11043.779124417628, 12531.122821402414, 12806.35968201605, 10454.372737635766, 10864.782704346027, 11299.661010169708, 11329.773404531697, 11429.28535732134, 10486.0, 10802.67591972242, 10819.729506762395, 10873.673789786319, 10886.782264354826], "unit": "ms", "name": "yui.html", "value": 6963.740185348303}], "name": "dromaeo_css", "value": 7411.190539096436}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 103.0, 107.0, 109.0, 108.0, 98.0, 107.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 106.8}, {"lowerIsBetter": true, "replicates": [148.0, 122.0, 157.0, 155.0, 131.0, 151.0, 151.0, 121.0, 163.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 158.0, 160.0, 160.0, 159.0, 161.0, 163.0, 161.0, 157.0], "unit": "ms", "name": "audio-dft", "value": 159.8}, {"lowerIsBetter": true, "replicates": [70.0, 73.0, 70.0, 149.0, 71.0, 78.0, 71.0, 71.0, 151.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.6}, {"lowerIsBetter": true, "replicates": [102.0, 92.0, 138.0, 93.0, 93.0, 93.0, 141.0, 92.0, 92.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 107.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 148.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 94.9}, {"lowerIsBetter": true, "replicates": [57.0, 54.0, 56.0, 55.0, 55.0, 54.0, 58.0, 55.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.2}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [80.0, 79.0, 76.0, 79.0, 77.0, 83.0, 78.0, 79.0, 80.0, 86.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.7}, {"lowerIsBetter": true, "replicates": [116.0, 109.0, 112.0, 114.0, 111.0, 112.0, 123.0, 114.0, 117.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.8}, {"lowerIsBetter": true, "replicates": [137.0, 154.0, 140.0, 140.0, 143.0, 134.0, 146.0, 143.0, 140.0, 131.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 140.8}, {"lowerIsBetter": true, "replicates": [64.0, 66.0, 69.0, 63.0, 64.0, 63.0, 64.0, 62.0, 65.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.3}], "name": "kraken", "value": 1471.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.67381974248926], "unit": "score", "name": "Richards", "value": 205.63415000000006}, {"lowerIsBetter": false, "replicates": [164.74464579901152], "unit": "score", "name": "DeltaBlue", "value": 401.33626}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5148.717948717948], "unit": "score", "name": "Decrypt", "value": 51.69850099601596}, {"lowerIsBetter": false, "replicates": [957.8544061302682], "unit": "score", "name": "RayTrace", "value": 772.5485159999996}, {"lowerIsBetter": false, "replicates": [540.8328826392644], "unit": "score", "name": "Earley", "value": 1232.290087}, {"lowerIsBetter": false, "replicates": [10610.526315789473], "unit": "score", "name": "Boyer", "value": 62.81149305555559}, {"lowerIsBetter": false, "replicates": [24536.585365853658], "unit": "score", "name": "RegExp", "value": 37.12761928429422}, {"lowerIsBetter": false, "replicates": [586.1664712778429], "unit": "score", "name": "Splay", "value": 139.02364600000004}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 22935.91614887687}]} 10:36:03 INFO - RETURN: dromaeo_css: 7411.19 10:36:03 INFO - RETURN: kraken: 1471.00 10:36:03 INFO - RETURN: v8_7: 22935.92 10:36:03 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "22935.92"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1471.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7411.19"}}} 10:36:03 INFO - Return code: 0 10:36:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:36:03 INFO - # TBPL SUCCESS # 10:36:03 INFO - Running post-action listener: _resource_record_post_action 10:36:03 INFO - Running post-run listener: _resource_record_post_run 10:36:04 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 126976; Write bytes: 264245248; Read time: 156; Write time: 125608 10:36:04 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 651264; Read time: 0; Write time: 40 10:36:04 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 126976; Write bytes: 262995968; Read time: 156; Write time: 125568 10:36:04 INFO - Running post-run listener: _upload_blobber_files 10:36:04 INFO - Blob upload gear active. 10:36:04 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:36:04 INFO - Copying logs to upload dir... 10:36:04 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.511931 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-03 10:36:04.909692) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-03 10:36:04.913601) ========= 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009931 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454514161/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-03 10:36:04.966399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:36:04.966751) ========= 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=82e07478c745e68b1fe4678a000001bc-1454523305.524886-378872296 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004596 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 10:36:05.016752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-03 10:36:05.017082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-03 10:36:05.017388) ========= ========= Total master_lag: 0.24 =========