builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1455850210.98 results: success (0) buildid: 20160218164328 builduid: 5cbc454baad34b6eaa87141c760ddc91 revision: 492ff9631188 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:10.984275) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:10.984740) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:10.985063) ========= 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006360 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.022164) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.022481) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.039615) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.039923) ========= 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004604 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.090496) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.090926) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.091390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.091726) ========= 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-18 18:50:11-- 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.3M=0s 2016-02-18 18:50:12 (83.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167198 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.271888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.272255) ========= 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014365 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.322321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.322672) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 492ff9631188 --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 492ff9631188 --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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-18 18:50:12,199 Setting DEBUG logging. 2016-02-18 18:50:12,199 attempt 1/10 2016-02-18 18:50:12,200 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/492ff9631188?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-18 18:50:12,462 unpacking tar archive at: mozilla-beta-492ff9631188/testing/mozharness/ program finished with exit code 0 elapsedTime=0.519783 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.857708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.858468) ========= echo 492ff9631188 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'492ff9631188'] 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 492ff9631188 program finished with exit code 0 elapsedTime=0.004811 script_repo_revision: '492ff9631188' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.915800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.916115) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-18 18:50:11.930319) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-02-18 18:50:11.930615) ========= /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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:50:12 INFO - MultiFileLogger online at 20160218 18:50:12 in /builds/slave/test-pgo 18:50:12 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 18:50:12 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:50:12 INFO - {'append_to_log': False, 18:50:12 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:50:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:50:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:50:12 INFO - 'branch': 'Mozilla-Beta', 18:50:12 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:12 INFO - 'config_files': ('talos/linux_config.py',), 18:50:12 INFO - 'default_actions': ('clobber', 18:50:12 INFO - 'read-buildbot-config', 18:50:12 INFO - 'download-and-extract', 18:50:12 INFO - 'populate-webroot', 18:50:12 INFO - 'create-virtualenv', 18:50:12 INFO - 'install', 18:50:12 INFO - 'run-tests'), 18:50:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:12 INFO - 'download_minidump_stackwalk': True, 18:50:12 INFO - 'download_symbols': 'ondemand', 18:50:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:12 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:12 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:12 INFO - 'installer_path': 'installer.exe', 18:50:12 INFO - 'log_level': 'info', 18:50:12 INFO - 'log_name': 'talos', 18:50:12 INFO - 'log_to_console': True, 18:50:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:50:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:50:12 INFO - 'opt_config_files': (), 18:50:12 INFO - 'pip_index': False, 18:50:12 INFO - 'sps_profile': False, 18:50:12 INFO - 'sps_profile_interval': 0, 18:50:12 INFO - 'suite': 'dromaeojs', 18:50:12 INFO - 'system_bits': '32', 18:50:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:12 INFO - 'title': 'talos-linux64-ix-029', 18:50:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:12 INFO - 'use_talos_json': True, 18:50:12 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:50:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:12 INFO - 'work_dir': 'build'} 18:50:12 INFO - ##### 18:50:12 INFO - ##### Running clobber step. 18:50:12 INFO - ##### 18:50:12 INFO - Running pre-action listener: _resource_record_pre_action 18:50:12 INFO - Running main action method: clobber 18:50:12 INFO - rmtree: /builds/slave/test-pgo/build 18:50:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:50:13 INFO - Running post-action listener: _resource_record_post_action 18:50:13 INFO - ##### 18:50:13 INFO - ##### Running read-buildbot-config step. 18:50:13 INFO - ##### 18:50:13 INFO - Running pre-action listener: _resource_record_pre_action 18:50:13 INFO - Running main action method: read_buildbot_config 18:50:13 INFO - Using buildbot properties: 18:50:13 INFO - { 18:50:13 INFO - "properties": { 18:50:13 INFO - "buildnumber": 90, 18:50:13 INFO - "product": "firefox", 18:50:13 INFO - "script_repo_revision": "production", 18:50:13 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:50:13 INFO - "repository": "", 18:50:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:50:13 INFO - "buildid": "20160218164328", 18:50:13 INFO - "slavename": "talos-linux64-ix-029", 18:50:13 INFO - "pgo_build": "True", 18:50:13 INFO - "basedir": "/builds/slave/test-pgo", 18:50:13 INFO - "project": "", 18:50:13 INFO - "platform": "ubuntu64_hw", 18:50:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:50:13 INFO - "slavebuilddir": "test", 18:50:13 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:50:13 INFO - "branch": "mozilla-beta", 18:50:13 INFO - "repo_path": "releases/mozilla-beta", 18:50:13 INFO - "stage_platform": "linux64-pgo", 18:50:13 INFO - "builduid": "5cbc454baad34b6eaa87141c760ddc91", 18:50:13 INFO - "revision": "492ff9631188" 18:50:13 INFO - }, 18:50:13 INFO - "sourcestamp": { 18:50:13 INFO - "repository": "", 18:50:13 INFO - "hasPatch": false, 18:50:13 INFO - "project": "", 18:50:13 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:50:13 INFO - "changes": [ 18:50:13 INFO - { 18:50:13 INFO - "category": null, 18:50:13 INFO - "files": [ 18:50:13 INFO - { 18:50:13 INFO - "url": null, 18:50:13 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.tar.bz2" 18:50:13 INFO - } 18:50:13 INFO - ], 18:50:13 INFO - "repository": "", 18:50:13 INFO - "rev": "492ff9631188", 18:50:13 INFO - "who": "sendchange", 18:50:13 INFO - "when": 1455850189, 18:50:13 INFO - "number": 7625442, 18:50:13 INFO - "comments": "Bug 1227344 Try to ensure script content is always different in update tests by using .clock(). r=jdm a=testonly", 18:50:13 INFO - "project": "", 18:50:13 INFO - "at": "Thu 18 Feb 2016 18:49:49", 18:50:13 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:50:13 INFO - "revlink": "", 18:50:13 INFO - "properties": [ 18:50:13 INFO - [ 18:50:13 INFO - "buildid", 18:50:13 INFO - "20160218164328", 18:50:13 INFO - "Change" 18:50:13 INFO - ], 18:50:13 INFO - [ 18:50:13 INFO - "builduid", 18:50:13 INFO - "5cbc454baad34b6eaa87141c760ddc91", 18:50:13 INFO - "Change" 18:50:13 INFO - ], 18:50:13 INFO - [ 18:50:13 INFO - "pgo_build", 18:50:13 INFO - "True", 18:50:13 INFO - "Change" 18:50:13 INFO - ] 18:50:13 INFO - ], 18:50:13 INFO - "revision": "492ff9631188" 18:50:13 INFO - } 18:50:13 INFO - ], 18:50:13 INFO - "revision": "492ff9631188" 18:50:13 INFO - } 18:50:13 INFO - } 18:50:13 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.tar.bz2. 18:50:13 INFO - Running post-action listener: _resource_record_post_action 18:50:13 INFO - ##### 18:50:13 INFO - ##### Running download-and-extract step. 18:50:13 INFO - ##### 18:50:13 INFO - Running pre-action listener: _resource_record_pre_action 18:50:13 INFO - Running main action method: download_and_extract 18:50:13 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:50:13 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')]} 18:50:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/test_packages.json 18:50:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:50:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:50:13 INFO - Downloaded 1270 bytes. 18:50:13 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:50:13 INFO - Using the following test package requirements: 18:50:13 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 18:50:13 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:50:13 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'jsshell-linux-x86_64.zip'], 18:50:13 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:50:13 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 18:50:13 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 18:50:13 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 18:50:13 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:50:13 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 18:50:13 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 18:50:13 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:50:13 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:50:13 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')]} 18:50:13 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.common.tests.zip 18:50:13 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:15 INFO - Downloaded 22255604 bytes. 18:50:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:50:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 18:50:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:50:17 INFO - Return code: 0 18:50:17 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 18:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:17 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.common.tests.zip 18:50:17 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:17 INFO - Downloaded 22255604 bytes. 18:50:17 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 18:50:17 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 18:50:17 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 18:50:18 INFO - Return code: 0 18:50: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')]} 18:50:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 18:50:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:21 INFO - Downloaded 11132038 bytes. 18:50:21 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 18:50:21 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 18:50:21 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 18:50:22 INFO - Return code: 0 18:50:22 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')]} 18:50:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.tar.bz2 18:50:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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 18:50:32 INFO - Downloaded 58333836 bytes. 18:50:32 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.tar.bz2 18:50:32 INFO - mkdir: /builds/slave/test-pgo/properties 18:50:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:50:32 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:50:32 INFO - Contents: 18:50:32 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.tar.bz2 18:50:32 INFO - Running post-action listener: _resource_record_post_action 18:50:32 INFO - Running post-action listener: set_extra_try_arguments 18:50:32 INFO - ##### 18:50:32 INFO - ##### Running populate-webroot step. 18:50:32 INFO - ##### 18:50:32 INFO - Running pre-action listener: _resource_record_pre_action 18:50:32 INFO - Running main action method: populate_webroot 18:50:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:50:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:50:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:50:32 INFO - '--tppagecycles', 18:50:32 INFO - '7'], 18:50:32 INFO - 'tests': ['tsvgm']}}, 18:50:32 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:50:32 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:50:32 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:50:32 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:50:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'tests': ['damp', 'tps']}, 18:50:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['damp', 'tps']}, 18:50:32 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:50:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:50:32 INFO - 'other': {'tests': ['a11yr', 18:50:32 INFO - 'ts_paint', 18:50:32 INFO - 'tpaint', 18:50:32 INFO - 'sessionrestore', 18:50:32 INFO - 'sessionrestore_no_auto_restore']}, 18:50:32 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['a11yr', 18:50:32 INFO - 'ts_paint', 18:50:32 INFO - 'tpaint', 18:50:32 INFO - 'sessionrestore', 18:50:32 INFO - 'sessionrestore_no_auto_restore']}, 18:50:32 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['a11yr', 18:50:32 INFO - 'ts_paint', 18:50:32 INFO - 'tpaint', 18:50:32 INFO - 'sessionrestore', 18:50:32 INFO - 'sessionrestore_no_auto_restore']}, 18:50:32 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['a11yr', 18:50:32 INFO - 'ts_paint', 18:50:32 INFO - 'tpaint', 18:50:32 INFO - 'sessionrestore', 18:50:32 INFO - 'sessionrestore_no_auto_restore']}, 18:50:32 INFO - 'other_l64': {'tests': ['a11yr', 18:50:32 INFO - 'ts_paint', 18:50:32 INFO - 'tpaint', 18:50:32 INFO - 'sessionrestore', 18:50:32 INFO - 'sessionrestore_no_auto_restore']}, 18:50:32 INFO - 'other_nol64': {'tests': ['a11yr', 18:50:32 INFO - 'ts_paint', 18:50:32 INFO - 'tpaint', 18:50:32 INFO - 'sessionrestore', 18:50:32 INFO - 'sessionrestore_no_auto_restore']}, 18:50:32 INFO - 'svgr': {'tests': ['tsvgx', 18:50:32 INFO - 'tsvgr_opacity', 18:50:32 INFO - 'tart', 18:50:32 INFO - 'tscrollx', 18:50:32 INFO - 'cart']}, 18:50:32 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['tsvgx', 18:50:32 INFO - 'tsvgr_opacity', 18:50:32 INFO - 'tart', 18:50:32 INFO - 'tscrollx', 18:50:32 INFO - 'cart']}, 18:50:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'tests': ['tp5o']}, 18:50:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'talos_options': ['--e10s'], 18:50:32 INFO - 'tests': ['tp5o']}, 18:50:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'talos_options': ['--xperf_path', 18:50:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:32 INFO - 'tests': ['tp5n']}, 18:50:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:32 INFO - 'talos_options': ['--e10s', 18:50:32 INFO - '--xperf_path', 18:50:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:32 INFO - 'tests': ['tp5n']}}, 18:50:32 INFO - 'talos.zip': {'path': '', 18:50:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:50:32 INFO - Running post-action listener: _resource_record_post_action 18:50:32 INFO - ##### 18:50:32 INFO - ##### Running create-virtualenv step. 18:50:32 INFO - ##### 18:50:32 INFO - Running pre-action listener: _resource_record_pre_action 18:50:32 INFO - Running main action method: create_virtualenv 18:50:32 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:50:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:50:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:50:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:50:32 INFO - Using real prefix '/usr' 18:50:32 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:50:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:35 INFO - Installing pip.................done. 18:50:35 INFO - Return code: 0 18:50:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:50: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')]} 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:50:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:50:35 INFO - Using env: {'DISPLAY': ':0', 18:50:35 INFO - 'HOME': '/home/cltbld', 18:50:35 INFO - 'LANG': 'en_US.UTF-8', 18:50:35 INFO - 'LANGUAGE': 'en_US:en', 18:50:35 INFO - 'LOGNAME': 'cltbld', 18:50:35 INFO - 'MAIL': '/var/mail/cltbld', 18:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:35 INFO - 'MOZ_NO_REMOTE': '1', 18:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:35 INFO - 'NO_EM_RESTART': '1', 18:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:35 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:35 INFO - 'SHELL': '/bin/bash', 18:50:35 INFO - 'SHLVL': '1', 18:50:35 INFO - 'TERM': 'linux', 18:50:35 INFO - 'TMOUT': '86400', 18:50:35 INFO - 'USER': 'cltbld', 18:50:35 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:35 INFO - '_': '/tools/buildbot/bin/python'} 18:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:50:35 INFO - Cleaning up... 18:50:35 INFO - Return code: 0 18:50:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:50: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')]} 18:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:50:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:50:35 INFO - Using env: {'DISPLAY': ':0', 18:50:35 INFO - 'HOME': '/home/cltbld', 18:50:35 INFO - 'LANG': 'en_US.UTF-8', 18:50:35 INFO - 'LANGUAGE': 'en_US:en', 18:50:35 INFO - 'LOGNAME': 'cltbld', 18:50:35 INFO - 'MAIL': '/var/mail/cltbld', 18:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:35 INFO - 'MOZ_NO_REMOTE': '1', 18:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:35 INFO - 'NO_EM_RESTART': '1', 18:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:35 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:35 INFO - 'SHELL': '/bin/bash', 18:50:35 INFO - 'SHLVL': '1', 18:50:35 INFO - 'TERM': 'linux', 18:50:35 INFO - 'TMOUT': '86400', 18:50:35 INFO - 'USER': 'cltbld', 18:50:35 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:35 INFO - '_': '/tools/buildbot/bin/python'} 18:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:35 INFO - Downloading/unpacking psutil>=0.7.1 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:37 INFO - Installing collected packages: psutil 18:50:37 INFO - Running setup.py install for psutil 18:50:37 INFO - building 'psutil._psutil_linux' extension 18:50:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:50:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:50:37 INFO - building 'psutil._psutil_posix' extension 18:50:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:50:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:50:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:38 INFO - Successfully installed psutil 18:50:38 INFO - Cleaning up... 18:50:38 INFO - Return code: 0 18:50:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:50: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')]} 18:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50: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')]} 18:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:50:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:50:38 INFO - Using env: {'DISPLAY': ':0', 18:50:38 INFO - 'HOME': '/home/cltbld', 18:50:38 INFO - 'LANG': 'en_US.UTF-8', 18:50:38 INFO - 'LANGUAGE': 'en_US:en', 18:50:38 INFO - 'LOGNAME': 'cltbld', 18:50:38 INFO - 'MAIL': '/var/mail/cltbld', 18:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:38 INFO - 'MOZ_NO_REMOTE': '1', 18:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:38 INFO - 'NO_EM_RESTART': '1', 18:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:38 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:38 INFO - 'SHELL': '/bin/bash', 18:50:38 INFO - 'SHLVL': '1', 18:50:38 INFO - 'TERM': 'linux', 18:50:38 INFO - 'TMOUT': '86400', 18:50:38 INFO - 'USER': 'cltbld', 18:50:38 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:38 INFO - '_': '/tools/buildbot/bin/python'} 18:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:50:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:50:40 INFO - Installing collected packages: mozsystemmonitor 18:50:40 INFO - Running setup.py install for mozsystemmonitor 18:50:40 INFO - Successfully installed mozsystemmonitor 18:50:40 INFO - Cleaning up... 18:50:40 INFO - Return code: 0 18:50:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:40 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:50:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:50:40 INFO - Using env: {'DISPLAY': ':0', 18:50:40 INFO - 'HOME': '/home/cltbld', 18:50:40 INFO - 'LANG': 'en_US.UTF-8', 18:50:40 INFO - 'LANGUAGE': 'en_US:en', 18:50:40 INFO - 'LOGNAME': 'cltbld', 18:50:40 INFO - 'MAIL': '/var/mail/cltbld', 18:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:40 INFO - 'MOZ_NO_REMOTE': '1', 18:50:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:40 INFO - 'NO_EM_RESTART': '1', 18:50:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:40 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:40 INFO - 'SHELL': '/bin/bash', 18:50:40 INFO - 'SHLVL': '1', 18:50:40 INFO - 'TERM': 'linux', 18:50:40 INFO - 'TMOUT': '86400', 18:50:40 INFO - 'USER': 'cltbld', 18:50:40 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:40 INFO - '_': '/tools/buildbot/bin/python'} 18:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:40 INFO - Downloading/unpacking blobuploader==1.2.4 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50: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 18:50:42 INFO - Downloading blobuploader-1.2.4.tar.gz 18:50:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:50:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:50:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:43 INFO - Downloading docopt-0.6.1.tar.gz 18:50:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:50:43 INFO - Installing collected packages: blobuploader, requests, docopt 18:50:43 INFO - Running setup.py install for blobuploader 18:50:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:50:43 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:50:43 INFO - Running setup.py install for requests 18:50:43 INFO - Running setup.py install for docopt 18:50:43 INFO - Successfully installed blobuploader requests docopt 18:50:43 INFO - Cleaning up... 18:50:43 INFO - Return code: 0 18:50:43 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:43 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:50:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:43 INFO - Using env: {'DISPLAY': ':0', 18:50:43 INFO - 'HOME': '/home/cltbld', 18:50:43 INFO - 'LANG': 'en_US.UTF-8', 18:50:43 INFO - 'LANGUAGE': 'en_US:en', 18:50:43 INFO - 'LOGNAME': 'cltbld', 18:50:43 INFO - 'MAIL': '/var/mail/cltbld', 18:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:43 INFO - 'MOZ_NO_REMOTE': '1', 18:50:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:43 INFO - 'NO_EM_RESTART': '1', 18:50:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:43 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:43 INFO - 'SHELL': '/bin/bash', 18:50:43 INFO - 'SHLVL': '1', 18:50:43 INFO - 'TERM': 'linux', 18:50:43 INFO - 'TMOUT': '86400', 18:50:43 INFO - 'USER': 'cltbld', 18:50:43 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:43 INFO - '_': '/tools/buildbot/bin/python'} 18:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:50:43 INFO - Running setup.py (path:/tmp/pip-qcaDCN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:50:44 INFO - Running setup.py (path:/tmp/pip-sa7Gll-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:50:44 INFO - Running setup.py (path:/tmp/pip-MkJoU6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:50:44 INFO - Running setup.py (path:/tmp/pip-tQT53m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:50:44 INFO - Running setup.py (path:/tmp/pip-XAUE3Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:50:44 INFO - Running setup.py (path:/tmp/pip-7UpjRm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:50:44 INFO - Running setup.py (path:/tmp/pip-ScbOLb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:50:44 INFO - Running setup.py (path:/tmp/pip-mYV_yq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:50:44 INFO - Running setup.py (path:/tmp/pip-RSKLNF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:50:44 INFO - Running setup.py (path:/tmp/pip-C4_NPp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:50:44 INFO - Running setup.py (path:/tmp/pip-yR7svo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:50:44 INFO - Running setup.py (path:/tmp/pip-LhBlyS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:50:44 INFO - Running setup.py (path:/tmp/pip-rveRXZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:50:44 INFO - Running setup.py (path:/tmp/pip-D1z_Yh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:50:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:50:44 INFO - Running setup.py (path:/tmp/pip-87daCc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:50:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:50:45 INFO - Running setup.py (path:/tmp/pip-Hz4atC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:50:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:50:45 INFO - Running setup.py (path:/tmp/pip-9Lu4TW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:50:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:50:45 INFO - Running setup.py install for manifestparser 18:50:45 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Running setup.py install for mozcrash 18:50:45 INFO - Running setup.py install for mozdebug 18:50:45 INFO - Running setup.py install for mozdevice 18:50:45 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Running setup.py install for mozfile 18:50:45 INFO - Running setup.py install for mozhttpd 18:50:45 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Running setup.py install for mozinfo 18:50:45 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Running setup.py install for mozInstall 18:50:45 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:50:45 INFO - Running setup.py install for mozleak 18:50:46 INFO - Running setup.py install for mozlog 18:50:46 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:50:46 INFO - Running setup.py install for moznetwork 18:50:46 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:50:46 INFO - Running setup.py install for mozprocess 18:50:46 INFO - Running setup.py install for mozprofile 18:50:46 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:50:46 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:50:46 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:50:46 INFO - Running setup.py install for mozrunner 18:50:46 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:50:46 INFO - Running setup.py install for mozscreenshot 18:50:46 INFO - Running setup.py install for moztest 18:50:46 INFO - Running setup.py install for mozversion 18:50:47 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:50:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:50:47 INFO - Cleaning up... 18:50:47 INFO - Return code: 0 18:50:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:50:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:47 INFO - Using env: {'DISPLAY': ':0', 18:50:47 INFO - 'HOME': '/home/cltbld', 18:50:47 INFO - 'LANG': 'en_US.UTF-8', 18:50:47 INFO - 'LANGUAGE': 'en_US:en', 18:50:47 INFO - 'LOGNAME': 'cltbld', 18:50:47 INFO - 'MAIL': '/var/mail/cltbld', 18:50:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:47 INFO - 'MOZ_NO_REMOTE': '1', 18:50:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:47 INFO - 'NO_EM_RESTART': '1', 18:50:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:47 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:47 INFO - 'SHELL': '/bin/bash', 18:50:47 INFO - 'SHLVL': '1', 18:50:47 INFO - 'TERM': 'linux', 18:50:47 INFO - 'TMOUT': '86400', 18:50:47 INFO - 'USER': 'cltbld', 18:50:47 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:47 INFO - '_': '/tools/buildbot/bin/python'} 18:50:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:50:47 INFO - Running setup.py (path:/tmp/pip-SaWdja-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:50:47 INFO - Running setup.py (path:/tmp/pip-QKhkol-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:50:47 INFO - Running setup.py (path:/tmp/pip-HKIhjh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:50:47 INFO - Running setup.py (path:/tmp/pip-gA6Qoo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:50:47 INFO - Running setup.py (path:/tmp/pip-Hr4Oor-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:50:47 INFO - Running setup.py (path:/tmp/pip-u32QL0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:50:47 INFO - Running setup.py (path:/tmp/pip-rC9Y5A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:50:47 INFO - Running setup.py (path:/tmp/pip-asbfvS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:50:47 INFO - Running setup.py (path:/tmp/pip-bMFQbz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:50:47 INFO - Running setup.py (path:/tmp/pip-mKeMa9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:50:47 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)) 18:50:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:50:48 INFO - Running setup.py (path:/tmp/pip-BilguT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:50:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:50:48 INFO - Running setup.py (path:/tmp/pip-SiR6V9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:50:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:50:48 INFO - Running setup.py (path:/tmp/pip-kEVc_h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:50:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:50:48 INFO - Running setup.py (path:/tmp/pip-kKvPpI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:50:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:50:48 INFO - Running setup.py (path:/tmp/pip-Qrf_gK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:50:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:50:48 INFO - Running setup.py (path:/tmp/pip-v0sRhF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:50:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:50:48 INFO - Running setup.py (path:/tmp/pip-fxLAXD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:50:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:50 INFO - Downloading blessings-1.6.tar.gz 18:50:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:50:50 INFO - Installing collected packages: blessings 18:50:50 INFO - Running setup.py install for blessings 18:50:50 INFO - Successfully installed blessings 18:50:50 INFO - Cleaning up... 18:50:50 INFO - Return code: 0 18:50:50 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:50:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:50:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:50:50 INFO - Reading from file tmpfile_stdout 18:50:50 INFO - Current package versions: 18:50:50 INFO - argparse == 1.2.1 18:50:50 INFO - blessings == 1.6 18:50:50 INFO - blobuploader == 1.2.4 18:50:50 INFO - docopt == 0.6.1 18:50:50 INFO - manifestparser == 1.1 18:50:50 INFO - mozInstall == 1.12 18:50:50 INFO - mozcrash == 0.16 18:50:50 INFO - mozdebug == 0.1 18:50:50 INFO - mozdevice == 0.47 18:50:50 INFO - mozfile == 1.2 18:50:50 INFO - mozhttpd == 0.7 18:50:50 INFO - mozinfo == 0.9 18:50:50 INFO - mozleak == 0.1 18:50:50 INFO - mozlog == 3.1 18:50:50 INFO - moznetwork == 0.27 18:50:50 INFO - mozprocess == 0.22 18:50:50 INFO - mozprofile == 0.27 18:50:50 INFO - mozrunner == 6.11 18:50:50 INFO - mozscreenshot == 0.1 18:50:50 INFO - mozsystemmonitor == 0.0 18:50:50 INFO - moztest == 0.7 18:50:50 INFO - mozversion == 1.4 18:50:50 INFO - psutil == 3.1.1 18:50:50 INFO - requests == 1.2.3 18:50:50 INFO - wsgiref == 0.1.2 18:50:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:50:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:50:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:50:50 INFO - Using env: {'DISPLAY': ':0', 18:50:50 INFO - 'HOME': '/home/cltbld', 18:50:50 INFO - 'LANG': 'en_US.UTF-8', 18:50:50 INFO - 'LANGUAGE': 'en_US:en', 18:50:50 INFO - 'LOGNAME': 'cltbld', 18:50:50 INFO - 'MAIL': '/var/mail/cltbld', 18:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:50 INFO - 'MOZ_NO_REMOTE': '1', 18:50:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:50 INFO - 'NO_EM_RESTART': '1', 18:50:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:50 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:50 INFO - 'SHELL': '/bin/bash', 18:50:50 INFO - 'SHLVL': '1', 18:50:50 INFO - 'TERM': 'linux', 18:50:50 INFO - 'TMOUT': '86400', 18:50:50 INFO - 'USER': 'cltbld', 18:50:50 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:50 INFO - '_': '/tools/buildbot/bin/python'} 18:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 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)) 18:50:50 INFO - Cleaning up... 18:50:50 INFO - Return code: 0 18:50:50 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:50:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:50 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 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:50 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 18:50:50 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 18:50:50 INFO - Using env: {'DISPLAY': ':0', 18:50:50 INFO - 'HOME': '/home/cltbld', 18:50:50 INFO - 'LANG': 'en_US.UTF-8', 18:50:50 INFO - 'LANGUAGE': 'en_US:en', 18:50:50 INFO - 'LOGNAME': 'cltbld', 18:50:50 INFO - 'MAIL': '/var/mail/cltbld', 18:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:50 INFO - 'MOZ_NO_REMOTE': '1', 18:50:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:50 INFO - 'NO_EM_RESTART': '1', 18:50:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:51 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:51 INFO - 'SHELL': '/bin/bash', 18:50:51 INFO - 'SHLVL': '1', 18:50:51 INFO - 'TERM': 'linux', 18:50:51 INFO - 'TMOUT': '86400', 18:50:51 INFO - 'USER': 'cltbld', 18:50:51 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:51 INFO - '_': '/tools/buildbot/bin/python'} 18:50:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:51 INFO - Downloading/unpacking jsonschema 18:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:50:53 INFO - Downloading/unpacking functools32 (from jsonschema) 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:53 INFO - Downloading functools32-3.2.3-2.tar.gz 18:50:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:50:53 INFO - warning: no files found matching '*.txt' 18:50:53 INFO - no previously-included directories found matching 'build' 18:50:53 INFO - no previously-included directories found matching 'dist' 18:50:53 INFO - no previously-included directories found matching '.git*' 18:50:53 INFO - Installing collected packages: jsonschema, functools32 18:50:53 INFO - Running setup.py install for functools32 18:50:53 INFO - warning: no files found matching '*.txt' 18:50:53 INFO - no previously-included directories found matching 'build' 18:50:53 INFO - no previously-included directories found matching 'dist' 18:50:53 INFO - no previously-included directories found matching '.git*' 18:50:53 INFO - Successfully installed jsonschema functools32 18:50:53 INFO - Cleaning up... 18:50:53 INFO - Return code: 0 18:50:53 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461a22f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28fd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ca600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, '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': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', '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 18:50:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 18:50:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:50:53 INFO - Using env: {'DISPLAY': ':0', 18:50:53 INFO - 'HOME': '/home/cltbld', 18:50:53 INFO - 'LANG': 'en_US.UTF-8', 18:50:53 INFO - 'LANGUAGE': 'en_US:en', 18:50:53 INFO - 'LOGNAME': 'cltbld', 18:50:53 INFO - 'MAIL': '/var/mail/cltbld', 18:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:53 INFO - 'MOZ_NO_REMOTE': '1', 18:50:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:50:53 INFO - 'NO_EM_RESTART': '1', 18:50:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:50:53 INFO - 'PWD': '/builds/slave/test-pgo', 18:50:53 INFO - 'SHELL': '/bin/bash', 18:50:53 INFO - 'SHLVL': '1', 18:50:53 INFO - 'TERM': 'linux', 18:50:53 INFO - 'TMOUT': '86400', 18:50:53 INFO - 'USER': 'cltbld', 18:50:53 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:53 INFO - '_': '/tools/buildbot/bin/python'} 18:50:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:53 INFO - Downloading/unpacking flake8 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:50:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:50:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:50:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:50:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:50:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:50:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:50:56 INFO - no previously-included directories found matching 'docs/_build' 18:50:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:50:56 INFO - Running setup.py install for pep8 18:50:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:50:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:50:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:50:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:50:56 INFO - no previously-included directories found matching 'docs/_build' 18:50:56 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:50:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:50:56 INFO - Cleaning up... 18:50:56 INFO - Return code: 0 18:50:56 INFO - Running post-action listener: _resource_record_post_action 18:50:56 INFO - Running post-action listener: _start_resource_monitoring 18:50:56 INFO - Starting resource monitoring. 18:50:56 INFO - ##### 18:50:56 INFO - ##### Running install step. 18:50:56 INFO - ##### 18:50:56 INFO - Running pre-action listener: _resource_record_pre_action 18:50:56 INFO - Running main action method: install 18:50:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:50:56 INFO - Reading from file tmpfile_stdout 18:50:56 INFO - Detecting whether we're running mozinstall >=1.0... 18:50:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:50:56 INFO - Reading from file tmpfile_stdout 18:50:56 INFO - Output received: 18:50:56 INFO - Usage: mozinstall [options] installer 18:50:56 INFO - Options: 18:50:56 INFO - -h, --help show this help message and exit 18:50:56 INFO - -d DEST, --destination=DEST 18:50:56 INFO - Directory to install application into. [default: 18:50:56 INFO - "/builds/slave/test-pgo"] 18:50:56 INFO - --app=APP Application being installed. [default: firefox] 18:50:56 INFO - mkdir: /builds/slave/test-pgo/build/application 18:50:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:51:11 INFO - Reading from file tmpfile_stdout 18:51:11 INFO - Output received: 18:51:11 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:51:11 INFO - Running post-action listener: _resource_record_post_action 18:51:11 INFO - ##### 18:51:11 INFO - ##### Running run-tests step. 18:51:11 INFO - ##### 18:51:11 INFO - Running pre-action listener: _resource_record_pre_action 18:51:11 INFO - Running main action method: run_tests 18:51:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:51:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:51:11 INFO - Python 2.7.3 18:51:11 INFO - Return code: 0 18:51:11 INFO - grabbing minidump binary from tooltool 18:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a59350>, '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 0x2a5a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5a6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:51:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:51:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:51:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:11 INFO - Return code: 0 18:51:11 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:51:11 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:51:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:51:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:51:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:51:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:51:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:51:12 INFO - Return code: 0 18:51:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:51:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost 18:51:12 INFO - Using env: {'DISPLAY': ':0', 18:51:12 INFO - 'HOME': '/home/cltbld', 18:51:12 INFO - 'LANG': 'en_US.UTF-8', 18:51:12 INFO - 'LANGUAGE': 'en_US:en', 18:51:12 INFO - 'LOGNAME': 'cltbld', 18:51:12 INFO - 'MAIL': '/var/mail/cltbld', 18:51:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:51:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:51:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:12 INFO - 'MOZ_NO_REMOTE': '1', 18:51:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:51:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:51:12 INFO - 'NO_EM_RESTART': '1', 18:51:12 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:51:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:51:12 INFO - 'PWD': '/builds/slave/test-pgo', 18:51:12 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:51:12 INFO - 'SHELL': '/bin/bash', 18:51:12 INFO - 'SHLVL': '1', 18:51:12 INFO - 'TERM': 'linux', 18:51:12 INFO - 'TMOUT': '86400', 18:51:12 INFO - 'USER': 'cltbld', 18:51:12 INFO - 'XDG_SESSION_COOKIE': '0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724', 18:51:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:12 INFO - '_': '/tools/buildbot/bin/python'} 18:51:12 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost'] with output_timeout 3600 18:51:12 INFO - mozversion INFO | application_buildid: 20160218164328 18:51:12 INFO - mozversion INFO | application_changeset: 492ff963118827592469bb0bfbdbdbd50058642c 18:51:12 INFO - mozversion INFO | application_display_name: Nightly 18:51:12 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:12 INFO - mozversion INFO | application_name: Firefox 18:51:12 INFO - mozversion INFO | application_remotingname: firefox 18:51:12 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:51:12 INFO - mozversion INFO | application_vendor: Mozilla 18:51:12 INFO - mozversion INFO | application_version: 45.0 18:51:12 INFO - mozversion INFO | platform_buildid: 20160218164328 18:51:12 INFO - mozversion INFO | platform_changeset: 492ff963118827592469bb0bfbdbdbd50058642c 18:51:12 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:51:12 INFO - mozversion INFO | platform_version: 45.0 18:51:12 INFO - 2016-02-18 18:51:12,927 DEBUG : using testdate: 1455850272 18:51:12 INFO - 2016-02-18 18:51:12,927 DEBUG : actual date: 1455850272 18:51:12 INFO - 2016-02-18 18:51:12,935 INFO : starting webserver on 'localhost:50108' 18:51:12 INFO - 2016-02-18 18:51:12,936 INFO : Starting test suite talos-linux64-ix-029 18:51:12 INFO - 2016-02-18 18:51:12,936 INFO : Starting test dromaeo_css 18:51:12 INFO - 2016-02-18 18:51:12,936 DEBUG : operating with platform_type : linux_ 18:51:12 INFO - 2016-02-18 18:51:12,937 INFO : Initialising browser for dromaeo_css test... 18:51:12 INFO - 2016-02-18 18:51:12,949 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpif4HyR/profile http://localhost:50108/getInfo.html 18:51:16 INFO - 2016-02-18 18:51:16,392 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:51:16 INFO - 2016-02-18 18:51:16,392 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:51:16 INFO - 2016-02-18 18:51:16,392 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:51:16 INFO - 2016-02-18 18:51:16,392 DEBUG : BROWSER_OUTPUT: __metrics 18:51:16 INFO - 2016-02-18 18:51:16,533 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] 18:51:17 INFO - 2016-02-18 18:51:17,369 INFO : Browser initialized. 18:51:17 INFO - 2016-02-18 18:51:17,370 INFO : Running cycle 1/1 for dromaeo_css test... 18:51:17 INFO - 2016-02-18 18:51:17,370 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpif4HyR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:51:29 INFO - 2016-02-18 18:51:29,055 DEBUG : BROWSER_OUTPUT: RSS: Main: 133120000 18:51:29 INFO - 2016-02-18 18:51:29,055 DEBUG : BROWSER_OUTPUT: 18:52:19 INFO - 2016-02-18 18:52:19,939 DEBUG : BROWSER_OUTPUT: 1455850339938 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] 18:54:27 INFO - 2016-02-18 18:54:27,706 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50108/tests/dromaeo/cssquery-ext.html) 18:54:28 INFO - 2016-02-18 18:54:28,844 DEBUG : BROWSER_OUTPUT: RSS: Main: 205672448 18:54:28 INFO - 2016-02-18 18:54:28,844 DEBUG : BROWSER_OUTPUT: 18:57:30 INFO - 2016-02-18 18:57:30,940 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/dromaeo/cssquery-ext.html (next: http://localhost:50108/tests/dromaeo/cssquery-jquery.html) 18:57:31 INFO - 2016-02-18 18:57:31,502 DEBUG : BROWSER_OUTPUT: RSS: Main: 214278144 18:57:31 INFO - 2016-02-18 18:57:31,502 DEBUG : BROWSER_OUTPUT: 19:00:33 INFO - 2016-02-18 19:00:33,190 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50108/tests/dromaeo/cssquery-mootools.html) 19:00:33 INFO - 2016-02-18 19:00:33,752 DEBUG : BROWSER_OUTPUT: RSS: Main: 213319680 19:00:33 INFO - 2016-02-18 19:00:33,752 DEBUG : BROWSER_OUTPUT: 19:03:35 INFO - 2016-02-18 19:03:35,440 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50108/tests/dromaeo/cssquery-prototype.html) 19:03:36 INFO - 2016-02-18 19:03:36,006 DEBUG : BROWSER_OUTPUT: RSS: Main: 198021120 19:03:36 INFO - 2016-02-18 19:03:36,006 DEBUG : BROWSER_OUTPUT: 19:06:38 INFO - 2016-02-18 19:06:38,316 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50108/tests/dromaeo/cssquery-yui.html) 19:06:38 INFO - 2016-02-18 19:06:38,910 DEBUG : BROWSER_OUTPUT: RSS: Main: 198889472 19:06:38 INFO - 2016-02-18 19:06:38,910 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,135 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/dromaeo/cssquery-yui.html (next: http://localhost:50108/tests/dromaeo/cssquery-dojo.html) 19:09:41 INFO - 2016-02-18 19:09:41,385 DEBUG : BROWSER_OUTPUT: RSS: Main: 238551040 19:09:41 INFO - 2016-02-18 19:09:41,385 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,386 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:09:41 INFO - 2016-02-18 19:09:41,386 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:09:41 INFO - 2016-02-18 19:09:41,386 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:09:41 INFO - 2016-02-18 19:09:41,386 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:09:41 INFO - 2016-02-18 19:09:41,386 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3271.574695289612;3339.448990916499;3368.5115658229556;3370.8651653933844;3384.1539615096235;5425.159100339449;5462.180672899067;5469.015577196104;5497.972510137443;5504.4495550445035;5449.591280653957;5486.91769623456;5495.0658388074025;5495.697736624484;5503.11950087986;4809.422869255694;4970.875728106799;5024.145895197807;5049.621278404116;5072.822451214208;4891;4956.67781594197;4958.107540642683;4961.776720047588;4964.5531902128805;4921.680090794087;4924.187509061001;4943.184374578208;5020.39755229373;5033.84898453047;5012.624053196007;5053.6967781932835;5056.696598204101;5061.822836200752;5091.719955402451;4976.527229913152;5021.121303771862;5023.874403139951;5033.647644664875;5044.495550444926;4988.326575912278;5149.485051494858;5182.844514664566;5296.682199068068;5338.733063346817;5864.472197502203;5870.471657550841;5898.911516327258;5909.113632955048;5910.822675319747;5919.704014799286;5927.318358388798;5936.050231962865;5941.197938278364;5941.465268125889;5348.545373643245;5408.945910540883;5426.8371948841595;5450.890982180414;5511.145772405243;5925.762969481172;5939.495142912814;5940.732667029907;5944.256967879015;5944.256967879015;5571.582131340166;5749.540036797046;5753.050746626844;5754.338251101093;5760.596758226884;5741.741621627037;5802.274715660543;5805.767769289266;5811;5840.561957853178;5800.1299805029585;5818.389069147764;5827.504662103769;5830.912536311958;5835.56233282497;5595;5691.317041954909;5781.913271300934;5830.329512106077;5833.095870140135;4402.471703395613;4455.844045458393;4490.191765482245;4556.453225612948;4578.862634120982;4079.0618157823865;4083.0404854859084;4083.775392353449;4083.9387409188885;4113.341865301538;5658.405867383948;5662.037453632893;5663.915041274384;5665.348484924204;5697.088465845528;5651.9152212716845;5746.913796292975;5907.202527658797;5962.761489540457;6004.609700369462;5638.069718496402;5665.801696940587;5669.036263835158;5671.546276297886;5678.68767217807;4964.3298154748645;5104.081265372194;5172.482751724872;5175.844724658266;5205.29728486657;6017.06735456005;6072.787452439144;6156.384361563808;6159.876802463926;6165.660888651168;5982.4316689914385;5985.311689155803;5987.31145918225;5991.161237426677;5992.640441573607;5916;6041.78853740117;6090.626122351159;6184.536159787944;6186.319504854552;5650.971745141248;5656.3495198051105;5659.518940890071;5688.829335119787;5703.543716502752;5927.8221653350465;5929.851753706192;5971.701414929149;5980.521558275241;5983.4914032306;5590.664560126405;5664.461876121762;5676.886462270649;5834.94165058344;5851.502622277154;5777.884442311214;5793.015187418233;5796.159556864315;5799.82600521985;5803.709814509173;5704.087346024616;5710.2291190690385;5712.542996560348;5718.0565206742085;5720.713964301852;5572.164175373727;5660.660360378391;5669.829905102853;5794.681292528951;5810.709464526842;5644.3508996465935;5664.546836253172;5682.744276507485;5685.147227915846;5691.544676425793 19:09:41 INFO - 2016-02-18 19:09:41,388 DEBUG : BROWSER_OUTPUT: |1;ext.html;577.1285359107749;614.1954040207328;615.587556337254;618.2457401969597;636.2110982381848;40273.3958990615;41016.794916025414;41174.79412602937;41287.38068928961;41334.37998430018;45930.540694593044;46513.534864651345;46525;46564.767176164154;46567.835804104914;7794.649240784178;7914.881276780853;7924;9123.498207598595;9381.296402769785;10921.344719316827;11285.887141128565;11289.887101128967;11296.717582060432;11300.547978080867;10968.23222374434;11038;11050.778984420307;11052.336859788438;11075.556977720882;10873.293235939638;10942.398168100752;10974.396408197586;10982.450877456135;10986.780264394747;10714.46427678613;10884.07485363745;10885.782284354347;10892.128629709683;10896.073833724144;43029.784851075856;43296.3505547417;43413.56586434127;43500.56499435029;43509;3620.456931460276;3646.3072016316814;3824.6557809796973;3858.189780146173;4252.9149417011795;31299.171585892247;31467.842660786777;31861.681383186336;31917.52123718146;31992.680073199437;4412.867613971586;4426.24753791853;4483.574060464251;4498.685092043558;4499.730016199039;302950;307638;308646;309760;310885;57806;58978.41021589729;67032.66483667649;67479.66260168668;67530;80130;81156;81642.59178704068;82083.07501549806;82156.58921705473;14807.259637018105;15535;15548.68902621964;15561.844381556266;15568.221588920509;11713.941430292794;11838.230515016496;11876;11876.524939002516;11886.167968242313;22536.661950070764;22878.771212287997;22907.88546057259;22966.42583935415;22984.310470685905;8239.382046346547;8644.703294505875;8646.610902509403;8652.740417787476;8670.575122826449;1010.6664800615779;1031.3554028732042;1046.3669479964665;1109.3233127792041;1162.4129814443652;29502;29602;30389.392212156075;30443.543346849816;30812.845935770627;1037.5382954585289;1041.5937784264142;1048.496721573649;1050.3164414919952;1179.8733356604894;13074.738505230032;15142.470013549473;15675.855527469872;15768.92115539415;16049.197540122947;230563;231428;233815;234048;234162;60713.69643151756;61103;61589.69205153945;61590;61678.69160654168;1367.856375080622;1413.7808639661075;1418.5957002254308;1422.679897023182;1428.9214093224764;30114.54828177535;30293.242668933453;30493.390132197677;31376.215594610316;31379;49556.75221624013;49747.50252497429;50162.24756628727;50180.74909625574;50258.2461263074;336823;339243;341279;341870;343093;34615.30769384649;41401.1719765609;42143.789281053396;42748.572514274456;44963.55036449724;50320;51420.74289628678;51685.483145168066;52008.49681369487;52135.739321303154;42251.7887410561;42383.576164237966;42551.36172957346;42579.7871010643;44059.77970110129;42443.05903976577;43454.56545434505;43481.56518434775;43691.12617747691;43747.12505749804;43005.35491967561;43563;43628;43946.560534394244;44006.339904900815;38353.80823095867;38754;39075.41386879256;39253.60746392499;39372.60627393804;28609.28476788097;29949.700502994692;30990.69009309878;31426.371472570878;31507.527387088754 19:09:41 INFO - 2016-02-18 19:09:41,389 DEBUG : BROWSER_OUTPUT: |2;jquery.html;385.69337376785455;389.09535330356925;392.4603669953814;392.95481019682734;393.856242471498;13882.861171388284;15997.84002159978;17135.65728685427;19296.90351548242;19321.323753668607;17925.462236132906;17951.820481795177;18282.085895705193;18301.54246143844;18331.358402455917;5925.31858836234;6069;6083.908741368883;6084.787032453865;6097.359777223394;4471.284594464886;4556.270996640538;4558.225101732697;4563.703359281652;6068.939310606904;6634;6733.225679046899;6843.349881761224;6869.278725733801;6881.380675739182;6781.932180678179;6790.320967903195;6796.864062718742;6840.042394064785;6857.828554286133;6424.068510066014;6561.311062338481;6562.475001999828;6567.014947757827;6588.637624930627;22089.226877059227;23001.19495817655;23318.533629327318;23726;23728.881355593283;2419.250032489934;2431.4407686232266;2480.7395223501453;2484.689413823272;2593.883275252599;14719.484818031318;14823.332950017275;14836.555307647328;14900.329485173193;14907.403703851833;2082.5938941906334;2092.1526781653456;2118.142152428269;2120.7455105387453;2162.2756376613697;63115.68442157806;65341;65617;65794.3420565793;66533.3346666532;16262.918685406497;16579.17104144812;16656.75014874778;17006.064666443464;17231.22459489326;26048.73951260501;26164.869175654;26169.738302617112;26228.60657090145;26255;9443.76390590227;9458.668946586837;9475.857862132074;9506;9641.228701703847;7848.7645370638975;7888.566128862968;7910.76267711958;7967.641456134488;7972.162922893012;8348.916510834935;8353.95823020881;8418.536980466011;8433.704820331259;8562.315014798802;1931.8357939575012;1932.1788239998225;1947.7370554974898;1960.362882063335;2323.2449453927543;961.5000199896015;969.7333233360882;975.23444096384;1043.1029314789275;1122.9438528073727;16492.670146597;16920.492385228463;17056.403025894033;17244.741328880078;18601.7209741854;947.4599478297305;959.2613687460616;975.4634950777045;975.9414435133915;1033.788073444942;10188.6433974812;10492.947535262427;10494.632687855888;10521.947390263153;10543.367397956146;533546;540224;541511;595014.1710909782;617365;76582;77573.2242677566;83286.58356708178;86533;89926.55036724775;515.2194425445454;529.1876968852793;529.5313647422059;530.4403853934059;530.7505472427953;10282.794344112926;10679.5194216262;10707.196960227859;10946.39794811293;15615.921920390325;35265;36321.27357452889;36928.26143477169;38363.61636383712;38365.232695346494;44333;44536.55463445454;46148.538514614425;46448;46459.767701161276;10061;10375.429351385746;10379.96200379956;10565.260431769702;10968.06771424406;18861;18874;18921.243150273836;19061.428157155307;19188.712169317194;14164.645883852987;14259.853082719781;14430.484799095944;14441.422343106158;14567.271636417925;15394.384224630889;15453.613659658598;15777.290021948811;15838.92080539636;16010;14240.501582444565;14382.928085359506;15015.24923753786;15118.546443606709;15136.772948405562;10454.738631534272;10461.372317660966;10633.255672102878;10699.090577301016;10718.303310284806;14262.42950281977;14369.784453233418;14814.25928703582;15255.466058687893;15507.612309691896 19:09:41 INFO - 2016-02-18 19:09:41,393 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1579.3682526989203;1584.6355338272203;1824.5803465203;1836.3022051620387;1843;8064.354851611872;8457.66169353225;8476.32189424846;8486.024107227671;8487.533185674785;7153.356197942184;7219.783406497801;8097.635606397712;8311.085780564148;8345.833083338344;2312.7340355859037;2325.395397196724;2336.6845475860796;2341.0401735288533;2352.5882970480097;2029.5940811837618;2317.9768202317928;2339.4736184358553;2383.4994651123284;2387.3912152401113;1928.1034319041644;2265.864048157116;2308.815294776414;2317.385892738426;2318.5131122464304;2294.9426264343356;2327.2669109230537;2330.988345058264;2341.1220792202926;2352.5530149271667;2339.4970081432575;2344.9648255276184;2369.158948573249;2374.7625237476113;2405.2543711449507;8372.58137093143;8453.873191902127;8455.323574114056;8458.957705211496;8461.66153353865;4780.641451891122;4895.510448955076;4898.730569818658;4903.166461701484;4907.55831975124;2989.282572182682;3074.6617872034058;3080.9148663302867;3090.5673205750986;3108.254019035438;980.9362391444533;992.0575453319375;1035.8239099353052;1042.2704107125044;1063.9521221545049;11402.942985285103;11410.771784564346;11433.085353171726;11436.656900293005;11454.255473394203;4255.5318914919335;4318.35224716295;4321.58944900234;4332.978335108304;4414.4702635683925;9697.757556061155;9787.608495660093;9811.852822207673;9814.263930205263;9827.852582211273;4853.077915196172;4875.902481950412;4881.048195601895;4891.241857512091;4894.681845680019;4375.868723938287;4389.31965545341;4400.361947517592;4406.339049142654;4408.360787685768;3779.3197224499304;3836.0985168485386;3838.846446142123;3845.384738441836;3866.2267546490816;1085.0505807418508;1105.3920343810871;1160.5415860734963;1162.587281515067;1165.5396118533301;1117.782032503654;1138.1009002887793;1138.3113216504062;1371.5268232459784;1454.7745099509593;7862.606869656494;7947.443678942419;8018.55897925608;8057.194280571896;8079.353651707849;1179.817128345108;1185.7391373897728;1193.7552801675808;1198.2870192235605;1210.9394530273628;6231.158793562901;6291.087792270187;6356.618602883842;6369.331220221903;6370.426661600385;9909.950450247847;10147.847782283272;10301.793964120676;10309.948450257702;10320.48397580118;9826.263030272758;9996.550155243032;10197.18422526196;10200.846987295197;10211.744706382251;7879.921200787919;7927.484713493604;7977.680892764341;7984.361251099898;7989.400794940403;7643.464957452925;7747.767566973;7764.84470310602;7770.378369730295;7804.219578042151;8860.202581767671;8942.971558270621;8959.462432254086;9179.357444978787;9211.677591284273;9137.314701397288;9222.723318300461;9408.764780880532;9410.764730881509;9418.10527999839;7451.962740186264;7452.403807695263;7472.626368681435;7502.399808015237;7524.736634217776;7882.763517094496;7890.9605451972375;7894.250046245597;7895.802604934923;7903.407244456804;6349.809505714836;7531.284527969834;7547.962260188664;7704.961475192588;7822.256885595859;7243.601601911735;7277.963610181915;7317.304856038668;7350.448716346403;7423.962880185564;7416.925830741624;7454.254574542502;7466.701331946661;7474.402047836269;7480.70077196906;6972.1284839395075;7093.680784364613;7105.82235444118;7132.821679458055;7149.571025738473;7110.075690160246;7150.284971503016;7156.964215178891;7215;7219.0976127984 19:09:41 INFO - 2016-02-18 19:09:41,393 DEBUG : BROWSER_OUTPUT: |4;prototype.html;351.33071498794794;359.2437918182227;365.30774182923375;369.46058754218836;370.63492459926965;5863.325717542479;5926.22959015328;5966.045432730764;5991.101334799782;6026.186464827247;5646.20953066571;5756.338021127572;5801.680907550093;5902.970485147579;5924.703764811753;4613.700109492889;4630.398048253723;4653.232216684243;4655.557722016403;4670;4623.768811559426;4640.83757068501;4650.511696271894;4652.604528615072;4652.790624421892;4387.890302742425;4479.216137175998;4504.211762941489;4583;4592.74739889306;4576.565226303496;4578.771061446931;4592.609628181609;4603.401557797499;4604.194266003436;4105.261053010457;4105.486814148231;4107.137501124767;4110.630043296118;4112.383142528644;5561.499465048165;5578.246936663539;5580.051391263495;5588.776448942038;5600.383957764642;5185.455527169631;5311.707856067915;5322.04203243416;5345.278387417688;5352;6263.373662633746;6275.905861412082;6276;6276.403741644536;6277.497800175975;4488.349189367556;4488.955110448886;4489.281714925596;4489.977550112261;4491.955080449186;6406.167198264243;6451.096846441502;6518.674066296666;6523.967380163069;6529.673516324212;4606.907861842811;4607.424071991001;4608.400907882021;4608.446986361658;4609.078184363074;4660.97669511657;4661.417322834645;4664.253719404879;4666.136764698542;4668.229742092584;5322;5326.201069839555;5327.147656374961;5329.760160792774;5336;6205.472534834499;6328.525360597973;6331.398517140864;6333.714982825692;6338.936610633927;1612.9354825806836;1646.2838665180684;1646.7118254305449;1647.5139833748833;1647.9588010299838;1102.862142232221;1117.212365282474;1123.719070232442;1128.4245035032193;1143.6226045404997;6187.81436556904;6191.349908259658;6192.659403732838;6199.070139479114;6203.937960620427;6514.348565143542;6520.641364724891;6525.445337146396;6527.738890444329;6531.379518945692;5996.370381110008;6089.756409743649;6128.816135515942;6231.626102433868;6238.625682459067;3509.298140371936;3524.4184709523156;3531.593866705362;3533.575970883459;3538.4515400113023;6784;6796.218434879953;6798.592084474947;6804.387605115563;6812.6934287957165;6568.310327415648;6708.060871477997;6714.227863795629;6714.496412769062;6718.764843230463;6768.593884366954;6803.7618683345845;6814.931850681529;6818.727250910008;6821.965890170517;6550.901736473861;6552.770653027121;6560.376764207393;6563.113979612882;6565.080888675494;6312.084747711648;6428.967855160694;6445.70994305248;6465.709043092978;6470.708818103102;6774.830629234111;6785.253622101466;6791.966040169965;6792.524523283323;6800;4609.354690343422;4609.631229501699;4609.67732258752;4612.53874612536;4621.69958952667;6391.265004524354;6519.934800651933;6533.248676402084;6536.182977127859;6538.509611779041;6380.425761681471;6381.968090159705;6382.648954307372;6389.680515974275;6391.360863913757;6352.078948552342;6365.4589359903075;6365.61806291624;6367.681615919279;6372.58578192416;6144.78493252734;6272.560920735505;6304.275008374096;6310.242770867525;6312.873742525216;6415.935840641534;6417.839554011187;6419.101325814297;6424.229092508929;6431.903521447089;6263.3736626337;6266.248050234001;6278.058291256166;6280.057991301342;6280.591761535486 19:09:41 INFO - 2016-02-18 19:09:41,396 DEBUG : BROWSER_OUTPUT: |5;yui.html;814.2468216899371;857.1685465098852;879.612970293071;945.4138434170814;975.8585005174245;13947.581572552826;14472.42110315586;14501.057431266974;14516.709665806691;14531;13969.371378287977;14154.079984800981;14160.57518274451;14167.78748318776;14180.71638567228;707.1655446573042;729.2415887477017;729.8394353242278;901.7475106970059;920.585736418611;736.2637362637363;895.7894894699773;921.3320342751518;927.1655510040983;929.8930622978377;962.0812124421161;966.5264020629876;967.4388854464394;971.7619183300102;978.3542861711272;746.7946314763457;763.1605234242345;919.7424721078108;958.1281034258826;959.712086374085;711.1572786248312;712.9750458733971;714.1608609883418;714.9177844547839;714.9714011439536;14372.928135359361;14466.638334041732;14491.202983835885;14545.34545945435;14592.562223133324;12467.93766031173;13561.118527295794;13832.832972145425;13874.791878121836;13877.791833122512;11138.49876755548;11280.83078753811;11336.886631133666;11340.829887551694;11446.14153938458;3881.7282790204695;3921.117748506592;3926.6466018058513;3934.3114954883067;3942.6057394260633;50034;50671.23993140106;51115.233271500954;51675.22487162695;51732;11015.83476247857;11041.889581104248;11118.499667514981;11179.385133817717;11221.326720396803;20356.491087722927;20403.183872645226;20471.078801453972;20505.48736281605;20525.281615143696;8018.679252829821;8163.142869998684;8180.100188979208;8265.586720663943;8399.664013439517;4421.823127074946;4548.863534093983;4596.954030459719;4619.422572178478;4644.210484217623;3089.2276930767307;3129.7339726123078;3132.9216769580944;3136.6549679535233;3136.764742644265;560.7504660426072;561.8033192965073;566.5948846574719;569.9088145896636;586.9148973398833;616.9629822210682;662.1954325494547;688.6246995387526;690.413148823496;693.2201273567237;8749.300055995505;8765.123487651184;8817.823643527094;8895.866562001574;9003.819923601492;563.6758863653416;661.0381894343718;676.2527407215055;752.8381397999457;757.9242075792376;4162.188373267244;4396.3625274335045;4397.73613583186;4406.933895991563;4439.223135951195;57409.13886291709;57481.425185747605;57581.424185757605;57615.711921440125;57840.71079644575;51571;52377.476225238024;52440.21339679908;52561;52854;50839.74580127076;51138.74430627823;51295.74352128216;51841.48158518367;51975.74012129915;9131.954340228522;9758.95539061131;9983.251256155963;10155.695329140137;10177.134943529883;37042;37393.25213495769;37415.2516949654;38108;38134.2373152541;46361.53638463572;49061.2640810381;49658.255126172415;49682.75158624184;49965.50034499754;11405;11840.28958262507;11924.463399147233;11985.400729963292;12490.515321784915;13110.606681799562;13160.60518184456;13457.394417251051;14248.643783905485;14252.28738563047;11933.343666098448;12094.334811585448;12409.875901240872;12732.68168295763;12853.100282980102;11981.940090299493;12218.633440996784;12307.56923507673;12572.811407829073;12922.806157907828;11137.331760094095;11326.320420774451;12292.446839892047;12640.241585504898;12861.09716886887;10709.250352475252;10979.17656175806;11015.72460688457;11207.71980700489;11292;9974.950125249326;10189.694309170736;10276.948615256875;10425.530851111567;10463.372197668165 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,858 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: __startTimestamp1455851381384__endTimestamp 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:09:41 INFO - 2016-02-18 19:09:41,397 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5456.72 Median:5665.80 stddev:596.62 (10.5%) stddev-sans-first:573.30 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: Values: 3271.6 3339.4 3368.5 3370.9 3384.2 5425.2 5462.2 5469.0 5498.0 5504.4 5449.6 5486.9 5495.1 5495.7 5503.1 4809.4 4970.9 5024.1 5049.6 5072.8 4891.0 4956.7 4958.1 4961.8 4964.6 4921.7 4924.2 4943.2 5020.4 5033.8 5012.6 5053.7 5056.7 5061.8 5091.7 4976.5 5021.1 5023.9 5033.6 5044.5 4988.3 5149.5 5182.8 5296.7 5338.7 5864.5 5870.5 5898.9 5909.1 5910.8 5919.7 5927.3 5936.1 5941.2 5941.5 5348.5 5408.9 5426.8 5450.9 5511.1 5925.8 5939.5 5940.7 5944.3 5944.3 5571.6 5749.5 5753.1 5754.3 5760.6 5741.7 5802.3 5805.8 5811.0 5840.6 5800.1 5818.4 5827.5 5830.9 5835.6 5595.0 5691.3 5781.9 5830.3 5833.1 4402.5 4455.8 4490.2 4556.5 4578.9 4079.1 4083.0 4083.8 4083.9 4113.3 5658.4 5662.0 5663.9 5665.3 5697.1 5651.9 5746.9 5907.2 5962.8 6004.6 5638.1 5665.8 5669.0 5671.5 5678.7 4964.3 5104.1 5172.5 5175.8 5205.3 6017.1 6072.8 6156.4 6159.9 6165.7 5982.4 5985.3 5987.3 5991.2 5992.6 5916.0 6041.8 6090.6 6184.5 6186.3 5651.0 5656.3 5659.5 5688.8 5703.5 5927.8 5929.9 5971.7 5980.5 5983.5 5590.7 5664.5 5676.9 5834.9 5851.5 5777.9 5793.0 5796.2 5799.8 5803.7 5704.1 5710.2 5712.5 5718.1 5720.7 5572.2 5660.7 5669.8 5794.7 5810.7 5644.4 5664.5 5682.7 5685.1 5691.5 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49772.48 Median:19117.88 stddev:77598.58 (405.9%) stddev-sans-first:77728.34 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: Values: 577.1 614.2 615.6 618.2 636.2 40273.4 41016.8 41174.8 41287.4 41334.4 45930.5 46513.5 46525.0 46564.8 46567.8 7794.6 7914.9 7924.0 9123.5 9381.3 10921.3 11285.9 11289.9 11296.7 11300.5 10968.2 11038.0 11050.8 11052.3 11075.6 10873.3 10942.4 10974.4 10982.5 10986.8 10714.5 10884.1 10885.8 10892.1 10896.1 43029.8 43296.4 43413.6 43500.6 43509.0 3620.5 3646.3 3824.7 3858.2 4252.9 31299.2 31467.8 31861.7 31917.5 31992.7 4412.9 4426.2 4483.6 4498.7 4499.7 302950.0 307638.0 308646.0 309760.0 310885.0 57806.0 58978.4 67032.7 67479.7 67530.0 80130.0 81156.0 81642.6 82083.1 82156.6 14807.3 15535.0 15548.7 15561.8 15568.2 11713.9 11838.2 11876.0 11876.5 11886.2 22536.7 22878.8 22907.9 22966.4 22984.3 8239.4 8644.7 8646.6 8652.7 8670.6 1010.7 1031.4 1046.4 1109.3 1162.4 29502.0 29602.0 30389.4 30443.5 30812.8 1037.5 1041.6 1048.5 1050.3 1179.9 13074.7 15142.5 15675.9 15768.9 16049.2 230563.0 231428.0 233815.0 234048.0 234162.0 60713.7 61103.0 61589.7 61590.0 61678.7 1367.9 1413.8 1418.6 1422.7 1428.9 30114.5 30293.2 30493.4 31376.2 31379.0 49556.8 49747.5 50162.2 50180.7 50258.2 336823.0 339243.0 341279.0 341870.0 343093.0 34615.3 41401.2 42143.8 42748.6 44963.6 50320.0 51420.7 51685.5 52008.5 52135.7 42251.8 42383.6 42551.4 42579.8 44059.8 42443.1 43454.6 43481.6 43691.1 43747.1 43005.4 43563.0 43628.0 43946.6 44006.3 38353.8 38754.0 39075.4 39253.6 39372.6 28609.3 29949.7 30990.7 31426.4 31507.5 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,398 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31054.13 Median:2482.71 stddev:92403.13 (3721.9%) stddev-sans-first:92633.65 19:09:41 INFO - 2016-02-18 19:09:41,399 DEBUG : BROWSER_OUTPUT: Values: 385.7 389.1 392.5 393.0 393.9 13882.9 15997.8 17135.7 19296.9 19321.3 17925.5 17951.8 18282.1 18301.5 18331.4 5925.3 6069.0 6083.9 6084.8 6097.4 4471.3 4556.3 4558.2 4563.7 6068.9 6634.0 6733.2 6843.3 6869.3 6881.4 6781.9 6790.3 6796.9 6840.0 6857.8 6424.1 6561.3 6562.5 6567.0 6588.6 22089.2 23001.2 23318.5 23726.0 23728.9 2419.3 2431.4 2480.7 2484.7 2593.9 14719.5 14823.3 14836.6 14900.3 14907.4 2082.6 2092.2 2118.1 2120.7 2162.3 63115.7 65341.0 65617.0 65794.3 66533.3 16262.9 16579.2 16656.8 17006.1 17231.2 26048.7 26164.9 26169.7 26228.6 26255.0 9443.8 9458.7 9475.9 9506.0 9641.2 7848.8 7888.6 7910.8 7967.6 7972.2 8348.9 8354.0 8418.5 8433.7 8562.3 1931.8 1932.2 1947.7 1960.4 2323.2 961.5 969.7 975.2 1043.1 1122.9 16492.7 16920.5 17056.4 17244.7 18601.7 947.5 959.3 975.5 975.9 1033.8 10188.6 10492.9 10494.6 10521.9 10543.4 533546.0 540224.0 541511.0 595014.2 617365.0 76582.0 77573.2 83286.6 86533.0 89926.6 515.2 529.2 529.5 530.4 530.8 10282.8 10679.5 10707.2 10946.4 15615.9 35265.0 36321.3 36928.3 38363.6 38365.2 44333.0 44536.6 46148.5 46448.0 46459.8 10061.0 10375.4 10380.0 10565.3 10968.1 18861.0 18874.0 18921.2 19061.4 19188.7 14164.6 14259.9 14430.5 14441.4 14567.3 15394.4 15453.6 15777.3 15838.9 16010.0 14240.5 14382.9 15015.2 15118.5 15136.8 10454.7 10461.4 10633.3 10699.1 10718.3 14262.4 14369.8 14814.3 15255.5 15507.6 19:09:41 INFO - 2016-02-18 19:09:41,399 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,400 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5822.09 Median:6261.12 stddev:3080.43 (49.2%) stddev-sans-first:3072.57 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: Values: 1579.4 1584.6 1824.6 1836.3 1843.0 8064.4 8457.7 8476.3 8486.0 8487.5 7153.4 7219.8 8097.6 8311.1 8345.8 2312.7 2325.4 2336.7 2341.0 2352.6 2029.6 2318.0 2339.5 2383.5 2387.4 1928.1 2265.9 2308.8 2317.4 2318.5 2294.9 2327.3 2331.0 2341.1 2352.6 2339.5 2345.0 2369.2 2374.8 2405.3 8372.6 8453.9 8455.3 8459.0 8461.7 4780.6 4895.5 4898.7 4903.2 4907.6 2989.3 3074.7 3080.9 3090.6 3108.3 980.9 992.1 1035.8 1042.3 1064.0 11402.9 11410.8 11433.1 11436.7 11454.3 4255.5 4318.4 4321.6 4333.0 4414.5 9697.8 9787.6 9811.9 9814.3 9827.9 4853.1 4875.9 4881.0 4891.2 4894.7 4375.9 4389.3 4400.4 4406.3 4408.4 3779.3 3836.1 3838.8 3845.4 3866.2 1085.1 1105.4 1160.5 1162.6 1165.5 1117.8 1138.1 1138.3 1371.5 1454.8 7862.6 7947.4 8018.6 8057.2 8079.4 1179.8 1185.7 1193.8 1198.3 1210.9 6231.2 6291.1 6356.6 6369.3 6370.4 9910.0 10147.8 10301.8 10309.9 10320.5 9826.3 9996.6 10197.2 10200.8 10211.7 7879.9 7927.5 7977.7 7984.4 7989.4 7643.5 7747.8 7764.8 7770.4 7804.2 8860.2 8943.0 8959.5 9179.4 9211.7 9137.3 9222.7 9408.8 9410.8 9418.1 7452.0 7452.4 7472.6 7502.4 7524.7 7882.8 7891.0 7894.3 7895.8 7903.4 6349.8 7531.3 7548.0 7705.0 7822.3 7243.6 7278.0 7317.3 7350.4 7424.0 7416.9 7454.3 7466.7 7474.4 7480.7 6972.1 7093.7 7105.8 7132.8 7149.6 7110.1 7150.3 7157.0 7215.0 7219.1 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5325.09 Median:6057.97 stddev:1579.73 (26.1%) stddev-sans-first:1539.42 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: Values: 351.3 359.2 365.3 369.5 370.6 5863.3 5926.2 5966.0 5991.1 6026.2 5646.2 5756.3 5801.7 5903.0 5924.7 4613.7 4630.4 4653.2 4655.6 4670.0 4623.8 4640.8 4650.5 4652.6 4652.8 4387.9 4479.2 4504.2 4583.0 4592.7 4576.6 4578.8 4592.6 4603.4 4604.2 4105.3 4105.5 4107.1 4110.6 4112.4 5561.5 5578.2 5580.1 5588.8 5600.4 5185.5 5311.7 5322.0 5345.3 5352.0 6263.4 6275.9 6276.0 6276.4 6277.5 4488.3 4489.0 4489.3 4490.0 4492.0 6406.2 6451.1 6518.7 6524.0 6529.7 4606.9 4607.4 4608.4 4608.4 4609.1 4661.0 4661.4 4664.3 4666.1 4668.2 5322.0 5326.2 5327.1 5329.8 5336.0 6205.5 6328.5 6331.4 6333.7 6338.9 1612.9 1646.3 1646.7 1647.5 1648.0 1102.9 1117.2 1123.7 1128.4 1143.6 6187.8 6191.3 6192.7 6199.1 6203.9 6514.3 6520.6 6525.4 6527.7 6531.4 5996.4 6089.8 6128.8 6231.6 6238.6 3509.3 3524.4 3531.6 3533.6 3538.5 6784.0 6796.2 6798.6 6804.4 6812.7 6568.3 6708.1 6714.2 6714.5 6718.8 6768.6 6803.8 6814.9 6818.7 6822.0 6550.9 6552.8 6560.4 6563.1 6565.1 6312.1 6429.0 6445.7 6465.7 6470.7 6774.8 6785.3 6792.0 6792.5 6800.0 4609.4 4609.6 4609.7 4612.5 4621.7 6391.3 6519.9 6533.2 6536.2 6538.5 6380.4 6382.0 6382.6 6389.7 6391.4 6352.1 6365.5 6365.6 6367.7 6372.6 6144.8 6272.6 6304.3 6310.2 6312.9 6415.9 6417.8 6419.1 6424.2 6431.9 6263.4 6266.2 6278.1 6280.1 6280.6 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14778.93 Median:4430.52 stddev:16755.56 (378.2%) stddev-sans-first:16769.74 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: Values: 814.2 857.2 879.6 945.4 975.9 13947.6 14472.4 14501.1 14516.7 14531.0 13969.4 14154.1 14160.6 14167.8 14180.7 707.2 729.2 729.8 901.7 920.6 736.3 895.8 921.3 927.2 929.9 962.1 966.5 967.4 971.8 978.4 746.8 763.2 919.7 958.1 959.7 711.2 713.0 714.2 714.9 715.0 14372.9 14466.6 14491.2 14545.3 14592.6 12467.9 13561.1 13832.8 13874.8 13877.8 11138.5 11280.8 11336.9 11340.8 11446.1 3881.7 3921.1 3926.6 3934.3 3942.6 50034.0 50671.2 51115.2 51675.2 51732.0 11015.8 11041.9 11118.5 11179.4 11221.3 20356.5 20403.2 20471.1 20505.5 20525.3 8018.7 8163.1 8180.1 8265.6 8399.7 4421.8 4548.9 4597.0 4619.4 4644.2 3089.2 3129.7 3132.9 3136.7 3136.8 560.8 561.8 566.6 569.9 586.9 617.0 662.2 688.6 690.4 693.2 8749.3 8765.1 8817.8 8895.9 9003.8 563.7 661.0 676.3 752.8 757.9 4162.2 4396.4 4397.7 4406.9 4439.2 57409.1 57481.4 57581.4 57615.7 57840.7 51571.0 52377.5 52440.2 52561.0 52854.0 50839.7 51138.7 51295.7 51841.5 51975.7 9132.0 9759.0 9983.3 10155.7 10177.1 37042.0 37393.3 37415.3 38108.0 38134.2 46361.5 49061.3 49658.3 49682.8 49965.5 11405.0 11840.3 11924.5 11985.4 12490.5 13110.6 13160.6 13457.4 14248.6 14252.3 11933.3 12094.3 12409.9 12732.7 12853.1 11981.9 12218.6 12307.6 12572.8 12922.8 11137.3 11326.3 12292.4 12640.2 12861.1 10709.3 10979.2 11015.7 11207.7 11292.0 9975.0 10189.7 10276.9 10425.5 10463.4 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:09:41 INFO - 2016-02-18 19:09:41,401 DEBUG : BROWSER_OUTPUT: 19:09:41 INFO - 2016-02-18 19:09:41,950 INFO : Browser exited with error code: 0 19:09:41 INFO - 2016-02-18 19:09:41,968 INFO : Completed test dromaeo_css (00:18:29) 19:09:41 INFO - 2016-02-18 19:09:41,968 INFO : Starting test kraken 19:09:41 INFO - 2016-02-18 19:09:41,968 DEBUG : operating with platform_type : linux_ 19:09:41 INFO - 2016-02-18 19:09:41,968 INFO : Initialising browser for kraken test... 19:09:41 INFO - 2016-02-18 19:09:41,975 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWlzIxn/profile http://localhost:50108/getInfo.html 19:09:45 INFO - 2016-02-18 19:09:45,413 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:09:45 INFO - 2016-02-18 19:09:45,413 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:09:45 INFO - 2016-02-18 19:09:45,413 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:09:45 INFO - 2016-02-18 19:09:45,413 DEBUG : BROWSER_OUTPUT: __metrics 19:09:45 INFO - 2016-02-18 19:09:45,555 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] 19:09:46 INFO - 2016-02-18 19:09:46,202 INFO : Browser initialized. 19:09:46 INFO - 2016-02-18 19:09:46,202 INFO : Running cycle 1/1 for kraken test... 19:09:46 INFO - 2016-02-18 19:09:46,203 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpWlzIxn/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:09:57 INFO - 2016-02-18 19:09:57,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 133058560 19:09:57 INFO - 2016-02-18 19:09:57,860 DEBUG : BROWSER_OUTPUT: 19:10:00 INFO - 2016-02-18 19:10:00,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50108/tests/kraken/driver.html?testName=audio-beat-detection) 19:10:00 INFO - 2016-02-18 19:10:00,853 DEBUG : BROWSER_OUTPUT: RSS: Main: 162881536 19:10:00 INFO - 2016-02-18 19:10:00,853 DEBUG : BROWSER_OUTPUT: 19:10:03 INFO - 2016-02-18 19:10:03,389 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50108/tests/kraken/driver.html?testName=audio-dft) 19:10:03 INFO - 2016-02-18 19:10:03,955 DEBUG : BROWSER_OUTPUT: RSS: Main: 162738176 19:10:03 INFO - 2016-02-18 19:10:03,956 DEBUG : BROWSER_OUTPUT: 19:10:06 INFO - 2016-02-18 19:10:06,738 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50108/tests/kraken/driver.html?testName=audio-fft) 19:10:07 INFO - 2016-02-18 19:10:07,300 DEBUG : BROWSER_OUTPUT: RSS: Main: 158965760 19:10:07 INFO - 2016-02-18 19:10:07,300 DEBUG : BROWSER_OUTPUT: 19:10:09 INFO - 2016-02-18 19:10:09,298 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50108/tests/kraken/driver.html?testName=audio-oscillator) 19:10:09 INFO - 2016-02-18 19:10:09,863 DEBUG : BROWSER_OUTPUT: RSS: Main: 158785536 19:10:09 INFO - 2016-02-18 19:10:09,863 DEBUG : BROWSER_OUTPUT: 19:10:12 INFO - 2016-02-18 19:10:12,066 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50108/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:10:12 INFO - 2016-02-18 19:10:12,628 DEBUG : BROWSER_OUTPUT: RSS: Main: 156983296 19:10:12 INFO - 2016-02-18 19:10:12,628 DEBUG : BROWSER_OUTPUT: 19:10:15 INFO - 2016-02-18 19:10:15,510 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50108/tests/kraken/driver.html?testName=imaging-darkroom) 19:10:16 INFO - 2016-02-18 19:10:16,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 158072832 19:10:16 INFO - 2016-02-18 19:10:16,078 DEBUG : BROWSER_OUTPUT: 19:10:19 INFO - 2016-02-18 19:10:19,398 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50108/tests/kraken/driver.html?testName=imaging-desaturate) 19:10:19 INFO - 2016-02-18 19:10:19,965 DEBUG : BROWSER_OUTPUT: RSS: Main: 159592448 19:10:19 INFO - 2016-02-18 19:10:19,965 DEBUG : BROWSER_OUTPUT: 19:10:22 INFO - 2016-02-18 19:10:22,683 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50108/tests/kraken/driver.html?testName=json-parse-financial) 19:10:23 INFO - 2016-02-18 19:10:23,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 158666752 19:10:23 INFO - 2016-02-18 19:10:23,251 DEBUG : BROWSER_OUTPUT: 19:10:24 INFO - 2016-02-18 19:10:24,758 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50108/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:10:25 INFO - 2016-02-18 19:10:25,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 166830080 19:10:25 INFO - 2016-02-18 19:10:25,319 DEBUG : BROWSER_OUTPUT: 19:10:27 INFO - 2016-02-18 19:10:27,089 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:10:27 INFO - 2016-02-18 19:10:27,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 164622336 19:10:27 INFO - 2016-02-18 19:10:27,677 DEBUG : BROWSER_OUTPUT: 19:10:29 INFO - 2016-02-18 19:10:29,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:10:30 INFO - 2016-02-18 19:10:30,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 170618880 19:10:30 INFO - 2016-02-18 19:10:30,194 DEBUG : BROWSER_OUTPUT: 19:10:32 INFO - 2016-02-18 19:10:32,548 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:10:33 INFO - 2016-02-18 19:10:33,128 DEBUG : BROWSER_OUTPUT: RSS: Main: 176406528 19:10:33 INFO - 2016-02-18 19:10:33,129 DEBUG : BROWSER_OUTPUT: 19:10:35 INFO - 2016-02-18 19:10:35,568 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:10:36 INFO - 2016-02-18 19:10:36,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 173608960 19:10:36 INFO - 2016-02-18 19:10:36,137 DEBUG : BROWSER_OUTPUT: 19:10:37 INFO - 2016-02-18 19:10:37,786 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50108/tests/kraken/driver.html?testName=ai-astar) 19:10:38 INFO - 2016-02-18 19:10:38,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 233046016 19:10:38 INFO - 2016-02-18 19:10:38,036 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,038 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:10:38 INFO - 2016-02-18 19:10:38,038 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:10:38 INFO - 2016-02-18 19:10:38,038 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:10:38 INFO - 2016-02-18 19:10:38,038 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:10:38 INFO - 2016-02-18 19:10:38,039 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;102;111;111;111;97;108;109;108 19:10:38 INFO - 2016-02-18 19:10:38,039 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;120;142;159;121;146;148;120;156;120 19:10:38 INFO - 2016-02-18 19:10:38,039 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;161;160;161;160;162;158;160;161 19:10:38 INFO - 2016-02-18 19:10:38,039 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;72;152;73;73;72;71;156;71 19:10:38 INFO - 2016-02-18 19:10:38,039 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;146;92;92;92;144;92;92;146 19:10:38 INFO - 2016-02-18 19:10:38,040 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;110;109;109;110;110;109 19:10:38 INFO - 2016-02-18 19:10:38,040 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;148;147;147;148;147;147;147;146 19:10:38 INFO - 2016-02-18 19:10:38,040 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;95;96;95;95 19:10:38 INFO - 2016-02-18 19:10:38,040 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;56;57;58;56;57;57;55;55 19:10:38 INFO - 2016-02-18 19:10:38,041 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;62;64;63;63;64;64;65;64;69;63 19:10:38 INFO - 2016-02-18 19:10:38,041 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;77;79;77;78;79;78;78;79;79 19:10:38 INFO - 2016-02-18 19:10:38,041 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;118;117;122;110;114;120;120;114;120 19:10:38 INFO - 2016-02-18 19:10:38,041 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;141;141;142;140;133;141;144;138;130 19:10:38 INFO - 2016-02-18 19:10:38,041 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;64;63;64;63;65;65;65;66;71 19:10:38 INFO - 2016-02-18 19:10:38,042 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:10:38 INFO - 2016-02-18 19:10:38,042 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:10:38 INFO - 2016-02-18 19:10:38,042 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,878 19:10:38 INFO - 2016-02-18 19:10:38,042 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:10:38 INFO - 2016-02-18 19:10:38,043 DEBUG : BROWSER_OUTPUT: __startTimestamp1455851438037__endTimestamp 19:10:38 INFO - 2016-02-18 19:10:38,043 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,046 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:10:38 INFO - 2016-02-18 19:10:38,048 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:10:38 INFO - 2016-02-18 19:10:38,048 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,048 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:110.00 stddev:4.50 (4.1%) stddev-sans-first:4.77 19:10:38 INFO - 2016-02-18 19:10:38,048 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 102.0 111.0 111.0 111.0 97.0 108.0 109.0 108.0 19:10:38 INFO - 2016-02-18 19:10:38,049 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,049 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:138.60 Median:147.00 stddev:16.53 (11.2%) stddev-sans-first:16.56 19:10:38 INFO - 2016-02-18 19:10:38,049 DEBUG : BROWSER_OUTPUT: Values: 154.0 120.0 142.0 159.0 121.0 146.0 148.0 120.0 156.0 120.0 19:10:38 INFO - 2016-02-18 19:10:38,049 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,049 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.17 (0.7%) stddev-sans-first:1.13 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 161.0 160.0 161.0 160.0 162.0 158.0 160.0 161.0 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.20 Median:72.00 stddev:34.70 (48.2%) stddev-sans-first:36.18 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 72.0 152.0 73.0 73.0 72.0 71.0 156.0 71.0 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:108.00 Median:92.00 stddev:25.77 (28.0%) stddev-sans-first:26.67 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 146.0 92.0 92.0 92.0 144.0 92.0 92.0 146.0 19:10:38 INFO - 2016-02-18 19:10:38,050 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,051 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:10:38 INFO - 2016-02-18 19:10:38,051 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 110.0 109.0 109.0 110.0 110.0 109.0 19:10:38 INFO - 2016-02-18 19:10:38,051 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,051 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 19:10:38 INFO - 2016-02-18 19:10:38,051 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 148.0 147.0 147.0 148.0 147.0 147.0 147.0 146.0 19:10:38 INFO - 2016-02-18 19:10:38,052 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,052 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:10:38 INFO - 2016-02-18 19:10:38,052 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 19:10:38 INFO - 2016-02-18 19:10:38,052 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,052 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.03 (1.8%) stddev-sans-first:1.00 19:10:38 INFO - 2016-02-18 19:10:38,052 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 56.0 57.0 58.0 56.0 57.0 57.0 55.0 55.0 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:1.91 (3.0%) stddev-sans-first:1.87 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: Values: 62.0 64.0 63.0 63.0 64.0 64.0 65.0 64.0 69.0 63.0 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:78.50 stddev:0.79 (1.0%) stddev-sans-first:0.83 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: Values: 78.0 77.0 79.0 77.0 78.0 79.0 78.0 78.0 79.0 79.0 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.30 Median:119.00 stddev:3.65 (3.1%) stddev-sans-first:3.87 19:10:38 INFO - 2016-02-18 19:10:38,053 DEBUG : BROWSER_OUTPUT: Values: 118.0 118.0 117.0 122.0 110.0 114.0 120.0 120.0 114.0 120.0 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.50 Median:141.00 stddev:4.45 (3.2%) stddev-sans-first:4.54 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: Values: 135.0 141.0 141.0 142.0 140.0 133.0 141.0 144.0 138.0 130.0 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:2.49 (3.8%) stddev-sans-first:2.42 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: Values: 62.0 64.0 63.0 64.0 63.0 65.0 65.0 65.0 66.0 71.0 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:10:38 INFO - 2016-02-18 19:10:38,054 DEBUG : BROWSER_OUTPUT: 19:10:38 INFO - 2016-02-18 19:10:38,662 INFO : Browser exited with error code: 0 19:10:38 INFO - 2016-02-18 19:10:38,720 INFO : Completed test kraken (00:00:56) 19:10:38 INFO - 2016-02-18 19:10:38,720 INFO : Starting test v8_7 19:10:38 INFO - 2016-02-18 19:10:38,720 DEBUG : operating with platform_type : linux_ 19:10:38 INFO - 2016-02-18 19:10:38,721 INFO : Initialising browser for v8_7 test... 19:10:38 INFO - 2016-02-18 19:10:38,727 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpj4CWJw/profile http://localhost:50108/getInfo.html 19:10:41 INFO - 2016-02-18 19:10:41,922 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:10:41 INFO - 2016-02-18 19:10:41,922 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:10:41 INFO - 2016-02-18 19:10:41,923 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:10:41 INFO - 2016-02-18 19:10:41,923 DEBUG : BROWSER_OUTPUT: __metrics 19:10:42 INFO - 2016-02-18 19:10:42,059 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] 19:10:43 INFO - 2016-02-18 19:10:43,109 INFO : Browser initialized. 19:10:43 INFO - 2016-02-18 19:10:43,110 INFO : Running cycle 1/1 for v8_7 test... 19:10:43 INFO - 2016-02-18 19:10:43,110 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpj4CWJw/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:10:54 INFO - 2016-02-18 19:10:54,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 133873664 19:10:54 INFO - 2016-02-18 19:10:54,801 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,192 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:50108/tests/v8_7/run.html (next: http://localhost:50108/tests/v8_7/run.html) 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 425582592 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: |0;Richards;172.47326664367023 19:11:16 INFO - 2016-02-18 19:11:16,451 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.31153466973382 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.9321845469705 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |4;RayTrace;960.6147934678194 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |5;Earley;543.773790103317 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |6;Boyer;10755.31914893617 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |7;RegExp;24804.878048780487 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |8;Splay;514.1388174807198 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: __startTimestamp1455851476444__endTimestamp 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:172.47 Median:172.47 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,452 DEBUG : BROWSER_OUTPUT: Values: 172.5 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.31 Median:164.31 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: Values: 164.3 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.93 Median:277.93 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: Values: 277.9 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: Values: 5170.1 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:960.61 Median:960.61 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: Values: 960.6 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:543.77 Median:543.77 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,453 DEBUG : BROWSER_OUTPUT: Values: 543.8 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10755.32 Median:10755.32 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: Values: 10755.3 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24804.88 Median:24804.88 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: Values: 24804.9 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:514.14 Median:514.14 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: Values: 514.1 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: Values: 4830.9 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:11:16 INFO - 2016-02-18 19:11:16,454 DEBUG : BROWSER_OUTPUT: 19:11:17 INFO - 2016-02-18 19:11:17,134 INFO : Browser exited with error code: 0 19:11:17 INFO - 2016-02-18 19:11:17,148 INFO : Completed test v8_7 (00:00:38) 19:11:17 INFO - 2016-02-18 19:11:17,425 INFO : Completed test suite (00:20:04) 19:11:17 INFO - 2016-02-18 19:11:17,426 DEBUG : Working with test: dromaeo_css 19:11:17 INFO - 2016-02-18 19:11:17,426 DEBUG : Generating results file: dromaeo_css 19:11:17 INFO - 2016-02-18 19:11:17,427 DEBUG : Working with test: kraken 19:11:17 INFO - 2016-02-18 19:11:17,427 DEBUG : Generating results file: kraken 19:11:17 INFO - 2016-02-18 19:11:17,427 INFO : javascript benchmark 19:11:17 INFO - 2016-02-18 19:11:17,427 DEBUG : Working with test: v8_7 19:11:17 INFO - 2016-02-18 19:11:17,427 DEBUG : Generating results file: v8_7 19:11:17 INFO - 2016-02-18 19:11:17,428 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:11:17 INFO - 2016-02-18 19:11:17,554 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 19:11:17 INFO - 2016-02-18 19:11:17,554 DEBUG : process_Request line: dromaeo_css 7455.15 graph.html#tests=[[72,53,35]] 19:11:17 INFO - 2016-02-18 19:11:17,554 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:11:17 INFO - 2016-02-18 19:11:17,584 DEBUG : process_Request line: kraken 1473.70 graph.html#tests=[[232,53,35]] 19:11:17 INFO - 2016-02-18 19:11:17,584 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:11:17 INFO - 2016-02-18 19:11:17,617 DEBUG : process_Request line: v8_7 23148.35 graph.html#tests=[[230,53,35]] 19:11:17 INFO - 2016-02-18 19:11:17,619 INFO : javascript benchmark 19:11:17 INFO - 2016-02-18 19:11:17,623 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3271.574695289612, 3339.448990916499, 3368.5115658229556, 3370.8651653933844, 3384.1539615096235, 5425.159100339449, 5462.180672899067, 5469.015577196104, 5497.972510137443, 5504.4495550445035, 5449.591280653957, 5486.91769623456, 5495.0658388074025, 5495.697736624484, 5503.11950087986, 4809.422869255694, 4970.875728106799, 5024.145895197807, 5049.621278404116, 5072.822451214208, 4891.0, 4956.67781594197, 4958.107540642683, 4961.776720047588, 4964.5531902128805, 4921.680090794087, 4924.187509061001, 4943.184374578208, 5020.39755229373, 5033.84898453047, 5012.624053196007, 5053.6967781932835, 5056.696598204101, 5061.822836200752, 5091.719955402451, 4976.527229913152, 5021.121303771862, 5023.874403139951, 5033.647644664875, 5044.495550444926, 4988.326575912278, 5149.485051494858, 5182.844514664566, 5296.682199068068, 5338.733063346817, 5864.472197502203, 5870.471657550841, 5898.911516327258, 5909.113632955048, 5910.822675319747, 5919.704014799286, 5927.318358388798, 5936.050231962865, 5941.197938278364, 5941.465268125889, 5348.545373643245, 5408.945910540883, 5426.8371948841595, 5450.890982180414, 5511.145772405243, 5925.762969481172, 5939.495142912814, 5940.732667029907, 5944.256967879015, 5944.256967879015, 5571.582131340166, 5749.540036797046, 5753.050746626844, 5754.338251101093, 5760.596758226884, 5741.741621627037, 5802.274715660543, 5805.767769289266, 5811.0, 5840.561957853178, 5800.1299805029585, 5818.389069147764, 5827.504662103769, 5830.912536311958, 5835.56233282497, 5595.0, 5691.317041954909, 5781.913271300934, 5830.329512106077, 5833.095870140135, 4402.471703395613, 4455.844045458393, 4490.191765482245, 4556.453225612948, 4578.862634120982, 4079.0618157823865, 4083.0404854859084, 4083.775392353449, 4083.9387409188885, 4113.341865301538, 5658.405867383948, 5662.037453632893, 5663.915041274384, 5665.348484924204, 5697.088465845528, 5651.9152212716845, 5746.913796292975, 5907.202527658797, 5962.761489540457, 6004.609700369462, 5638.069718496402, 5665.801696940587, 5669.036263835158, 5671.546276297886, 5678.68767217807, 4964.3298154748645, 5104.081265372194, 5172.482751724872, 5175.844724658266, 5205.29728486657, 6017.06735456005, 6072.787452439144, 6156.384361563808, 6159.876802463926, 6165.660888651168, 5982.4316689914385, 5985.311689155803, 5987.31145918225, 5991.161237426677, 5992.640441573607, 5916.0, 6041.78853740117, 6090.626122351159, 6184.536159787944, 6186.319504854552, 5650.971745141248, 5656.3495198051105, 5659.518940890071, 5688.829335119787, 5703.543716502752, 5927.8221653350465, 5929.851753706192, 5971.701414929149, 5980.521558275241, 5983.4914032306, 5590.664560126405, 5664.461876121762, 5676.886462270649, 5834.94165058344, 5851.502622277154, 5777.884442311214, 5793.015187418233, 5796.159556864315, 5799.82600521985, 5803.709814509173, 5704.087346024616, 5710.2291190690385, 5712.542996560348, 5718.0565206742085, 5720.713964301852, 5572.164175373727, 5660.660360378391, 5669.829905102853, 5794.681292528951, 5810.709464526842, 5644.3508996465935, 5664.546836253172, 5682.744276507485, 5685.147227915846, 5691.544676425793], "unit": "ms", "name": "dojo.html", "value": 5419.05559939409}, {"lowerIsBetter": false, "replicates": [577.1285359107749, 614.1954040207328, 615.587556337254, 618.2457401969597, 636.2110982381848, 40273.3958990615, 41016.794916025414, 41174.79412602937, 41287.38068928961, 41334.37998430018, 45930.540694593044, 46513.534864651345, 46525.0, 46564.767176164154, 46567.835804104914, 7794.649240784178, 7914.881276780853, 7924.0, 9123.498207598595, 9381.296402769785, 10921.344719316827, 11285.887141128565, 11289.887101128967, 11296.717582060432, 11300.547978080867, 10968.23222374434, 11038.0, 11050.778984420307, 11052.336859788438, 11075.556977720882, 10873.293235939638, 10942.398168100752, 10974.396408197586, 10982.450877456135, 10986.780264394747, 10714.46427678613, 10884.07485363745, 10885.782284354347, 10892.128629709683, 10896.073833724144, 43029.784851075856, 43296.3505547417, 43413.56586434127, 43500.56499435029, 43509.0, 3620.456931460276, 3646.3072016316814, 3824.6557809796973, 3858.189780146173, 4252.9149417011795, 31299.171585892247, 31467.842660786777, 31861.681383186336, 31917.52123718146, 31992.680073199437, 4412.867613971586, 4426.24753791853, 4483.574060464251, 4498.685092043558, 4499.730016199039, 302950.0, 307638.0, 308646.0, 309760.0, 310885.0, 57806.0, 58978.41021589729, 67032.66483667649, 67479.66260168668, 67530.0, 80130.0, 81156.0, 81642.59178704068, 82083.07501549806, 82156.58921705473, 14807.259637018105, 15535.0, 15548.68902621964, 15561.844381556266, 15568.221588920509, 11713.941430292794, 11838.230515016496, 11876.0, 11876.524939002516, 11886.167968242313, 22536.661950070764, 22878.771212287997, 22907.88546057259, 22966.42583935415, 22984.310470685905, 8239.382046346547, 8644.703294505875, 8646.610902509403, 8652.740417787476, 8670.575122826449, 1010.6664800615779, 1031.3554028732042, 1046.3669479964665, 1109.3233127792041, 1162.4129814443652, 29502.0, 29602.0, 30389.392212156075, 30443.543346849816, 30812.845935770627, 1037.5382954585289, 1041.5937784264142, 1048.496721573649, 1050.3164414919952, 1179.8733356604894, 13074.738505230032, 15142.470013549473, 15675.855527469872, 15768.92115539415, 16049.197540122947, 230563.0, 231428.0, 233815.0, 234048.0, 234162.0, 60713.69643151756, 61103.0, 61589.69205153945, 61590.0, 61678.69160654168, 1367.856375080622, 1413.7808639661075, 1418.5957002254308, 1422.679897023182, 1428.9214093224764, 30114.54828177535, 30293.242668933453, 30493.390132197677, 31376.215594610316, 31379.0, 49556.75221624013, 49747.50252497429, 50162.24756628727, 50180.74909625574, 50258.2461263074, 336823.0, 339243.0, 341279.0, 341870.0, 343093.0, 34615.30769384649, 41401.1719765609, 42143.789281053396, 42748.572514274456, 44963.55036449724, 50320.0, 51420.74289628678, 51685.483145168066, 52008.49681369487, 52135.739321303154, 42251.7887410561, 42383.576164237966, 42551.36172957346, 42579.7871010643, 44059.77970110129, 42443.05903976577, 43454.56545434505, 43481.56518434775, 43691.12617747691, 43747.12505749804, 43005.35491967561, 43563.0, 43628.0, 43946.560534394244, 44006.339904900815, 38353.80823095867, 38754.0, 39075.41386879256, 39253.60746392499, 39372.60627393804, 28609.28476788097, 29949.700502994692, 30990.69009309878, 31426.371472570878, 31507.527387088754], "unit": "ms", "name": "ext.html", "value": 20657.50587372267}, {"lowerIsBetter": false, "replicates": [385.69337376785455, 389.09535330356925, 392.4603669953814, 392.95481019682734, 393.856242471498, 13882.861171388284, 15997.84002159978, 17135.65728685427, 19296.90351548242, 19321.323753668607, 17925.462236132906, 17951.820481795177, 18282.085895705193, 18301.54246143844, 18331.358402455917, 5925.31858836234, 6069.0, 6083.908741368883, 6084.787032453865, 6097.359777223394, 4471.284594464886, 4556.270996640538, 4558.225101732697, 4563.703359281652, 6068.939310606904, 6634.0, 6733.225679046899, 6843.349881761224, 6869.278725733801, 6881.380675739182, 6781.932180678179, 6790.320967903195, 6796.864062718742, 6840.042394064785, 6857.828554286133, 6424.068510066014, 6561.311062338481, 6562.475001999828, 6567.014947757827, 6588.637624930627, 22089.226877059227, 23001.19495817655, 23318.533629327318, 23726.0, 23728.881355593283, 2419.250032489934, 2431.4407686232266, 2480.7395223501453, 2484.689413823272, 2593.883275252599, 14719.484818031318, 14823.332950017275, 14836.555307647328, 14900.329485173193, 14907.403703851833, 2082.5938941906334, 2092.1526781653456, 2118.142152428269, 2120.7455105387453, 2162.2756376613697, 63115.68442157806, 65341.0, 65617.0, 65794.3420565793, 66533.3346666532, 16262.918685406497, 16579.17104144812, 16656.75014874778, 17006.064666443464, 17231.22459489326, 26048.73951260501, 26164.869175654, 26169.738302617112, 26228.60657090145, 26255.0, 9443.76390590227, 9458.668946586837, 9475.857862132074, 9506.0, 9641.228701703847, 7848.7645370638975, 7888.566128862968, 7910.76267711958, 7967.641456134488, 7972.162922893012, 8348.916510834935, 8353.95823020881, 8418.536980466011, 8433.704820331259, 8562.315014798802, 1931.8357939575012, 1932.1788239998225, 1947.7370554974898, 1960.362882063335, 2323.2449453927543, 961.5000199896015, 969.7333233360882, 975.23444096384, 1043.1029314789275, 1122.9438528073727, 16492.670146597, 16920.492385228463, 17056.403025894033, 17244.741328880078, 18601.7209741854, 947.4599478297305, 959.2613687460616, 975.4634950777045, 975.9414435133915, 1033.788073444942, 10188.6433974812, 10492.947535262427, 10494.632687855888, 10521.947390263153, 10543.367397956146, 533546.0, 540224.0, 541511.0, 595014.1710909782, 617365.0, 76582.0, 77573.2242677566, 83286.58356708178, 86533.0, 89926.55036724775, 515.2194425445454, 529.1876968852793, 529.5313647422059, 530.4403853934059, 530.7505472427953, 10282.794344112926, 10679.5194216262, 10707.196960227859, 10946.39794811293, 15615.921920390325, 35265.0, 36321.27357452889, 36928.26143477169, 38363.61636383712, 38365.232695346494, 44333.0, 44536.55463445454, 46148.538514614425, 46448.0, 46459.767701161276, 10061.0, 10375.429351385746, 10379.96200379956, 10565.260431769702, 10968.06771424406, 18861.0, 18874.0, 18921.243150273836, 19061.428157155307, 19188.712169317194, 14164.645883852987, 14259.853082719781, 14430.484799095944, 14441.422343106158, 14567.271636417925, 15394.384224630889, 15453.613659658598, 15777.290021948811, 15838.92080539636, 16010.0, 14240.501582444565, 14382.928085359506, 15015.24923753786, 15118.546443606709, 15136.772948405562, 10454.738631534272, 10461.372317660966, 10633.255672102878, 10699.090577301016, 10718.303310284806, 14262.42950281977, 14369.784453233418, 14814.25928703582, 15255.466058687893, 15507.612309691896], "unit": "ms", "name": "jquery.html", "value": 9880.825998890625}, {"lowerIsBetter": false, "replicates": [1579.3682526989203, 1584.6355338272203, 1824.5803465203, 1836.3022051620387, 1843.0, 8064.354851611872, 8457.66169353225, 8476.32189424846, 8486.024107227671, 8487.533185674785, 7153.356197942184, 7219.783406497801, 8097.635606397712, 8311.085780564148, 8345.833083338344, 2312.7340355859037, 2325.395397196724, 2336.6845475860796, 2341.0401735288533, 2352.5882970480097, 2029.5940811837618, 2317.9768202317928, 2339.4736184358553, 2383.4994651123284, 2387.3912152401113, 1928.1034319041644, 2265.864048157116, 2308.815294776414, 2317.385892738426, 2318.5131122464304, 2294.9426264343356, 2327.2669109230537, 2330.988345058264, 2341.1220792202926, 2352.5530149271667, 2339.4970081432575, 2344.9648255276184, 2369.158948573249, 2374.7625237476113, 2405.2543711449507, 8372.58137093143, 8453.873191902127, 8455.323574114056, 8458.957705211496, 8461.66153353865, 4780.641451891122, 4895.510448955076, 4898.730569818658, 4903.166461701484, 4907.55831975124, 2989.282572182682, 3074.6617872034058, 3080.9148663302867, 3090.5673205750986, 3108.254019035438, 980.9362391444533, 992.0575453319375, 1035.8239099353052, 1042.2704107125044, 1063.9521221545049, 11402.942985285103, 11410.771784564346, 11433.085353171726, 11436.656900293005, 11454.255473394203, 4255.5318914919335, 4318.35224716295, 4321.58944900234, 4332.978335108304, 4414.4702635683925, 9697.757556061155, 9787.608495660093, 9811.852822207673, 9814.263930205263, 9827.852582211273, 4853.077915196172, 4875.902481950412, 4881.048195601895, 4891.241857512091, 4894.681845680019, 4375.868723938287, 4389.31965545341, 4400.361947517592, 4406.339049142654, 4408.360787685768, 3779.3197224499304, 3836.0985168485386, 3838.846446142123, 3845.384738441836, 3866.2267546490816, 1085.0505807418508, 1105.3920343810871, 1160.5415860734963, 1162.587281515067, 1165.5396118533301, 1117.782032503654, 1138.1009002887793, 1138.3113216504062, 1371.5268232459784, 1454.7745099509593, 7862.606869656494, 7947.443678942419, 8018.55897925608, 8057.194280571896, 8079.353651707849, 1179.817128345108, 1185.7391373897728, 1193.7552801675808, 1198.2870192235605, 1210.9394530273628, 6231.158793562901, 6291.087792270187, 6356.618602883842, 6369.331220221903, 6370.426661600385, 9909.950450247847, 10147.847782283272, 10301.793964120676, 10309.948450257702, 10320.48397580118, 9826.263030272758, 9996.550155243032, 10197.18422526196, 10200.846987295197, 10211.744706382251, 7879.921200787919, 7927.484713493604, 7977.680892764341, 7984.361251099898, 7989.400794940403, 7643.464957452925, 7747.767566973, 7764.84470310602, 7770.378369730295, 7804.219578042151, 8860.202581767671, 8942.971558270621, 8959.462432254086, 9179.357444978787, 9211.677591284273, 9137.314701397288, 9222.723318300461, 9408.764780880532, 9410.764730881509, 9418.10527999839, 7451.962740186264, 7452.403807695263, 7472.626368681435, 7502.399808015237, 7524.736634217776, 7882.763517094496, 7890.9605451972375, 7894.250046245597, 7895.802604934923, 7903.407244456804, 6349.809505714836, 7531.284527969834, 7547.962260188664, 7704.961475192588, 7822.256885595859, 7243.601601911735, 7277.963610181915, 7317.304856038668, 7350.448716346403, 7423.962880185564, 7416.925830741624, 7454.254574542502, 7466.701331946661, 7474.402047836269, 7480.70077196906, 6972.1284839395075, 7093.680784364613, 7105.82235444118, 7132.821679458055, 7149.571025738473, 7110.075690160246, 7150.284971503016, 7156.964215178891, 7215.0, 7219.0976127984], "unit": "ms", "name": "mootools.html", "value": 4739.834256239918}, {"lowerIsBetter": false, "replicates": [351.33071498794794, 359.2437918182227, 365.30774182923375, 369.46058754218836, 370.63492459926965, 5863.325717542479, 5926.22959015328, 5966.045432730764, 5991.101334799782, 6026.186464827247, 5646.20953066571, 5756.338021127572, 5801.680907550093, 5902.970485147579, 5924.703764811753, 4613.700109492889, 4630.398048253723, 4653.232216684243, 4655.557722016403, 4670.0, 4623.768811559426, 4640.83757068501, 4650.511696271894, 4652.604528615072, 4652.790624421892, 4387.890302742425, 4479.216137175998, 4504.211762941489, 4583.0, 4592.74739889306, 4576.565226303496, 4578.771061446931, 4592.609628181609, 4603.401557797499, 4604.194266003436, 4105.261053010457, 4105.486814148231, 4107.137501124767, 4110.630043296118, 4112.383142528644, 5561.499465048165, 5578.246936663539, 5580.051391263495, 5588.776448942038, 5600.383957764642, 5185.455527169631, 5311.707856067915, 5322.04203243416, 5345.278387417688, 5352.0, 6263.373662633746, 6275.905861412082, 6276.0, 6276.403741644536, 6277.497800175975, 4488.349189367556, 4488.955110448886, 4489.281714925596, 4489.977550112261, 4491.955080449186, 6406.167198264243, 6451.096846441502, 6518.674066296666, 6523.967380163069, 6529.673516324212, 4606.907861842811, 4607.424071991001, 4608.400907882021, 4608.446986361658, 4609.078184363074, 4660.97669511657, 4661.417322834645, 4664.253719404879, 4666.136764698542, 4668.229742092584, 5322.0, 5326.201069839555, 5327.147656374961, 5329.760160792774, 5336.0, 6205.472534834499, 6328.525360597973, 6331.398517140864, 6333.714982825692, 6338.936610633927, 1612.9354825806836, 1646.2838665180684, 1646.7118254305449, 1647.5139833748833, 1647.9588010299838, 1102.862142232221, 1117.212365282474, 1123.719070232442, 1128.4245035032193, 1143.6226045404997, 6187.81436556904, 6191.349908259658, 6192.659403732838, 6199.070139479114, 6203.937960620427, 6514.348565143542, 6520.641364724891, 6525.445337146396, 6527.738890444329, 6531.379518945692, 5996.370381110008, 6089.756409743649, 6128.816135515942, 6231.626102433868, 6238.625682459067, 3509.298140371936, 3524.4184709523156, 3531.593866705362, 3533.575970883459, 3538.4515400113023, 6784.0, 6796.218434879953, 6798.592084474947, 6804.387605115563, 6812.6934287957165, 6568.310327415648, 6708.060871477997, 6714.227863795629, 6714.496412769062, 6718.764843230463, 6768.593884366954, 6803.7618683345845, 6814.931850681529, 6818.727250910008, 6821.965890170517, 6550.901736473861, 6552.770653027121, 6560.376764207393, 6563.113979612882, 6565.080888675494, 6312.084747711648, 6428.967855160694, 6445.70994305248, 6465.709043092978, 6470.708818103102, 6774.830629234111, 6785.253622101466, 6791.966040169965, 6792.524523283323, 6800.0, 4609.354690343422, 4609.631229501699, 4609.67732258752, 4612.53874612536, 4621.69958952667, 6391.265004524354, 6519.934800651933, 6533.248676402084, 6536.182977127859, 6538.509611779041, 6380.425761681471, 6381.968090159705, 6382.648954307372, 6389.680515974275, 6391.360863913757, 6352.078948552342, 6365.4589359903075, 6365.61806291624, 6367.681615919279, 6372.58578192416, 6144.78493252734, 6272.560920735505, 6304.275008374096, 6310.242770867525, 6312.873742525216, 6415.935840641534, 6417.839554011187, 6419.101325814297, 6424.229092508929, 6431.903521447089, 6263.3736626337, 6266.248050234001, 6278.058291256166, 6280.057991301342, 6280.591761535486], "unit": "ms", "name": "prototype.html", "value": 4822.572207742467}, {"lowerIsBetter": false, "replicates": [814.2468216899371, 857.1685465098852, 879.612970293071, 945.4138434170814, 975.8585005174245, 13947.581572552826, 14472.42110315586, 14501.057431266974, 14516.709665806691, 14531.0, 13969.371378287977, 14154.079984800981, 14160.57518274451, 14167.78748318776, 14180.71638567228, 707.1655446573042, 729.2415887477017, 729.8394353242278, 901.7475106970059, 920.585736418611, 736.2637362637363, 895.7894894699773, 921.3320342751518, 927.1655510040983, 929.8930622978377, 962.0812124421161, 966.5264020629876, 967.4388854464394, 971.7619183300102, 978.3542861711272, 746.7946314763457, 763.1605234242345, 919.7424721078108, 958.1281034258826, 959.712086374085, 711.1572786248312, 712.9750458733971, 714.1608609883418, 714.9177844547839, 714.9714011439536, 14372.928135359361, 14466.638334041732, 14491.202983835885, 14545.34545945435, 14592.562223133324, 12467.93766031173, 13561.118527295794, 13832.832972145425, 13874.791878121836, 13877.791833122512, 11138.49876755548, 11280.83078753811, 11336.886631133666, 11340.829887551694, 11446.14153938458, 3881.7282790204695, 3921.117748506592, 3926.6466018058513, 3934.3114954883067, 3942.6057394260633, 50034.0, 50671.23993140106, 51115.233271500954, 51675.22487162695, 51732.0, 11015.83476247857, 11041.889581104248, 11118.499667514981, 11179.385133817717, 11221.326720396803, 20356.491087722927, 20403.183872645226, 20471.078801453972, 20505.48736281605, 20525.281615143696, 8018.679252829821, 8163.142869998684, 8180.100188979208, 8265.586720663943, 8399.664013439517, 4421.823127074946, 4548.863534093983, 4596.954030459719, 4619.422572178478, 4644.210484217623, 3089.2276930767307, 3129.7339726123078, 3132.9216769580944, 3136.6549679535233, 3136.764742644265, 560.7504660426072, 561.8033192965073, 566.5948846574719, 569.9088145896636, 586.9148973398833, 616.9629822210682, 662.1954325494547, 688.6246995387526, 690.413148823496, 693.2201273567237, 8749.300055995505, 8765.123487651184, 8817.823643527094, 8895.866562001574, 9003.819923601492, 563.6758863653416, 661.0381894343718, 676.2527407215055, 752.8381397999457, 757.9242075792376, 4162.188373267244, 4396.3625274335045, 4397.73613583186, 4406.933895991563, 4439.223135951195, 57409.13886291709, 57481.425185747605, 57581.424185757605, 57615.711921440125, 57840.71079644575, 51571.0, 52377.476225238024, 52440.21339679908, 52561.0, 52854.0, 50839.74580127076, 51138.74430627823, 51295.74352128216, 51841.48158518367, 51975.74012129915, 9131.954340228522, 9758.95539061131, 9983.251256155963, 10155.695329140137, 10177.134943529883, 37042.0, 37393.25213495769, 37415.2516949654, 38108.0, 38134.2373152541, 46361.53638463572, 49061.2640810381, 49658.255126172415, 49682.75158624184, 49965.50034499754, 11405.0, 11840.28958262507, 11924.463399147233, 11985.400729963292, 12490.515321784915, 13110.606681799562, 13160.60518184456, 13457.394417251051, 14248.643783905485, 14252.28738563047, 11933.343666098448, 12094.334811585448, 12409.875901240872, 12732.68168295763, 12853.100282980102, 11981.940090299493, 12218.633440996784, 12307.56923507673, 12572.811407829073, 12922.806157907828, 11137.331760094095, 11326.320420774451, 12292.446839892047, 12640.241585504898, 12861.09716886887, 10709.250352475252, 10979.17656175806, 11015.72460688457, 11207.71980700489, 11292.0, 9974.950125249326, 10189.694309170736, 10276.948615256875, 10425.530851111567, 10463.372197668165], "unit": "ms", "name": "yui.html", "value": 6789.869230808083}], "name": "dromaeo_css", "value": 7455.152740007874}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 102.0, 111.0, 111.0, 111.0, 97.0, 108.0, 109.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 142.0, 159.0, 121.0, 146.0, 148.0, 120.0, 156.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 138.6}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 161.0, 160.0, 161.0, 160.0, 162.0, 158.0, 160.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 72.0, 152.0, 73.0, 73.0, 72.0, 71.0, 156.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.2}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 146.0, 92.0, 92.0, 92.0, 144.0, 92.0, 92.0, 146.0], "unit": "ms", "name": "audio-oscillator", "value": 108.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 148.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 147.3}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 56.0, 57.0, 58.0, 56.0, 57.0, 57.0, 55.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 63.0, 63.0, 64.0, 64.0, 65.0, 64.0, 69.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [78.0, 77.0, 79.0, 77.0, 78.0, 79.0, 78.0, 78.0, 79.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.2}, {"lowerIsBetter": true, "replicates": [118.0, 118.0, 117.0, 122.0, 110.0, 114.0, 120.0, 120.0, 114.0, 120.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.3}, {"lowerIsBetter": true, "replicates": [135.0, 141.0, 141.0, 142.0, 140.0, 133.0, 141.0, 144.0, 138.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.5}, {"lowerIsBetter": true, "replicates": [62.0, 64.0, 63.0, 64.0, 63.0, 65.0, 65.0, 65.0, 66.0, 71.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.8}], "name": "kraken", "value": 1473.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [172.47326664367023], "unit": "score", "name": "Richards", "value": 204.68099600000008}, {"lowerIsBetter": false, "replicates": [164.31153466973382], "unit": "score", "name": "DeltaBlue", "value": 402.39414800000014}, {"lowerIsBetter": false, "replicates": [277.9321845469705], "unit": "score", "name": "Encrypt", "value": 957.7192379999997}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [960.6147934678194], "unit": "score", "name": "RayTrace", "value": 770.3285489999998}, {"lowerIsBetter": false, "replicates": [543.773790103317], "unit": "score", "name": "Earley", "value": 1225.6254570000006}, {"lowerIsBetter": false, "replicates": [10755.31914893617], "unit": "score", "name": "Boyer", "value": 61.96589713155287}, {"lowerIsBetter": false, "replicates": [24804.878048780487], "unit": "score", "name": "RegExp", "value": 36.72604228121927}, {"lowerIsBetter": false, "replicates": [514.1388174807198], "unit": "score", "name": "Splay", "value": 158.49999499999993}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23148.35352855693}]} 19:11:17 INFO - RETURN: dromaeo_css: 7455.15 19:11:17 INFO - RETURN: kraken: 1473.70 19:11:17 INFO - RETURN: v8_7: 23148.35 19:11:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23148.35"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1473.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7455.15"}}} 19:11:17 INFO - Return code: 0 19:11:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:11:17 INFO - # TBPL SUCCESS # 19:11:17 INFO - Running post-action listener: _resource_record_post_action 19:11:17 INFO - Running post-run listener: _resource_record_post_run 19:11:18 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 4096; Write bytes: 339611648; Read time: 244; Write time: 228656 19:11:18 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 72212480; Read time: 0; Write time: 39960 19:11:18 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 267399168; Read time: 244; Write time: 188696 19:11:18 INFO - Running post-run listener: _upload_blobber_files 19:11:18 INFO - Blob upload gear active. 19:11:18 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:11:18 INFO - Copying logs to upload dir... 19:11:18 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1266.130367 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-02-18 19:11:18.074720) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-18 19:11:18.078719) ========= 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009901 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455842608/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-18 19:11:18.130409) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 19:11:18.130756) ========= 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=0274524eba3bdaad075c2247000001d4-1455849391.577075-1317100724 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004580 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 19:11:18.180579) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 19:11:18.180959) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 19:11:18.181318) ========= ========= Total master_lag: 0.29 =========