builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-001 starttime: 1456163521.54 results: success (0) buildid: 20160222072101 builduid: b499c037fa75445394e488eb40be2791 revision: e2b86793693f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.541952) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.542490) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.542815) ========= 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.008899 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.566243) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.566565) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.579956) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.580389) ========= 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004590 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.631866) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.632216) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.632620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.633247) ========= 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-22 09:52:01-- 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% 64.5M=0s 2016-02-22 09:52:02 (64.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.179386 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.826430) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.826783) ========= 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017731 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:01.876551) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-22 09:52:01.876944) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e2b86793693f --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 e2b86793693f --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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-22 09:52:02,251 Setting DEBUG logging. 2016-02-22 09:52:02,251 attempt 1/10 2016-02-22 09:52:02,251 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e2b86793693f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-22 09:52:03,086 unpacking tar archive at: mozilla-beta-e2b86793693f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.157601 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-22 09:52:03.048086) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:03.048499) ========= echo e2b86793693f in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'e2b86793693f'] 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False e2b86793693f program finished with exit code 0 elapsedTime=0.003346 script_repo_revision: 'e2b86793693f' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:03.096490) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:03.096840) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-22 09:52:03.107089) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-02-22 09:52:03.107538) ========= /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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:52:03 INFO - MultiFileLogger online at 20160222 09:52:03 in /builds/slave/test-pgo 09:52:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:52:03 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:52:03 INFO - {'append_to_log': False, 09:52:03 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:52:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:52:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:52:03 INFO - 'branch': 'Mozilla-Beta', 09:52:03 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:03 INFO - 'config_files': ('talos/linux_config.py',), 09:52:03 INFO - 'default_actions': ('clobber', 09:52:03 INFO - 'read-buildbot-config', 09:52:03 INFO - 'download-and-extract', 09:52:03 INFO - 'populate-webroot', 09:52:03 INFO - 'create-virtualenv', 09:52:03 INFO - 'install', 09:52:03 INFO - 'run-tests'), 09:52:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:03 INFO - 'download_minidump_stackwalk': True, 09:52:03 INFO - 'download_symbols': 'ondemand', 09:52:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:52:03 INFO - 'tooltool.py': '/tools/tooltool.py', 09:52:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:52:03 INFO - '/tools/misc-python/virtualenv.py')}, 09:52:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:03 INFO - 'installer_path': 'installer.exe', 09:52:03 INFO - 'log_level': 'info', 09:52:03 INFO - 'log_name': 'talos', 09:52:03 INFO - 'log_to_console': True, 09:52:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:52:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:52:03 INFO - 'opt_config_files': (), 09:52:03 INFO - 'pip_index': False, 09:52:03 INFO - 'sps_profile': False, 09:52:03 INFO - 'sps_profile_interval': 0, 09:52:03 INFO - 'suite': 'dromaeojs', 09:52:03 INFO - 'system_bits': '32', 09:52:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:03 INFO - 'title': 'talos-linux64-ix-001', 09:52:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:03 INFO - 'use_talos_json': True, 09:52:03 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:52:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:03 INFO - 'work_dir': 'build'} 09:52:03 INFO - ##### 09:52:03 INFO - ##### Running clobber step. 09:52:03 INFO - ##### 09:52:03 INFO - Running pre-action listener: _resource_record_pre_action 09:52:03 INFO - Running main action method: clobber 09:52:03 INFO - rmtree: /builds/slave/test-pgo/build 09:52:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:52:04 INFO - Running post-action listener: _resource_record_post_action 09:52:04 INFO - ##### 09:52:04 INFO - ##### Running read-buildbot-config step. 09:52:04 INFO - ##### 09:52:04 INFO - Running pre-action listener: _resource_record_pre_action 09:52:04 INFO - Running main action method: read_buildbot_config 09:52:04 INFO - Using buildbot properties: 09:52:04 INFO - { 09:52:04 INFO - "properties": { 09:52:04 INFO - "buildnumber": 92, 09:52:04 INFO - "product": "firefox", 09:52:04 INFO - "script_repo_revision": "production", 09:52:04 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 09:52:04 INFO - "repository": "", 09:52:04 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 09:52:04 INFO - "buildid": "20160222072101", 09:52:04 INFO - "slavename": "talos-linux64-ix-001", 09:52:04 INFO - "pgo_build": "True", 09:52:04 INFO - "basedir": "/builds/slave/test-pgo", 09:52:04 INFO - "project": "", 09:52:04 INFO - "platform": "ubuntu64_hw", 09:52:04 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:52:04 INFO - "slavebuilddir": "test", 09:52:04 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 09:52:04 INFO - "branch": "mozilla-beta", 09:52:04 INFO - "repo_path": "releases/mozilla-beta", 09:52:04 INFO - "stage_platform": "linux64-pgo", 09:52:04 INFO - "builduid": "b499c037fa75445394e488eb40be2791", 09:52:04 INFO - "revision": "e2b86793693f" 09:52:04 INFO - }, 09:52:04 INFO - "sourcestamp": { 09:52:04 INFO - "repository": "", 09:52:04 INFO - "hasPatch": false, 09:52:04 INFO - "project": "", 09:52:04 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:52:04 INFO - "changes": [ 09:52:04 INFO - { 09:52:04 INFO - "category": null, 09:52:04 INFO - "files": [ 09:52:04 INFO - { 09:52:04 INFO - "url": null, 09:52:04 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2" 09:52:04 INFO - } 09:52:04 INFO - ], 09:52:04 INFO - "repository": "", 09:52:04 INFO - "rev": "e2b86793693f", 09:52:04 INFO - "who": "sendchange", 09:52:04 INFO - "when": 1456163304, 09:52:04 INFO - "number": 7636101, 09:52:04 INFO - "comments": "Upgrade to NSS 3.21.1 to pick up the fix for bug 1245528, a=sledru", 09:52:04 INFO - "project": "", 09:52:04 INFO - "at": "Mon 22 Feb 2016 09:48:24", 09:52:04 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 09:52:04 INFO - "revlink": "", 09:52:04 INFO - "properties": [ 09:52:04 INFO - [ 09:52:04 INFO - "buildid", 09:52:04 INFO - "20160222072101", 09:52:04 INFO - "Change" 09:52:04 INFO - ], 09:52:04 INFO - [ 09:52:04 INFO - "builduid", 09:52:04 INFO - "b499c037fa75445394e488eb40be2791", 09:52:04 INFO - "Change" 09:52:04 INFO - ], 09:52:04 INFO - [ 09:52:04 INFO - "pgo_build", 09:52:04 INFO - "True", 09:52:04 INFO - "Change" 09:52:04 INFO - ] 09:52:04 INFO - ], 09:52:04 INFO - "revision": "e2b86793693f" 09:52:04 INFO - } 09:52:04 INFO - ], 09:52:04 INFO - "revision": "e2b86793693f" 09:52:04 INFO - } 09:52:04 INFO - } 09:52:04 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2. 09:52:04 INFO - Running post-action listener: _resource_record_post_action 09:52:04 INFO - ##### 09:52:04 INFO - ##### Running download-and-extract step. 09:52:04 INFO - ##### 09:52:04 INFO - Running pre-action listener: _resource_record_pre_action 09:52:04 INFO - Running main action method: download_and_extract 09:52:04 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/test_packages.json 09:52:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:52:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:52:04 INFO - Downloaded 1270 bytes. 09:52:04 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:52:04 INFO - Using the following test package requirements: 09:52:04 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:52:04 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 09:52:04 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'jsshell-linux-x86_64.zip'], 09:52:04 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 09:52:04 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:52:04 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 09:52:04 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 09:52:04 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 09:52:04 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 09:52:04 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 09:52:04 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 09:52:04 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:52:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:52:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:52:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:52:04 INFO - Downloaded 21581504 bytes. 09:52:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:52:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:52:04 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:52:05 INFO - Return code: 0 09:52:05 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:52:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:52:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:52:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:52:06 INFO - Downloaded 21581504 bytes. 09:52:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 09:52:06 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 09:52:06 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 09:52:07 INFO - Return code: 0 09:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:07 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:52:07 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:52:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:52:07 INFO - Downloaded 11132038 bytes. 09:52:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 09:52:07 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 09:52:07 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 09:52:07 INFO - Return code: 0 09:52:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:07 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:52:07 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:52:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:52:08 INFO - Downloaded 57944791 bytes. 09:52:08 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:52:08 INFO - mkdir: /builds/slave/test-pgo/properties 09:52:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:52:08 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:52:08 INFO - Contents: 09:52:08 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2 09:52:08 INFO - Running post-action listener: _resource_record_post_action 09:52:08 INFO - Running post-action listener: set_extra_try_arguments 09:52:08 INFO - ##### 09:52:08 INFO - ##### Running populate-webroot step. 09:52:08 INFO - ##### 09:52:08 INFO - Running pre-action listener: _resource_record_pre_action 09:52:08 INFO - Running main action method: populate_webroot 09:52:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:52:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:52:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:52:08 INFO - '--tppagecycles', 09:52:08 INFO - '7'], 09:52:08 INFO - 'tests': ['tsvgm']}}, 09:52:08 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:52:08 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:52:08 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:52:08 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:52:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:52:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'tests': ['damp', 'tps']}, 09:52:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['damp', 'tps']}, 09:52:08 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:52:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:52:08 INFO - 'other': {'tests': ['a11yr', 09:52:08 INFO - 'ts_paint', 09:52:08 INFO - 'tpaint', 09:52:08 INFO - 'sessionrestore', 09:52:08 INFO - 'sessionrestore_no_auto_restore']}, 09:52:08 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['a11yr', 09:52:08 INFO - 'ts_paint', 09:52:08 INFO - 'tpaint', 09:52:08 INFO - 'sessionrestore', 09:52:08 INFO - 'sessionrestore_no_auto_restore']}, 09:52:08 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['a11yr', 09:52:08 INFO - 'ts_paint', 09:52:08 INFO - 'tpaint', 09:52:08 INFO - 'sessionrestore', 09:52:08 INFO - 'sessionrestore_no_auto_restore']}, 09:52:08 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['a11yr', 09:52:08 INFO - 'ts_paint', 09:52:08 INFO - 'tpaint', 09:52:08 INFO - 'sessionrestore', 09:52:08 INFO - 'sessionrestore_no_auto_restore']}, 09:52:08 INFO - 'other_l64': {'tests': ['a11yr', 09:52:08 INFO - 'ts_paint', 09:52:08 INFO - 'tpaint', 09:52:08 INFO - 'sessionrestore', 09:52:08 INFO - 'sessionrestore_no_auto_restore']}, 09:52:08 INFO - 'other_nol64': {'tests': ['a11yr', 09:52:08 INFO - 'ts_paint', 09:52:08 INFO - 'tpaint', 09:52:08 INFO - 'sessionrestore', 09:52:08 INFO - 'sessionrestore_no_auto_restore']}, 09:52:08 INFO - 'svgr': {'tests': ['tsvgx', 09:52:08 INFO - 'tsvgr_opacity', 09:52:08 INFO - 'tart', 09:52:08 INFO - 'tscrollx', 09:52:08 INFO - 'cart']}, 09:52:08 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['tsvgx', 09:52:08 INFO - 'tsvgr_opacity', 09:52:08 INFO - 'tart', 09:52:08 INFO - 'tscrollx', 09:52:08 INFO - 'cart']}, 09:52:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'tests': ['tp5o']}, 09:52:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'talos_options': ['--e10s'], 09:52:08 INFO - 'tests': ['tp5o']}, 09:52:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'talos_options': ['--xperf_path', 09:52:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:08 INFO - 'tests': ['tp5n']}, 09:52:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:52:08 INFO - 'talos_options': ['--e10s', 09:52:08 INFO - '--xperf_path', 09:52:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:52:08 INFO - 'tests': ['tp5n']}}, 09:52:08 INFO - 'talos.zip': {'path': '', 09:52:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:52:08 INFO - Running post-action listener: _resource_record_post_action 09:52:08 INFO - ##### 09:52:08 INFO - ##### Running create-virtualenv step. 09:52:08 INFO - ##### 09:52:08 INFO - Running pre-action listener: _resource_record_pre_action 09:52:08 INFO - Running main action method: create_virtualenv 09:52:08 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:52:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 09:52:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:52:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:52:08 INFO - Using real prefix '/usr' 09:52:08 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:52:09 INFO - Installing distribute.............................................................................................................................................................................................done. 09:52:11 INFO - Installing pip.................done. 09:52:11 INFO - Return code: 0 09:52:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:52: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')]} 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52: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')]} 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:11 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:52:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:52:11 INFO - Using env: {'DISPLAY': ':0', 09:52:11 INFO - 'HOME': '/home/cltbld', 09:52:11 INFO - 'LANG': 'en_US.UTF-8', 09:52:11 INFO - 'LANGUAGE': 'en_US:en', 09:52:11 INFO - 'LOGNAME': 'cltbld', 09:52:11 INFO - 'MAIL': '/var/mail/cltbld', 09:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:11 INFO - 'MOZ_NO_REMOTE': '1', 09:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:11 INFO - 'NO_EM_RESTART': '1', 09:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:11 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:11 INFO - 'SHELL': '/bin/bash', 09:52:11 INFO - 'SHLVL': '1', 09:52:11 INFO - 'TERM': 'linux', 09:52:11 INFO - 'TMOUT': '86400', 09:52:11 INFO - 'USER': 'cltbld', 09:52:11 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:11 INFO - '_': '/tools/buildbot/bin/python'} 09:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:52:11 INFO - Cleaning up... 09:52:11 INFO - Return code: 0 09:52:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:52: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')]} 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52: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')]} 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:11 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:52:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:52:11 INFO - Using env: {'DISPLAY': ':0', 09:52:11 INFO - 'HOME': '/home/cltbld', 09:52:11 INFO - 'LANG': 'en_US.UTF-8', 09:52:11 INFO - 'LANGUAGE': 'en_US:en', 09:52:11 INFO - 'LOGNAME': 'cltbld', 09:52:11 INFO - 'MAIL': '/var/mail/cltbld', 09:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:11 INFO - 'MOZ_NO_REMOTE': '1', 09:52:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:11 INFO - 'NO_EM_RESTART': '1', 09:52:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:11 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:11 INFO - 'SHELL': '/bin/bash', 09:52:11 INFO - 'SHLVL': '1', 09:52:11 INFO - 'TERM': 'linux', 09:52:11 INFO - 'TMOUT': '86400', 09:52:11 INFO - 'USER': 'cltbld', 09:52:11 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:11 INFO - '_': '/tools/buildbot/bin/python'} 09:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:11 INFO - Downloading/unpacking psutil>=0.7.1 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:13 INFO - Installing collected packages: psutil 09:52:13 INFO - Running setup.py install for psutil 09:52:13 INFO - building 'psutil._psutil_linux' extension 09:52:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:52:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:52:14 INFO - building 'psutil._psutil_posix' extension 09:52:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:52:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:52:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:14 INFO - Successfully installed psutil 09:52:14 INFO - Cleaning up... 09:52:14 INFO - Return code: 0 09:52:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:14 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:52:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:52:14 INFO - Using env: {'DISPLAY': ':0', 09:52:14 INFO - 'HOME': '/home/cltbld', 09:52:14 INFO - 'LANG': 'en_US.UTF-8', 09:52:14 INFO - 'LANGUAGE': 'en_US:en', 09:52:14 INFO - 'LOGNAME': 'cltbld', 09:52:14 INFO - 'MAIL': '/var/mail/cltbld', 09:52:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:14 INFO - 'MOZ_NO_REMOTE': '1', 09:52:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:14 INFO - 'NO_EM_RESTART': '1', 09:52:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:14 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:14 INFO - 'SHELL': '/bin/bash', 09:52:14 INFO - 'SHLVL': '1', 09:52:14 INFO - 'TERM': 'linux', 09:52:14 INFO - 'TMOUT': '86400', 09:52:14 INFO - 'USER': 'cltbld', 09:52:14 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:14 INFO - '_': '/tools/buildbot/bin/python'} 09:52:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:52:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:52:16 INFO - Installing collected packages: mozsystemmonitor 09:52:16 INFO - Running setup.py install for mozsystemmonitor 09:52:16 INFO - Successfully installed mozsystemmonitor 09:52:16 INFO - Cleaning up... 09:52:16 INFO - Return code: 0 09:52:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:16 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:52:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:52:16 INFO - Using env: {'DISPLAY': ':0', 09:52:16 INFO - 'HOME': '/home/cltbld', 09:52:16 INFO - 'LANG': 'en_US.UTF-8', 09:52:16 INFO - 'LANGUAGE': 'en_US:en', 09:52:16 INFO - 'LOGNAME': 'cltbld', 09:52:16 INFO - 'MAIL': '/var/mail/cltbld', 09:52:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:16 INFO - 'MOZ_NO_REMOTE': '1', 09:52:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:16 INFO - 'NO_EM_RESTART': '1', 09:52:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:16 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:16 INFO - 'SHELL': '/bin/bash', 09:52:16 INFO - 'SHLVL': '1', 09:52:16 INFO - 'TERM': 'linux', 09:52:16 INFO - 'TMOUT': '86400', 09:52:16 INFO - 'USER': 'cltbld', 09:52:16 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:16 INFO - '_': '/tools/buildbot/bin/python'} 09:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:52:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:19 INFO - Downloading docopt-0.6.1.tar.gz 09:52:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:19 INFO - Running setup.py install for blobuploader 09:52:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:19 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:52:19 INFO - Running setup.py install for requests 09:52:19 INFO - Running setup.py install for docopt 09:52:19 INFO - Successfully installed blobuploader requests docopt 09:52:19 INFO - Cleaning up... 09:52:19 INFO - Return code: 0 09:52:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:19 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:52:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:19 INFO - Using env: {'DISPLAY': ':0', 09:52:19 INFO - 'HOME': '/home/cltbld', 09:52:19 INFO - 'LANG': 'en_US.UTF-8', 09:52:19 INFO - 'LANGUAGE': 'en_US:en', 09:52:19 INFO - 'LOGNAME': 'cltbld', 09:52:19 INFO - 'MAIL': '/var/mail/cltbld', 09:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:19 INFO - 'MOZ_NO_REMOTE': '1', 09:52:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:19 INFO - 'NO_EM_RESTART': '1', 09:52:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:19 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:19 INFO - 'SHELL': '/bin/bash', 09:52:19 INFO - 'SHLVL': '1', 09:52:19 INFO - 'TERM': 'linux', 09:52:19 INFO - 'TMOUT': '86400', 09:52:19 INFO - 'USER': 'cltbld', 09:52:19 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:19 INFO - '_': '/tools/buildbot/bin/python'} 09:52:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:52:20 INFO - Running setup.py (path:/tmp/pip-tvhw1w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:52:20 INFO - Running setup.py (path:/tmp/pip-m5suzk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:52:20 INFO - Running setup.py (path:/tmp/pip-3Hz2FD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:52:20 INFO - Running setup.py (path:/tmp/pip-ni76Y0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:52:20 INFO - Running setup.py (path:/tmp/pip-FXAdlI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:52:20 INFO - Running setup.py (path:/tmp/pip-ZWOjKk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:52:20 INFO - Running setup.py (path:/tmp/pip-ZQo62h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:52:20 INFO - Running setup.py (path:/tmp/pip-W2kYlf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:52:20 INFO - Running setup.py (path:/tmp/pip-B4GTNB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:52:20 INFO - Running setup.py (path:/tmp/pip-ULn90L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:52:20 INFO - Running setup.py (path:/tmp/pip-1Clvqc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:52:20 INFO - Running setup.py (path:/tmp/pip-vLgszn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:52:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:52:20 INFO - Running setup.py (path:/tmp/pip-FTFmV1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:52:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:52:21 INFO - Running setup.py (path:/tmp/pip-dan9eD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:52:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:52:21 INFO - Running setup.py (path:/tmp/pip-0AwW5W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:52:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:52:21 INFO - Running setup.py (path:/tmp/pip-rVpFC5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:52:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:52:21 INFO - Running setup.py (path:/tmp/pip-_srY6r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:52:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:52:21 INFO - Running setup.py install for manifestparser 09:52:21 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:52:21 INFO - Running setup.py install for mozcrash 09:52:21 INFO - Running setup.py install for mozdebug 09:52:21 INFO - Running setup.py install for mozdevice 09:52:21 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:52:21 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:52:21 INFO - Running setup.py install for mozfile 09:52:21 INFO - Running setup.py install for mozhttpd 09:52:21 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:52:21 INFO - Running setup.py install for mozinfo 09:52:22 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Running setup.py install for mozInstall 09:52:22 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Running setup.py install for mozleak 09:52:22 INFO - Running setup.py install for mozlog 09:52:22 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Running setup.py install for moznetwork 09:52:22 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Running setup.py install for mozprocess 09:52:22 INFO - Running setup.py install for mozprofile 09:52:22 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Running setup.py install for mozrunner 09:52:22 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:52:22 INFO - Running setup.py install for mozscreenshot 09:52:22 INFO - Running setup.py install for moztest 09:52:23 INFO - Running setup.py install for mozversion 09:52:23 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:52:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:52:23 INFO - Cleaning up... 09:52:23 INFO - Return code: 0 09:52:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:23 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:52:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:23 INFO - Using env: {'DISPLAY': ':0', 09:52:23 INFO - 'HOME': '/home/cltbld', 09:52:23 INFO - 'LANG': 'en_US.UTF-8', 09:52:23 INFO - 'LANGUAGE': 'en_US:en', 09:52:23 INFO - 'LOGNAME': 'cltbld', 09:52:23 INFO - 'MAIL': '/var/mail/cltbld', 09:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:23 INFO - 'MOZ_NO_REMOTE': '1', 09:52:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:23 INFO - 'NO_EM_RESTART': '1', 09:52:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:23 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:23 INFO - 'SHELL': '/bin/bash', 09:52:23 INFO - 'SHLVL': '1', 09:52:23 INFO - 'TERM': 'linux', 09:52:23 INFO - 'TMOUT': '86400', 09:52:23 INFO - 'USER': 'cltbld', 09:52:23 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:23 INFO - '_': '/tools/buildbot/bin/python'} 09:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:52:23 INFO - Running setup.py (path:/tmp/pip-C8xkho-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:52:23 INFO - Running setup.py (path:/tmp/pip-iKqE_4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:52:23 INFO - Running setup.py (path:/tmp/pip-7QGxSk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:52:23 INFO - Running setup.py (path:/tmp/pip-PTkwGc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:52:23 INFO - Running setup.py (path:/tmp/pip-zj4Ge8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:52:23 INFO - Running setup.py (path:/tmp/pip-U3gOh0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:52:23 INFO - Running setup.py (path:/tmp/pip-HQfZ8V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:52:23 INFO - Running setup.py (path:/tmp/pip-u_58Vb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:52:23 INFO - Running setup.py (path:/tmp/pip-Il84yq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 09:52:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:52:24 INFO - Running setup.py (path:/tmp/pip-euRkFc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:52:24 INFO - Running setup.py (path:/tmp/pip-TUSZpu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:52:24 INFO - Running setup.py (path:/tmp/pip-7UUKPA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:52:24 INFO - Running setup.py (path:/tmp/pip-uEOIyQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:52:24 INFO - Running setup.py (path:/tmp/pip-ox5bRX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:52:24 INFO - Running setup.py (path:/tmp/pip-Fifvqa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:52:24 INFO - Running setup.py (path:/tmp/pip-3I5cLC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 09:52:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:52:24 INFO - Running setup.py (path:/tmp/pip-vuNmWi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:52:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:26 INFO - Downloading blessings-1.6.tar.gz 09:52:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:52:26 INFO - Installing collected packages: blessings 09:52:26 INFO - Running setup.py install for blessings 09:52:26 INFO - Successfully installed blessings 09:52:26 INFO - Cleaning up... 09:52:26 INFO - Return code: 0 09:52:26 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:52:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:52:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:52:26 INFO - Reading from file tmpfile_stdout 09:52:26 INFO - Current package versions: 09:52:26 INFO - argparse == 1.2.1 09:52:26 INFO - blessings == 1.6 09:52:26 INFO - blobuploader == 1.2.4 09:52:26 INFO - docopt == 0.6.1 09:52:26 INFO - manifestparser == 1.1 09:52:26 INFO - mozInstall == 1.12 09:52:26 INFO - mozcrash == 0.16 09:52:26 INFO - mozdebug == 0.1 09:52:26 INFO - mozdevice == 0.47 09:52:26 INFO - mozfile == 1.2 09:52:26 INFO - mozhttpd == 0.7 09:52:26 INFO - mozinfo == 0.9 09:52:26 INFO - mozleak == 0.1 09:52:26 INFO - mozlog == 3.1 09:52:26 INFO - moznetwork == 0.27 09:52:26 INFO - mozprocess == 0.22 09:52:26 INFO - mozprofile == 0.27 09:52:26 INFO - mozrunner == 6.11 09:52:26 INFO - mozscreenshot == 0.1 09:52:26 INFO - mozsystemmonitor == 0.0 09:52:26 INFO - moztest == 0.7 09:52:26 INFO - mozversion == 1.4 09:52:26 INFO - psutil == 3.1.1 09:52:26 INFO - requests == 1.2.3 09:52:26 INFO - wsgiref == 0.1.2 09:52:26 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:26 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:52:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:26 INFO - Using env: {'DISPLAY': ':0', 09:52:26 INFO - 'HOME': '/home/cltbld', 09:52:26 INFO - 'LANG': 'en_US.UTF-8', 09:52:26 INFO - 'LANGUAGE': 'en_US:en', 09:52:26 INFO - 'LOGNAME': 'cltbld', 09:52:26 INFO - 'MAIL': '/var/mail/cltbld', 09:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:26 INFO - 'MOZ_NO_REMOTE': '1', 09:52:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:26 INFO - 'NO_EM_RESTART': '1', 09:52:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:26 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:26 INFO - 'SHELL': '/bin/bash', 09:52:26 INFO - 'SHLVL': '1', 09:52:26 INFO - 'TERM': 'linux', 09:52:26 INFO - 'TMOUT': '86400', 09:52:26 INFO - 'USER': 'cltbld', 09:52:26 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:26 INFO - '_': '/tools/buildbot/bin/python'} 09:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 09:52:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 09:52:27 INFO - Cleaning up... 09:52:27 INFO - Return code: 0 09:52:27 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:27 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 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:52:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:52:27 INFO - Using env: {'DISPLAY': ':0', 09:52:27 INFO - 'HOME': '/home/cltbld', 09:52:27 INFO - 'LANG': 'en_US.UTF-8', 09:52:27 INFO - 'LANGUAGE': 'en_US:en', 09:52:27 INFO - 'LOGNAME': 'cltbld', 09:52:27 INFO - 'MAIL': '/var/mail/cltbld', 09:52:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:27 INFO - 'MOZ_NO_REMOTE': '1', 09:52:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:27 INFO - 'NO_EM_RESTART': '1', 09:52:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:27 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:27 INFO - 'SHELL': '/bin/bash', 09:52:27 INFO - 'SHLVL': '1', 09:52:27 INFO - 'TERM': 'linux', 09:52:27 INFO - 'TMOUT': '86400', 09:52:27 INFO - 'USER': 'cltbld', 09:52:27 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:27 INFO - '_': '/tools/buildbot/bin/python'} 09:52:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:27 INFO - Downloading/unpacking jsonschema 09:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:52:29 INFO - Downloading/unpacking functools32 (from jsonschema) 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:29 INFO - Downloading functools32-3.2.3-2.tar.gz 09:52:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:52:29 INFO - warning: no files found matching '*.txt' 09:52:29 INFO - no previously-included directories found matching 'build' 09:52:29 INFO - no previously-included directories found matching 'dist' 09:52:29 INFO - no previously-included directories found matching '.git*' 09:52:29 INFO - Installing collected packages: jsonschema, functools32 09:52:29 INFO - Running setup.py install for functools32 09:52:29 INFO - warning: no files found matching '*.txt' 09:52:29 INFO - no previously-included directories found matching 'build' 09:52:29 INFO - no previously-included directories found matching 'dist' 09:52:29 INFO - no previously-included directories found matching '.git*' 09:52:29 INFO - Successfully installed jsonschema functools32 09:52:29 INFO - Cleaning up... 09:52:29 INFO - Return code: 0 09:52:29 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:52:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4eb2a04f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21418a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x220e600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, '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': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:52:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:52:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:52:29 INFO - Using env: {'DISPLAY': ':0', 09:52:29 INFO - 'HOME': '/home/cltbld', 09:52:29 INFO - 'LANG': 'en_US.UTF-8', 09:52:29 INFO - 'LANGUAGE': 'en_US:en', 09:52:29 INFO - 'LOGNAME': 'cltbld', 09:52:29 INFO - 'MAIL': '/var/mail/cltbld', 09:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:29 INFO - 'MOZ_NO_REMOTE': '1', 09:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:29 INFO - 'NO_EM_RESTART': '1', 09:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:29 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:29 INFO - 'SHELL': '/bin/bash', 09:52:29 INFO - 'SHLVL': '1', 09:52:29 INFO - 'TERM': 'linux', 09:52:29 INFO - 'TMOUT': '86400', 09:52:29 INFO - 'USER': 'cltbld', 09:52:29 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:29 INFO - '_': '/tools/buildbot/bin/python'} 09:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:29 INFO - Downloading/unpacking flake8 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:52:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:52:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:52:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:52:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:52:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:52:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:52:32 INFO - no previously-included directories found matching 'docs/_build' 09:52:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:52:32 INFO - Running setup.py install for pep8 09:52:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:52:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:52:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:52:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:52:32 INFO - no previously-included directories found matching 'docs/_build' 09:52:32 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:52:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:52:32 INFO - Cleaning up... 09:52:32 INFO - Return code: 0 09:52:32 INFO - Running post-action listener: _resource_record_post_action 09:52:32 INFO - Running post-action listener: _start_resource_monitoring 09:52:32 INFO - Starting resource monitoring. 09:52:32 INFO - ##### 09:52:32 INFO - ##### Running install step. 09:52:32 INFO - ##### 09:52:32 INFO - Running pre-action listener: _resource_record_pre_action 09:52:32 INFO - Running main action method: install 09:52:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:52:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:52:32 INFO - Reading from file tmpfile_stdout 09:52:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:52:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:52:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:52:32 INFO - Reading from file tmpfile_stdout 09:52:32 INFO - Output received: 09:52:32 INFO - Usage: mozinstall [options] installer 09:52:32 INFO - Options: 09:52:32 INFO - -h, --help show this help message and exit 09:52:32 INFO - -d DEST, --destination=DEST 09:52:32 INFO - Directory to install application into. [default: 09:52:32 INFO - "/builds/slave/test-pgo"] 09:52:32 INFO - --app=APP Application being installed. [default: firefox] 09:52:32 INFO - mkdir: /builds/slave/test-pgo/build/application 09:52:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:52:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:52:47 INFO - Reading from file tmpfile_stdout 09:52:47 INFO - Output received: 09:52:47 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:52:47 INFO - Running post-action listener: _resource_record_post_action 09:52:47 INFO - ##### 09:52:47 INFO - ##### Running run-tests step. 09:52:47 INFO - ##### 09:52:47 INFO - Running pre-action listener: _resource_record_pre_action 09:52:47 INFO - Running main action method: run_tests 09:52:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:52:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:52:47 INFO - Python 2.7.3 09:52:47 INFO - Return code: 0 09:52:47 INFO - grabbing minidump binary from tooltool 09:52: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')]} 09:52:47 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 0x229d350>, '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 0x229e250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x229e6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:52:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 09:52:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:52:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:52:47 INFO - Return code: 0 09:52:47 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:52:47 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:52:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:52:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:52:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:52:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:52:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:52:48 INFO - Return code: 0 09:52:48 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/1456154461/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 09:52:48 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/1456154461/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost 09:52:48 INFO - Using env: {'DISPLAY': ':0', 09:52:48 INFO - 'HOME': '/home/cltbld', 09:52:48 INFO - 'LANG': 'en_US.UTF-8', 09:52:48 INFO - 'LANGUAGE': 'en_US:en', 09:52:48 INFO - 'LOGNAME': 'cltbld', 09:52:48 INFO - 'MAIL': '/var/mail/cltbld', 09:52:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:52:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:48 INFO - 'MOZ_NO_REMOTE': '1', 09:52:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:52:48 INFO - 'NO_EM_RESTART': '1', 09:52:48 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:52:48 INFO - 'PWD': '/builds/slave/test-pgo', 09:52:48 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:52:48 INFO - 'SHELL': '/bin/bash', 09:52:48 INFO - 'SHLVL': '1', 09:52:48 INFO - 'TERM': 'linux', 09:52:48 INFO - 'TMOUT': '86400', 09:52:48 INFO - 'USER': 'cltbld', 09:52:48 INFO - 'XDG_SESSION_COOKIE': '8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695', 09:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:48 INFO - '_': '/tools/buildbot/bin/python'} 09:52:48 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/1456154461/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost'] with output_timeout 3600 09:52:48 INFO - mozversion INFO | application_buildid: 20160222072101 09:52:48 INFO - mozversion INFO | application_changeset: e2b86793693f179f15f19e3d3a5bb048b3cd59eb 09:52:48 INFO - mozversion INFO | application_display_name: Nightly 09:52:48 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:52:48 INFO - mozversion INFO | application_name: Firefox 09:52:48 INFO - mozversion INFO | application_remotingname: firefox 09:52:48 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:52:48 INFO - mozversion INFO | application_vendor: Mozilla 09:52:48 INFO - mozversion INFO | application_version: 45.0 09:52:48 INFO - mozversion INFO | platform_buildid: 20160222072101 09:52:48 INFO - mozversion INFO | platform_changeset: e2b86793693f179f15f19e3d3a5bb048b3cd59eb 09:52:48 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:52:48 INFO - mozversion INFO | platform_version: 45.0 09:52:48 INFO - 2016-02-22 09:52:48,783 DEBUG : using testdate: 1456163568 09:52:48 INFO - 2016-02-22 09:52:48,783 DEBUG : actual date: 1456163568 09:52:48 INFO - 2016-02-22 09:52:48,794 INFO : starting webserver on 'localhost:46359' 09:52:48 INFO - 2016-02-22 09:52:48,795 INFO : Starting test suite talos-linux64-ix-001 09:52:48 INFO - 2016-02-22 09:52:48,795 INFO : Starting test dromaeo_css 09:52:48 INFO - 2016-02-22 09:52:48,795 DEBUG : operating with platform_type : linux_ 09:52:48 INFO - 2016-02-22 09:52:48,796 INFO : Initialising browser for dromaeo_css test... 09:52:48 INFO - 2016-02-22 09:52:48,805 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoZ_YmK/profile http://localhost:46359/getInfo.html 09:52:52 INFO - 2016-02-22 09:52:52,836 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:52:52 INFO - 2016-02-22 09:52:52,837 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:52:52 INFO - 2016-02-22 09:52:52,837 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:52:52 INFO - 2016-02-22 09:52:52,837 DEBUG : BROWSER_OUTPUT: __metrics 09:52:52 INFO - 2016-02-22 09:52:52,998 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:52:54 INFO - 2016-02-22 09:52:54,118 INFO : Browser initialized. 09:52:54 INFO - 2016-02-22 09:52:54,119 INFO : Running cycle 1/1 for dromaeo_css test... 09:52:54 INFO - 2016-02-22 09:52:54,119 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoZ_YmK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:53:05 INFO - 2016-02-22 09:53:05,751 DEBUG : BROWSER_OUTPUT: RSS: Main: 134864896 09:53:05 INFO - 2016-02-22 09:53:05,752 DEBUG : BROWSER_OUTPUT: 09:53:56 INFO - 2016-02-22 09:53:56,500 DEBUG : BROWSER_OUTPUT: 1456163636497 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:56:04 INFO - 2016-02-22 09:56:04,548 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46359/tests/dromaeo/cssquery-ext.html) 09:56:05 INFO - 2016-02-22 09:56:05,653 DEBUG : BROWSER_OUTPUT: RSS: Main: 210743296 09:56:05 INFO - 2016-02-22 09:56:05,653 DEBUG : BROWSER_OUTPUT: 09:59:07 INFO - 2016-02-22 09:59:07,687 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/dromaeo/cssquery-ext.html (next: http://localhost:46359/tests/dromaeo/cssquery-jquery.html) 09:59:08 INFO - 2016-02-22 09:59:08,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 215248896 09:59:08 INFO - 2016-02-22 09:59:08,253 DEBUG : BROWSER_OUTPUT: 10:02:10 INFO - 2016-02-22 10:02:10,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46359/tests/dromaeo/cssquery-mootools.html) 10:02:10 INFO - 2016-02-22 10:02:10,711 DEBUG : BROWSER_OUTPUT: RSS: Main: 207360000 10:02:10 INFO - 2016-02-22 10:02:10,712 DEBUG : BROWSER_OUTPUT: 10:05:12 INFO - 2016-02-22 10:05:12,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46359/tests/dromaeo/cssquery-prototype.html) 10:05:12 INFO - 2016-02-22 10:05:12,953 DEBUG : BROWSER_OUTPUT: RSS: Main: 194883584 10:05:12 INFO - 2016-02-22 10:05:12,953 DEBUG : BROWSER_OUTPUT: 10:08:15 INFO - 2016-02-22 10:08:15,331 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46359/tests/dromaeo/cssquery-yui.html) 10:08:15 INFO - 2016-02-22 10:08:15,913 DEBUG : BROWSER_OUTPUT: RSS: Main: 200544256 10:08:15 INFO - 2016-02-22 10:08:15,913 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,188 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/dromaeo/cssquery-yui.html (next: http://localhost:46359/tests/dromaeo/cssquery-dojo.html) 10:11:18 INFO - 2016-02-22 10:11:18,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 232112128 10:11:18 INFO - 2016-02-22 10:11:18,432 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,433 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:11:18 INFO - 2016-02-22 10:11:18,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:11:18 INFO - 2016-02-22 10:11:18,434 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:11:18 INFO - 2016-02-22 10:11:18,434 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:11:18 INFO - 2016-02-22 10:11:18,437 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3283.9343213135735;3327.48423994281;3356.6643335666454;3361.07570418135;3383.509391138285;5469.917951230735;5488.478594533518;5505.3393592768825;5516.558675305971;5529.198266251391;5496.725163741817;5506.284183056207;5518.586106042043;5536.141898005805;5539.390667026624;4870.220764677652;5017.749112544377;5028.547430731233;5104.412992505872;5112.642115051948;4916.975415122919;4977.6266779991465;4979.004199160165;4984.675996060245;4985.6759310644875;4954.479779623141;4973.204287314031;5025.3718285214345;5029.47190544993;5071.949280507203;5051.343325367716;5087.160618497944;5117.02776472469;5117.897642047175;5144.691318520863;4986.700797952134;5046.318746969149;5080.288759573626;5104.464031276737;5106.540411362958;5175.637705360627;5248.973755131238;5272.6836389816735;5351.491608297206;5352.4915133062295;5832.066869300891;5866.76532938682;5871.706414679292;5879.941200587982;5880.382559831199;5659.434056594308;5920.348761636216;5921.289445266596;5938.287405511323;5952.761889524457;5389.568834493231;5410.48600382963;5482.616216864861;5485.232067510552;5515.448455154493;5928.881422371528;5958.582899197015;5971.5222782177325;5976.581639285208;5977.222961015127;5605.187247849039;5746.109353050284;5776.046952252831;5795.362510123883;5802.970985145132;5875.706214689249;5922.170896074553;5939.495142912814;5941.70291485424;5995.820125396245;5778.479936805707;5827.329857066492;5833.854153646193;5839.299284085937;5854.2389489366105;5672;5746.454086861741;5779.826605201851;5831.241938548046;5836.708164591753;4492.775361231926;4495.595396414338;4497.797599108048;4498.797554110073;4512.503624601291;4027.0737730321603;4030.274550580865;4036.2734707752297;4037.7981100944835;4058.6347228748964;5472;5558.666480011212;5560;5632.436756324417;5638.915416268759;5925.377835327315;5953.910691339634;5958.523318134538;5961.986462301419;5971.313298970587;5626.493615574618;5631.549476041989;5634.436556344414;5643.097104463348;5647.209390685307;4960.107180707508;5027.824026159067;5034.0686972910125;5116.411612664517;5149.0474262261605;6065.059915713072;6070.180525629072;6081.452669259788;6114.449699526975;6116.785912493041;5925.762969481172;5928.822135335947;5935.34711181761;5939.4951429129005;5939.673317967467;5876.382979787061;5938.168656388023;5998.670073145844;6049.697515124314;6053.909191362045;5853.824385268449;5893.410658934073;5902.822915312548;5907.91138132937;5931.3178984417345;5967.343592204767;5971.552133590085;5974.731137098754;5974.970125149346;5981.790637327673;5605.691686957257;5636.267285252891;5715.9142612860005;5753.252077229933;5778.075507918713;5747.109198074305;5830.679312637845;5838.620489668158;5839;5842.737076831468;5732.828015159552;5754.856128596819;5757.481826635623;5769.0481070624555;5769.74036168365;5576.219329293983;5675.233843431084;5760.596758227051;5854.385289544536;5877.764889404547;5587.525060369914;5638.126090455986;5649.265595472562;5655.038643430709;5667.291588551431 10:11:18 INFO - 2016-02-22 10:11:18,438 DEBUG : BROWSER_OUTPUT: |1;ext.html;602.7498588085945;629.7386584567407;637.1557686065962;646.8576913079122;667.946564274858;30186.641601127976;30507.38985220294;30531.389372212543;30531.847340763292;37763.24473510535;44824.55175448245;45478.772606137005;45569;45731.54268457314;45817.770911145395;8975;9118.544072796367;9150.359474836763;9184.816303673922;9194.172524472797;8231.382646301521;8400.537970411626;8898.110188981082;10417.791644167111;10632.946835265813;10141.847872281922;10289.485525723721;10290.43402612856;10290.639827606035;10300.175985921145;10256.846147307797;10299;10363;10374.688759337232;10375.221858360615;10200.642977495752;10318.535665894977;10332.58669653213;10337.586496540129;10342.32774869631;38448.23103537913;38827.611723882685;38860.80569597162;42946.78526607347;43136.56863431357;3711.4989476420606;3720.479132921393;3776.754510956779;3803.1442925341853;4167.91664166715;30517.847410763025;30775;30794.538081928564;30917.536236956465;30931.072067838002;4251.426057482231;4277.1873344064525;4313.84901528445;4326.221280169569;4331.805068771881;281315;283846.99999999796;283935;284526;285560;58712;59438;65846.34153658498;66527.66736166384;66955.66522167456;66004.33995660079;66054.33945660578;66381.66809165923;66432.0140791554;66441.33558664448;17059.744103838453;17252.827471725373;17286.13569321554;17329;17374.65250694979;12470.189437686522;12475.064370172091;12485.250884946932;12487.188332758342;12490.937545312216;22172.778272217394;22420.55158896813;22457.663135052986;22542.887285563465;22564.548709025727;8255.25702686761;8360.665573376997;8392.664293428317;8405.747827565183;8455.112213217646;1006.5822683586376;1047.0210353319671;1095.39753135775;1121.3440137519478;1199.8560172779323;28785;28848;29218;29464.410711785644;29529.85235073811;1084.2789544952518;1122.938238396896;1193.6657735833808;1197.2397527570056;1246.5948566715854;15074.924625376803;15650.843491565167;15788.60528486797;17337.306507739802;18345.449636510926;401496;402801;405285;407777;408119;59759.70120149371;60078.69960650169;60220.69889650524;60242.69878650666;60267;1422.4950142699192;1442.4302400551724;1446.2913172545586;1460.1677044084981;1463.0782606957762;28591;33836.66163338335;34084.488732668535;34147.65852341445;34527.65472345244;53498.19752703635;53901;54016.45983540272;54036.72981635224;54112.45887541074;331148;331973;334086;334685;335198;35081.64918350784;40913.1817363657;41503.7924810374;52705.73647131893;52890;49507.50492495173;51351;51614.74192629013;51676;52828.73585632047;41736.79131604323;42502.149957001304;43254.351184731626;43727.78136109299;44005;42639.7868010658;42895.7855210722;43093;43834;43865.34201987037;37868.2426351477;42224.36663449989;42574.1485170301;47047.47143964148;47132.52867471419;35370.46944295889;38142.23715525618;38628;39081.41377879391;39374.2125157501;28985;29175.54122293919;29492.705072948997;29649.5552566716;29677.406451871273 10:11:18 INFO - 2016-02-22 10:11:18,439 DEBUG : BROWSER_OUTPUT: |2;jquery.html;384.4751913637885;388.04153740261546;388.77062533105465;390.07552101519394;398.26718837339297;14876.256187190624;17139.485815425527;17923.82076179238;19434;19443.611127777436;17094.572635684115;17575.82424175758;17639.91180044098;17857;18202.908985455055;5057.69653820772;5133.076046311662;5160.56135228506;5164.92252616211;5180.818671346504;4008.158286759784;4132.5040995080635;5123.077845987721;5485.862853428676;5637.548996080303;5555.3611334696425;5601.411851755568;5602.551795856321;5622.240997465351;5627.493525582697;5449.482299181572;5567.109262517978;5570.2480165177585;5573.247611572447;5576.86057848555;5339.199120131972;5362.222477740745;5368.275282836845;5381.838544843661;5388.568914486832;19250.614987700308;21018.684719729215;21396.679049814265;21534.353969380943;21541.353759387242;2270.0579259607243;2287.5081857400737;2321.3152120124605;2356.7289761677457;2402.4354276744953;9305.581248843751;9445.291603129794;9669.516524173763;10052.24608154384;10984.505697243643;1885.8397036251936;1947.6202140582593;1954.5211423201265;1984.82136607706;2014.8085931836451;61472.69263653698;61674.69162654203;62252;63430.99999999954;64028.35971640271;10827.133829293638;10888.836667449994;10913.83629245562;10921.071708904674;10957.671269861918;14312.713745725026;14351.641208969859;14368.13791172533;14390.712185756227;14416.567502974927;7816.921830781619;7929.603519823986;7949.523028618186;8003.8799418008775;8024.117347091816;7584.469087163956;7587.886181707278;7592.202818703957;7633.313001829751;7651.043619547557;4631.143238500888;4669.112868554964;4671.766411679402;4686.718796872198;4704.9059018819435;1742.8782064611187;1812.9456116316533;1830.1947143256925;1926.2776458827939;1932.7584051993501;900.9819803604022;902.1339514066439;956.31623389277;985.7387792235063;1023.8993082436261;14942.327595258239;16522.83477165237;17101.743473847902;17785.288588456577;18814.52963675919;968.2931460034215;968.5931908598264;976.9364991275544;986.6793292180071;1005.9245556583139;9241.260699144052;9384.8592271116;9760.853587196198;9946.303758736964;10425.687229383131;442234;455064;624013;647678;651774;81692.59153704194;84418.57790711007;86196;88336.11663883408;89629.55185224031;1224.761171571535;1226.4848763519344;1262.1732765038914;1272.4719241514854;1304.8564657887625;14474;15022.399104036152;15627.609309767347;16303;16406.261718222948;36336.818315908255;36467.27065458623;36758.63241367552;37493.4375984355;37668.81165594154;45406.545934540234;45517.54482455266;46689.29966050444;46797;46885.29672054854;9928.900710992797;10393.168546516412;10502.264841461025;10510.369377837354;10677.09244714208;12389.93805030969;12407.751844963232;12565.18326308787;12566.811497827723;17282.913585431994;14842.073897496763;15601.063936163866;15721.764173537178;15742.606434839221;15780.526584202493;15452.5364239073;15484.767728484307;15617.14105724196;15883.841161588236;15968;11779.764404711686;12456.252624842538;14670.853291467376;15270.77093843571;16534.421295254608;10007.149392301735;10135.64525241613;10218.948905255425;10235.948820255851;10269.794604108025;10395.94802025985;11704.94147529257;12798;14912.179830109015;15579.376824927329 10:11:18 INFO - 2016-02-22 10:11:18,440 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1490.5379332406956;1538.6153461634588;1610.9919450402747;1689.1993015714643;1810.5926166612514;7547.773566792998;8042.678292868278;8054.637541310641;8075.959620201891;8077.959610201948;7751.496152750082;7794.94768206292;7800.3759699224065;7806.180351063128;7815.413843961697;2350.459394339303;2374.6200607902783;2415.855048697075;2417.2506522977847;2417.4802417480237;2409.229046705055;2438.804895608347;2439.878006099697;2440.2191298784305;2440.633904914268;1999.2902519605475;2039.0416504243055;2334.9182778602753;2345.4839935214227;2350.976490235093;2346.1319311854636;2352.3883790214495;2373.0151986925407;2376.5603363377745;2379.988100059506;2316.1661801751357;2358.375030616871;2359.622460406327;2359.6814430051895;2361.8110551155864;6716.1604799400075;7055.118110236221;7428.331450169457;7695.653695583712;7706.113796913371;4673.322368256584;4706.199946009152;4727.030958653502;4733.644976626767;4737.431508219001;3112.548680441346;3121.8595163217483;3142.701443362877;3150.2754366495606;3240.627327857303;1022.3303736052898;1022.8772547294298;1060.363781730963;1060.8779990301136;1061.4374381577777;10819.621313254078;10853.34879907208;10878.238523303293;10962.835557466644;10967.122630189566;4191.517975432804;4232.4286221360335;4320.697551171451;4344.500382456058;4353.216421044242;9023.097690230925;9023.639054437881;9052.094790520896;9069.047749986288;9080.273578113867;4738.099761045391;4821.132196204647;4834.299026641188;4835.516448355207;4836.032793441255;4389.253826849444;4418.911621767546;4521.367008618796;4525.52481989393;4531.864044078683;3570.0003998879833;3570.0360902556163;3587.1390866191928;3593.1915319053005;3614.5120408744483;995.66147509847;1007.8135544924213;1012.86832711747;1033.1631378583372;1034.13132968307;1191.8271850581616;1192.8807119288;1295.4559085184246;1414.3918115210352;1456.9198694071715;7478.85042299151;7501;7610.315071643578;7618.780995040767;7629.122650895218;518.1631664861222;522.2271038862505;522.4488164985976;527.0197432775035;538.8141091323487;5793.826185214451;6009.879802404015;6097.085437184369;6109.633421994695;6114.144019837227;8876.689315874042;9323.114304141096;9421.152096311365;9446.197073248713;9464.90535094654;9107.817843643223;9246.630134794668;9275.443473391619;9290.44257344548;9339.626414943326;7146.3568278855155;7281.708731650675;7285.052943117361;7292.671829767673;7315.049043624294;7351.448641352027;7401.962990185015;7408.6666100026705;7411.110666720028;7442;8164.5917704115745;8203.0566484855;8289.295409889986;8317.126701696236;8440;8354.665813367397;8397.70608028716;8411.411201215857;8586.484810911365;8738.475691458532;6683.8663226734225;6743.2582415935185;6760.729570817112;6766.932330676827;6768.950812624051;7505.549667019996;7693.153753087267;7695.038120234971;7716.266954639525;7728.304452599293;7026.051483049723;7091.893621595577;7104.715811367694;7139.2503787101605;7176.959340895645;7087.929120708727;7151.964240178766;7165.641717913979;7257.455690823315;7277.963610181915;7280.817979550554;7319.853602927805;7329.560226386434;7343.926560734538;7371.705131794668;6210.503159747119;6335.1130841683125;6356.046593011085;6803.93196068033;6854.622995735083;6717.126773519607;6792.0830687856505;6803.353681400259;6805.081314022544;6811.421029212573 10:11:18 INFO - 2016-02-22 10:11:18,442 DEBUG : BROWSER_OUTPUT: |4;prototype.html;340.1224839913025;341.4991114053734;343.90370696205065;349.9055255081128;350.0548518998704;5751.884962300752;5808.303003639564;5876.412358764122;5890.823275301743;5985.88028239435;5684.204211410395;5841.065429531276;5841.240638716961;5858.091995740667;5865.941340586593;4570.314452832085;4570.862874113774;4572.7942242599165;4577.992841574848;4583.220852455074;4406.206882761102;4484.708493947899;4532.52408497108;4572.748498832563;4573.8399156029545;4496.213162696531;4510.797014134372;4521.751303678297;4522.412086428776;4532.886677833064;4542.591166794988;4548.272276435771;4548.340490628873;4550.954490455086;4578.725276483422;4055.9188816223805;4059.025833799896;4063.6952228582677;4066.2477441673386;4078.8164532596106;5445.809396671098;5561.805336813232;5580.804671836507;5612.550995920316;5617.522510586605;5247.895042099175;5262.342207224097;5271.578273738091;5276.7889284428575;5285.577153827685;5952;6274.905876411857;6281.057841323793;6285.120083188312;6287.025511045796;4266.978665106654;4272.572742725733;4320;4321.243782338078;4336.848210312655;6411.711472983727;6466.579672321331;6475.83810404739;6477.384648458342;6478.74085036598;4516.299973504047;4519.5480451954545;4520.593146616755;4521.299198624153;4526.728396296233;4503.549645035536;4576.725396476222;4578.175928332932;4581.931271030937;4584.381108550369;5258.5267325940085;5263.210518422267;5270.92093618596;5272.762725677354;5275.103232450492;6294.8111556653375;6310;6311.274203466568;6311.463525600284;6318.462930650854;1587.6030992251938;1614.555997100783;1615.063263307279;1615.111688571281;1615.2569817883905;1084.2897901874285;1086.869575650927;1091.1325496230515;1097.05653138301;1114.1254115519278;6039.456448919531;6078;6092.360302168208;6092.5430592705725;6098.390160983866;6470.611763294218;6473.967630161819;6475.125858009203;6478.319776423502;6490.286068532458;6035.426634469718;6134.969325153345;6153.261608606996;6155.599886007395;6163.3528479509905;3489.8778542750883;3490.650934906489;3516.7538272320694;3517.1383011161915;3519.524864143359;6655.933440665628;6669.266380698119;6675.0988616537115;6678.165229346332;6678.599284042973;6566.080748695186;6610.272869984298;6624.8012559623285;6626.734930602722;6630.900536491957;6499.610023398611;6647.634380109141;6666.400023997863;6670.132882725215;6671.766488172988;6261.123442718114;6366;6476.255230648348;6483.189601299838;6489.837754056186;6253.437190652863;6266.436020758154;6273;6277.309495955533;6408.03879418091;6692.531522793358;6702.329767023259;6707.027481015128;6712.765053223114;6717.361850624183;4431.379606855107;4590.265557510782;4590.793414296431;4590.862274131781;4596.195665758412;6505;6505.512086593619;6512.381323774234;6515.0553169791065;6519.4784417245555;6342.334054924167;6359.20509936258;6364.77723279683;6366.5225108118;6367.522435817425;6219.284782250099;6322.272938612118;6330.208723909512;6332.240131184103;6335.239771227482;6209.223847019122;6220.720067596879;6225.097360882737;6226.377362263918;6233.906491402723;6339.841503962438;6344.809655710336;6351.364863513611;6354.665520240616;6377.330380310001;6096.939030609815;6216.036514340284;6217.253929528305;6218.067289906369;6229.657368844757 10:11:18 INFO - 2016-02-22 10:11:18,443 DEBUG : BROWSER_OUTPUT: |5;yui.html;762.36342653884;769.6844293839523;839.0770152831885;901.1709227510689;901.1754244865948;13356.332183390829;13621.318934053295;13651.590452286448;13654.863451365483;13680.589582312534;12509.374531273423;12578.622641320751;12600.180988235757;12600.684982875433;12668;733.8825787873928;748.3115533708974;748.9513181643204;749.4042236422031;875.5184648443362;885.154677283193;893.9374243802937;894.2354287084546;894.3962825093037;895;923.4643906534194;929.1776777551847;936.1901954809075;936.4053825820585;937.9296552758536;763.3130182835437;776.6000509737489;925.7222833150062;927.2257664849858;931.2270815223349;581.9156222347779;583.0467186150631;674.8515326628134;678.871014507247;684.2815044203616;13670.111442756288;13739.450421983109;13751.518696845562;13768.862311376959;13790.241536715477;12319.568815091518;12672.936635316857;12801.167924084904;13317.53388631403;13374.732505349839;11117.833232501518;11159.442027898573;11188.496517656726;11441.084713223005;11753.4710938008;3808.6572208501093;3829.3872980323013;3875.2830726315724;3879.941800872989;3909.2181563687377;48993.51006489961;49292;49657.50342496565;49691;51474.22788658173;10487.265891387682;10502.579896804195;10557.788844223227;10599.258051936291;10721.731956701145;18730.250789968522;18774.1551630177;19522;19693.803061969196;19761.901190493954;7772.611369431619;7839;7927.5243485391065;8072.354211663052;8098.959505202554;4322.416473776063;4418.911621767546;4450.777461126931;4483.282674772021;4676.134915040729;3170.381775553791;3175.7300629446295;3197.088829683529;3197.216681912946;3205.0705295464522;569.2940753465756;583.0117950074583;585.28887401807;590.7459792289357;595.6188039654625;726.4878260826105;736.5359823311279;740.8962745215674;754.7584772872738;765.9846803064019;8891.599878005505;8979.922409310924;9093.77265568366;9162.541872906462;9277.257819374434;743.7917383132732;754.5813869924507;762.4738930138187;763.050002746584;778.766370088971;4003.279409706281;4369.08249267651;4373.212821692063;4439.16090843796;4538.909221815546;56681.149782753295;56685.14972275419;57136;57272.42727572754;57480.137797933065;50664.49335506598;51199.7440012805;51523.22715159276;51535.22697159545;51926;45558.54441455609;46246;47107.764461177474;47232;47737.76131119322;8589.355798315277;8757.824843502967;8840.867386989328;8933.508657023925;8987.370884038055;34987.769116647156;35697;36264.45603316005;36572;36647;43292.40245585275;45950;46454.30318545157;46569.13723451024;46706.29940550827;11730.472128754056;11777.175597708077;11926.940365298118;12096.455659495168;12239;13015.154014989374;13196.472141114247;13222.272774997467;13355.599332020054;13820.23988680592;11402.771944561227;11842.644720658394;12088.516459341528;12274.87725122773;12656.810147847973;10845;10852.620158294421;10897.945510272399;11145.712014955445;11372.601958931398;11778.175527712978;11983.400829958293;12324.50701971947;12751;12838.743225135258;10637.840432393676;10810.945945270223;10825.945870270598;10829.566817327219;11408.429578521207;10037.49812509357;10147.49262536885;10206.438645874548;10523.368597884151;10843.783124337628 10:11:18 INFO - 2016-02-22 10:11:18,443 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:11:18 INFO - 2016-02-22 10:11:18,443 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:11:18 INFO - 2016-02-22 10:11:18,443 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,886 10:11:18 INFO - 2016-02-22 10:11:18,443 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:11:18 INFO - 2016-02-22 10:11:18,443 DEBUG : BROWSER_OUTPUT: __startTimestamp1456164678432__endTimestamp 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5471.58 Median:5655.04 stddev:599.22 (10.6%) stddev-sans-first:575.96 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: Values: 3283.9 3327.5 3356.7 3361.1 3383.5 5469.9 5488.5 5505.3 5516.6 5529.2 5496.7 5506.3 5518.6 5536.1 5539.4 4870.2 5017.7 5028.5 5104.4 5112.6 4917.0 4977.6 4979.0 4984.7 4985.7 4954.5 4973.2 5025.4 5029.5 5071.9 5051.3 5087.2 5117.0 5117.9 5144.7 4986.7 5046.3 5080.3 5104.5 5106.5 5175.6 5249.0 5272.7 5351.5 5352.5 5832.1 5866.8 5871.7 5879.9 5880.4 5659.4 5920.3 5921.3 5938.3 5952.8 5389.6 5410.5 5482.6 5485.2 5515.4 5928.9 5958.6 5971.5 5976.6 5977.2 5605.2 5746.1 5776.0 5795.4 5803.0 5875.7 5922.2 5939.5 5941.7 5995.8 5778.5 5827.3 5833.9 5839.3 5854.2 5672.0 5746.5 5779.8 5831.2 5836.7 4492.8 4495.6 4497.8 4498.8 4512.5 4027.1 4030.3 4036.3 4037.8 4058.6 5472.0 5558.7 5560.0 5632.4 5638.9 5925.4 5953.9 5958.5 5962.0 5971.3 5626.5 5631.5 5634.4 5643.1 5647.2 4960.1 5027.8 5034.1 5116.4 5149.0 6065.1 6070.2 6081.5 6114.4 6116.8 5925.8 5928.8 5935.3 5939.5 5939.7 5876.4 5938.2 5998.7 6049.7 6053.9 5853.8 5893.4 5902.8 5907.9 5931.3 5967.3 5971.6 5974.7 5975.0 5981.8 5605.7 5636.3 5715.9 5753.3 5778.1 5747.1 5830.7 5838.6 5839.0 5842.7 5732.8 5754.9 5757.5 5769.0 5769.7 5576.2 5675.2 5760.6 5854.4 5877.8 5587.5 5638.1 5649.3 5655.0 5667.3 10:11:18 INFO - 2016-02-22 10:11:18,444 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,445 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53027.36 Median:20770.00 stddev:90258.90 (434.6%) stddev-sans-first:90426.27 10:11:18 INFO - 2016-02-22 10:11:18,445 DEBUG : BROWSER_OUTPUT: Values: 602.7 629.7 637.2 646.9 667.9 30186.6 30507.4 30531.4 30531.8 37763.2 44824.6 45478.8 45569.0 45731.5 45817.8 8975.0 9118.5 9150.4 9184.8 9194.2 8231.4 8400.5 8898.1 10417.8 10632.9 10141.8 10289.5 10290.4 10290.6 10300.2 10256.8 10299.0 10363.0 10374.7 10375.2 10200.6 10318.5 10332.6 10337.6 10342.3 38448.2 38827.6 38860.8 42946.8 43136.6 3711.5 3720.5 3776.8 3803.1 4167.9 30517.8 30775.0 30794.5 30917.5 30931.1 4251.4 4277.2 4313.8 4326.2 4331.8 281315.0 283847.0 283935.0 284526.0 285560.0 58712.0 59438.0 65846.3 66527.7 66955.7 66004.3 66054.3 66381.7 66432.0 66441.3 17059.7 17252.8 17286.1 17329.0 17374.7 12470.2 12475.1 12485.3 12487.2 12490.9 22172.8 22420.6 22457.7 22542.9 22564.5 8255.3 8360.7 8392.7 8405.7 8455.1 1006.6 1047.0 1095.4 1121.3 1199.9 28785.0 28848.0 29218.0 29464.4 29529.9 1084.3 1122.9 1193.7 1197.2 1246.6 15074.9 15650.8 15788.6 17337.3 18345.4 401496.0 402801.0 405285.0 407777.0 408119.0 59759.7 60078.7 60220.7 60242.7 60267.0 1422.5 1442.4 1446.3 1460.2 1463.1 28591.0 33836.7 34084.5 34147.7 34527.7 53498.2 53901.0 54016.5 54036.7 54112.5 331148.0 331973.0 334086.0 334685.0 335198.0 35081.6 40913.2 41503.8 52705.7 52890.0 49507.5 51351.0 51614.7 51676.0 52828.7 41736.8 42502.1 43254.4 43727.8 44005.0 42639.8 42895.8 43093.0 43834.0 43865.3 37868.2 42224.4 42574.1 47047.5 47132.5 35370.5 38142.2 38628.0 39081.4 39374.2 28985.0 29175.5 29492.7 29649.6 29677.4 10:11:18 INFO - 2016-02-22 10:11:18,445 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,445 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29967.17 Median:2304.41 stddev:93572.91 (4060.6%) stddev-sans-first:93809.04 10:11:18 INFO - 2016-02-22 10:11:18,446 DEBUG : BROWSER_OUTPUT: Values: 384.5 388.0 388.8 390.1 398.3 14876.3 17139.5 17923.8 19434.0 19443.6 17094.6 17575.8 17639.9 17857.0 18202.9 5057.7 5133.1 5160.6 5164.9 5180.8 4008.2 4132.5 5123.1 5485.9 5637.5 5555.4 5601.4 5602.6 5622.2 5627.5 5449.5 5567.1 5570.2 5573.2 5576.9 5339.2 5362.2 5368.3 5381.8 5388.6 19250.6 21018.7 21396.7 21534.4 21541.4 2270.1 2287.5 2321.3 2356.7 2402.4 9305.6 9445.3 9669.5 10052.2 10984.5 1885.8 1947.6 1954.5 1984.8 2014.8 61472.7 61674.7 62252.0 63431.0 64028.4 10827.1 10888.8 10913.8 10921.1 10957.7 14312.7 14351.6 14368.1 14390.7 14416.6 7816.9 7929.6 7949.5 8003.9 8024.1 7584.5 7587.9 7592.2 7633.3 7651.0 4631.1 4669.1 4671.8 4686.7 4704.9 1742.9 1812.9 1830.2 1926.3 1932.8 901.0 902.1 956.3 985.7 1023.9 14942.3 16522.8 17101.7 17785.3 18814.5 968.3 968.6 976.9 986.7 1005.9 9241.3 9384.9 9760.9 9946.3 10425.7 442234.0 455064.0 624013.0 647678.0 651774.0 81692.6 84418.6 86196.0 88336.1 89629.6 1224.8 1226.5 1262.2 1272.5 1304.9 14474.0 15022.4 15627.6 16303.0 16406.3 36336.8 36467.3 36758.6 37493.4 37668.8 45406.5 45517.5 46689.3 46797.0 46885.3 9928.9 10393.2 10502.3 10510.4 10677.1 12389.9 12407.8 12565.2 12566.8 17282.9 14842.1 15601.1 15721.8 15742.6 15780.5 15452.5 15484.8 15617.1 15883.8 15968.0 11779.8 12456.3 14670.9 15270.8 16534.4 10007.1 10135.6 10218.9 10235.9 10269.8 10395.9 11704.9 12798.0 14912.2 15579.4 10:11:18 INFO - 2016-02-22 10:11:18,446 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,446 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5507.63 Median:6345.58 stddev:2849.39 (44.9%) stddev-sans-first:2841.39 10:11:18 INFO - 2016-02-22 10:11:18,447 DEBUG : BROWSER_OUTPUT: Values: 1490.5 1538.6 1611.0 1689.2 1810.6 7547.8 8042.7 8054.6 8076.0 8078.0 7751.5 7794.9 7800.4 7806.2 7815.4 2350.5 2374.6 2415.9 2417.3 2417.5 2409.2 2438.8 2439.9 2440.2 2440.6 1999.3 2039.0 2334.9 2345.5 2351.0 2346.1 2352.4 2373.0 2376.6 2380.0 2316.2 2358.4 2359.6 2359.7 2361.8 6716.2 7055.1 7428.3 7695.7 7706.1 4673.3 4706.2 4727.0 4733.6 4737.4 3112.5 3121.9 3142.7 3150.3 3240.6 1022.3 1022.9 1060.4 1060.9 1061.4 10819.6 10853.3 10878.2 10962.8 10967.1 4191.5 4232.4 4320.7 4344.5 4353.2 9023.1 9023.6 9052.1 9069.0 9080.3 4738.1 4821.1 4834.3 4835.5 4836.0 4389.3 4418.9 4521.4 4525.5 4531.9 3570.0 3570.0 3587.1 3593.2 3614.5 995.7 1007.8 1012.9 1033.2 1034.1 1191.8 1192.9 1295.5 1414.4 1456.9 7478.9 7501.0 7610.3 7618.8 7629.1 518.2 522.2 522.4 527.0 538.8 5793.8 6009.9 6097.1 6109.6 6114.1 8876.7 9323.1 9421.2 9446.2 9464.9 9107.8 9246.6 9275.4 9290.4 9339.6 7146.4 7281.7 7285.1 7292.7 7315.0 7351.4 7402.0 7408.7 7411.1 7442.0 8164.6 8203.1 8289.3 8317.1 8440.0 8354.7 8397.7 8411.4 8586.5 8738.5 6683.9 6743.3 6760.7 6766.9 6769.0 7505.5 7693.2 7695.0 7716.3 7728.3 7026.1 7091.9 7104.7 7139.3 7177.0 7087.9 7152.0 7165.6 7257.5 7278.0 7280.8 7319.9 7329.6 7343.9 7371.7 6210.5 6335.1 6356.0 6803.9 6854.6 6717.1 6792.1 6803.4 6805.1 6811.4 10:11:18 INFO - 2016-02-22 10:11:18,447 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,448 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5263.00 Median:6010.65 stddev:1568.91 (26.1%) stddev-sans-first:1529.18 10:11:18 INFO - 2016-02-22 10:11:18,448 DEBUG : BROWSER_OUTPUT: Values: 340.1 341.5 343.9 349.9 350.1 5751.9 5808.3 5876.4 5890.8 5985.9 5684.2 5841.1 5841.2 5858.1 5865.9 4570.3 4570.9 4572.8 4578.0 4583.2 4406.2 4484.7 4532.5 4572.7 4573.8 4496.2 4510.8 4521.8 4522.4 4532.9 4542.6 4548.3 4548.3 4551.0 4578.7 4055.9 4059.0 4063.7 4066.2 4078.8 5445.8 5561.8 5580.8 5612.6 5617.5 5247.9 5262.3 5271.6 5276.8 5285.6 5952.0 6274.9 6281.1 6285.1 6287.0 4267.0 4272.6 4320.0 4321.2 4336.8 6411.7 6466.6 6475.8 6477.4 6478.7 4516.3 4519.5 4520.6 4521.3 4526.7 4503.5 4576.7 4578.2 4581.9 4584.4 5258.5 5263.2 5270.9 5272.8 5275.1 6294.8 6310.0 6311.3 6311.5 6318.5 1587.6 1614.6 1615.1 1615.1 1615.3 1084.3 1086.9 1091.1 1097.1 1114.1 6039.5 6078.0 6092.4 6092.5 6098.4 6470.6 6474.0 6475.1 6478.3 6490.3 6035.4 6135.0 6153.3 6155.6 6163.4 3489.9 3490.7 3516.8 3517.1 3519.5 6655.9 6669.3 6675.1 6678.2 6678.6 6566.1 6610.3 6624.8 6626.7 6630.9 6499.6 6647.6 6666.4 6670.1 6671.8 6261.1 6366.0 6476.3 6483.2 6489.8 6253.4 6266.4 6273.0 6277.3 6408.0 6692.5 6702.3 6707.0 6712.8 6717.4 4431.4 4590.3 4590.8 4590.9 4596.2 6505.0 6505.5 6512.4 6515.1 6519.5 6342.3 6359.2 6364.8 6366.5 6367.5 6219.3 6322.3 6330.2 6332.2 6335.2 6209.2 6220.7 6225.1 6226.4 6233.9 6339.8 6344.8 6351.4 6354.7 6377.3 6096.9 6216.0 6217.3 6218.1 6229.7 10:11:18 INFO - 2016-02-22 10:11:18,448 DEBUG : BROWSER_OUTPUT: 10:11:18 INFO - 2016-02-22 10:11:18,448 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14209.78 Median:4429.04 stddev:16086.89 (363.2%) stddev-sans-first:16100.32 10:11:18 INFO - 2016-02-22 10:11:18,448 DEBUG : BROWSER_OUTPUT: Values: 762.4 769.7 839.1 901.2 901.2 13356.3 13621.3 13651.6 13654.9 13680.6 12509.4 12578.6 12600.2 12600.7 12668.0 733.9 748.3 749.0 749.4 875.5 885.2 893.9 894.2 894.4 895.0 923.5 929.2 936.2 936.4 937.9 763.3 776.6 925.7 927.2 931.2 581.9 583.0 674.9 678.9 684.3 13670.1 13739.5 13751.5 13768.9 13790.2 12319.6 12672.9 12801.2 13317.5 13374.7 11117.8 11159.4 11188.5 11441.1 11753.5 3808.7 3829.4 3875.3 3879.9 3909.2 48993.5 49292.0 49657.5 49691.0 51474.2 10487.3 10502.6 10557.8 10599.3 10721.7 18730.3 18774.2 19522.0 19693.8 19761.9 7772.6 7839.0 7927.5 8072.4 8099.0 4322.4 4418.9 4450.8 4483.3 4676.1 3170.4 3175.7 3197.1 3197.2 3205.1 569.3 583.0 585.3 590.7 595.6 726.5 736.5 740.9 754.8 766.0 8891.6 8979.9 9093.8 9162.5 9277.3 743.8 754.6 762.5 763.1 778.8 4003.3 4369.1 4373.2 4439.2 4538.9 56681.1 56685.1 57136.0 57272.4 57480.1 50664.5 51199.7 51523.2 51535.2 51926.0 45558.5 46246.0 47107.8 47232.0 47737.8 8589.4 8757.8 8840.9 8933.5 8987.4 34987.8 35697.0 36264.5 36572.0 36647.0 43292.4 45950.0 46454.3 46569.1 46706.3 11730.5 11777.2 11926.9 12096.5 12239.0 13015.2 13196.5 13222.3 13355.6 13820.2 11402.8 11842.6 12088.5 12274.9 12656.8 10845.0 10852.6 10897.9 11145.7 11372.6 11778.2 11983.4 12324.5 12751.0 12838.7 10637.8 10810.9 10825.9 10829.6 11408.4 10037.5 10147.5 10206.4 10523.4 10843.8 10:11:18 INFO - 2016-02-22 10:11:18,450 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:11:18 INFO - 2016-02-22 10:11:18,450 DEBUG : BROWSER_OUTPUT: 10:11:19 INFO - 2016-02-22 10:11:19,015 INFO : Browser exited with error code: 0 10:11:19 INFO - 2016-02-22 10:11:19,033 INFO : Completed test dromaeo_css (00:18:30) 10:11:19 INFO - 2016-02-22 10:11:19,033 INFO : Starting test kraken 10:11:19 INFO - 2016-02-22 10:11:19,033 DEBUG : operating with platform_type : linux_ 10:11:19 INFO - 2016-02-22 10:11:19,033 INFO : Initialising browser for kraken test... 10:11:19 INFO - 2016-02-22 10:11:19,039 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpE8M4_F/profile http://localhost:46359/getInfo.html 10:11:22 INFO - 2016-02-22 10:11:22,716 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:11:22 INFO - 2016-02-22 10:11:22,716 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:11:22 INFO - 2016-02-22 10:11:22,716 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:11:22 INFO - 2016-02-22 10:11:22,716 DEBUG : BROWSER_OUTPUT: __metrics 10:11:22 INFO - 2016-02-22 10:11:22,873 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:24 INFO - 2016-02-22 10:11:24,054 INFO : Browser initialized. 10:11:24 INFO - 2016-02-22 10:11:24,055 INFO : Running cycle 1/1 for kraken test... 10:11:24 INFO - 2016-02-22 10:11:24,055 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpE8M4_F/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:11:35 INFO - 2016-02-22 10:11:35,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 132395008 10:11:35 INFO - 2016-02-22 10:11:35,651 DEBUG : BROWSER_OUTPUT: 10:11:38 INFO - 2016-02-22 10:11:38,069 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46359/tests/kraken/driver.html?testName=audio-beat-detection) 10:11:38 INFO - 2016-02-22 10:11:38,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 166019072 10:11:38 INFO - 2016-02-22 10:11:38,650 DEBUG : BROWSER_OUTPUT: 10:11:41 INFO - 2016-02-22 10:11:41,240 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46359/tests/kraken/driver.html?testName=audio-dft) 10:11:41 INFO - 2016-02-22 10:11:41,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 163942400 10:11:41 INFO - 2016-02-22 10:11:41,807 DEBUG : BROWSER_OUTPUT: 10:11:44 INFO - 2016-02-22 10:11:44,611 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46359/tests/kraken/driver.html?testName=audio-fft) 10:11:45 INFO - 2016-02-22 10:11:45,177 DEBUG : BROWSER_OUTPUT: RSS: Main: 161669120 10:11:45 INFO - 2016-02-22 10:11:45,177 DEBUG : BROWSER_OUTPUT: 10:11:47 INFO - 2016-02-22 10:11:47,170 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46359/tests/kraken/driver.html?testName=audio-oscillator) 10:11:47 INFO - 2016-02-22 10:11:47,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 161288192 10:11:47 INFO - 2016-02-22 10:11:47,731 DEBUG : BROWSER_OUTPUT: 10:11:49 INFO - 2016-02-22 10:11:49,925 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46359/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:11:50 INFO - 2016-02-22 10:11:50,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 160399360 10:11:50 INFO - 2016-02-22 10:11:50,492 DEBUG : BROWSER_OUTPUT: 10:11:53 INFO - 2016-02-22 10:11:53,377 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46359/tests/kraken/driver.html?testName=imaging-darkroom) 10:11:53 INFO - 2016-02-22 10:11:53,943 DEBUG : BROWSER_OUTPUT: RSS: Main: 160354304 10:11:53 INFO - 2016-02-22 10:11:53,944 DEBUG : BROWSER_OUTPUT: 10:11:57 INFO - 2016-02-22 10:11:57,346 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46359/tests/kraken/driver.html?testName=imaging-desaturate) 10:11:57 INFO - 2016-02-22 10:11:57,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 161591296 10:11:57 INFO - 2016-02-22 10:11:57,908 DEBUG : BROWSER_OUTPUT: 10:12:00 INFO - 2016-02-22 10:12:00,627 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46359/tests/kraken/driver.html?testName=json-parse-financial) 10:12:01 INFO - 2016-02-22 10:12:01,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 159956992 10:12:01 INFO - 2016-02-22 10:12:01,194 DEBUG : BROWSER_OUTPUT: 10:12:02 INFO - 2016-02-22 10:12:02,763 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46359/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:12:03 INFO - 2016-02-22 10:12:03,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 167038976 10:12:03 INFO - 2016-02-22 10:12:03,316 DEBUG : BROWSER_OUTPUT: 10:12:05 INFO - 2016-02-22 10:12:05,071 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:12:05 INFO - 2016-02-22 10:12:05,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 165478400 10:12:05 INFO - 2016-02-22 10:12:05,657 DEBUG : BROWSER_OUTPUT: 10:12:07 INFO - 2016-02-22 10:12:07,614 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:12:08 INFO - 2016-02-22 10:12:08,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 169824256 10:12:08 INFO - 2016-02-22 10:12:08,183 DEBUG : BROWSER_OUTPUT: 10:12:10 INFO - 2016-02-22 10:12:10,504 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:12:11 INFO - 2016-02-22 10:12:11,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 173539328 10:12:11 INFO - 2016-02-22 10:12:11,086 DEBUG : BROWSER_OUTPUT: 10:12:13 INFO - 2016-02-22 10:12:13,542 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:12:14 INFO - 2016-02-22 10:12:14,104 DEBUG : BROWSER_OUTPUT: RSS: Main: 173801472 10:12:14 INFO - 2016-02-22 10:12:14,104 DEBUG : BROWSER_OUTPUT: 10:12:15 INFO - 2016-02-22 10:12:15,754 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46359/tests/kraken/driver.html?testName=ai-astar) 10:12:16 INFO - 2016-02-22 10:12:16,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 231854080 10:12:16 INFO - 2016-02-22 10:12:16,003 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,005 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:12:16 INFO - 2016-02-22 10:12:16,006 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:12:16 INFO - 2016-02-22 10:12:16,006 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:12:16 INFO - 2016-02-22 10:12:16,007 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:12:16 INFO - 2016-02-22 10:12:16,007 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;103;108;109;108;98;109;108;108 10:12:16 INFO - 2016-02-22 10:12:16,007 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;122;155;165;122;152;151;122;161;122 10:12:16 INFO - 2016-02-22 10:12:16,007 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;159;160;160;160;159;163;161;160 10:12:16 INFO - 2016-02-22 10:12:16,008 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;156;71;72;72;70;148;72 10:12:16 INFO - 2016-02-22 10:12:16,008 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;136;91;91;92;132;92;94;136 10:12:16 INFO - 2016-02-22 10:12:16,008 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;111;108;110;109;110;109 10:12:16 INFO - 2016-02-22 10:12:16,008 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;145;145;146;145;147;145;145 10:12:16 INFO - 2016-02-22 10:12:16,009 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;94;95;95;95;96;95;94;95;95;95 10:12:16 INFO - 2016-02-22 10:12:16,009 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;59;58;59;58;57;57;56;56 10:12:16 INFO - 2016-02-22 10:12:16,009 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;65;65;65;65;64;64;64;64;64 10:12:16 INFO - 2016-02-22 10:12:16,009 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;74;78;78;77;77;78;78;76;77;77 10:12:16 INFO - 2016-02-22 10:12:16,009 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;109;123;112;109;109;111;114;118;112;114 10:12:16 INFO - 2016-02-22 10:12:16,010 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;135;140;131;143;145;140;139;144;131 10:12:16 INFO - 2016-02-22 10:12:16,010 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;63;64;63;63;64;62;65;62;62 10:12:16 INFO - 2016-02-22 10:12:16,010 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:12:16 INFO - 2016-02-22 10:12:16,010 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:12:16 INFO - 2016-02-22 10:12:16,011 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,894 10:12:16 INFO - 2016-02-22 10:12:16,011 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:12:16 INFO - 2016-02-22 10:12:16,011 DEBUG : BROWSER_OUTPUT: __startTimestamp1456164736005__endTimestamp 10:12:16 INFO - 2016-02-22 10:12:16,011 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,013 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:12:16 INFO - 2016-02-22 10:12:16,013 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:12:16 INFO - 2016-02-22 10:12:16,013 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,014 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.80 Median:108.50 stddev:3.61 (3.3%) stddev-sans-first:3.83 10:12:16 INFO - 2016-02-22 10:12:16,014 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 103.0 108.0 109.0 108.0 98.0 109.0 108.0 108.0 10:12:16 INFO - 2016-02-22 10:12:16,014 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,014 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.00 Median:151.50 stddev:17.88 (11.8%) stddev-sans-first:18.83 10:12:16 INFO - 2016-02-22 10:12:16,014 DEBUG : BROWSER_OUTPUT: Values: 148.0 122.0 155.0 165.0 122.0 152.0 151.0 122.0 161.0 122.0 10:12:16 INFO - 2016-02-22 10:12:16,014 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 159.0 160.0 160.0 160.0 159.0 163.0 161.0 160.0 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.40 Median:71.00 stddev:34.11 (48.0%) stddev-sans-first:35.65 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 156.0 71.0 72.0 72.0 70.0 148.0 72.0 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.00 Median:92.00 stddev:20.52 (22.3%) stddev-sans-first:21.30 10:12:16 INFO - 2016-02-22 10:12:16,015 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 136.0 91.0 91.0 92.0 132.0 92.0 94.0 136.0 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.84 (0.8%) stddev-sans-first:0.88 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 111.0 108.0 110.0 109.0 110.0 109.0 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 145.0 145.0 146.0 145.0 147.0 145.0 145.0 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,016 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: Values: 94.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.50 stddev:1.07 (1.9%) stddev-sans-first:1.13 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 59.0 58.0 59.0 58.0 57.0 57.0 56.0 56.0 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.53 10:12:16 INFO - 2016-02-22 10:12:16,017 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 65.0 65.0 65.0 64.0 64.0 64.0 64.0 64.0 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.50 stddev:1.25 (1.6%) stddev-sans-first:0.71 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: Values: 74.0 78.0 78.0 77.0 77.0 78.0 78.0 76.0 77.0 77.0 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.10 Median:113.00 stddev:4.48 (4.0%) stddev-sans-first:4.50 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: Values: 109.0 123.0 112.0 109.0 109.0 111.0 114.0 118.0 112.0 114.0 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,018 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.80 Median:140.00 stddev:4.98 (3.6%) stddev-sans-first:5.27 10:12:16 INFO - 2016-02-22 10:12:16,019 DEBUG : BROWSER_OUTPUT: Values: 140.0 135.0 140.0 131.0 143.0 145.0 140.0 139.0 144.0 131.0 10:12:16 INFO - 2016-02-22 10:12:16,019 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,019 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.50 stddev:1.58 (2.5%) stddev-sans-first:1.05 10:12:16 INFO - 2016-02-22 10:12:16,019 DEBUG : BROWSER_OUTPUT: Values: 67.0 63.0 64.0 63.0 63.0 64.0 62.0 65.0 62.0 62.0 10:12:16 INFO - 2016-02-22 10:12:16,019 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:12:16 INFO - 2016-02-22 10:12:16,019 DEBUG : BROWSER_OUTPUT: 10:12:16 INFO - 2016-02-22 10:12:16,675 INFO : Browser exited with error code: 0 10:12:16 INFO - 2016-02-22 10:12:16,725 INFO : Completed test kraken (00:00:57) 10:12:16 INFO - 2016-02-22 10:12:16,725 INFO : Starting test v8_7 10:12:16 INFO - 2016-02-22 10:12:16,726 DEBUG : operating with platform_type : linux_ 10:12:16 INFO - 2016-02-22 10:12:16,726 INFO : Initialising browser for v8_7 test... 10:12:16 INFO - 2016-02-22 10:12:16,732 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphZTgJf/profile http://localhost:46359/getInfo.html 10:12:20 INFO - 2016-02-22 10:12:20,558 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:12:20 INFO - 2016-02-22 10:12:20,558 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:12:20 INFO - 2016-02-22 10:12:20,558 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:12:20 INFO - 2016-02-22 10:12:20,559 DEBUG : BROWSER_OUTPUT: __metrics 10:12:20 INFO - 2016-02-22 10:12:20,727 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:12:21 INFO - 2016-02-22 10:12:21,866 INFO : Browser initialized. 10:12:21 INFO - 2016-02-22 10:12:21,867 INFO : Running cycle 1/1 for v8_7 test... 10:12:21 INFO - 2016-02-22 10:12:21,867 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphZTgJf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:12:33 INFO - 2016-02-22 10:12:33,563 DEBUG : BROWSER_OUTPUT: RSS: Main: 132689920 10:12:33 INFO - 2016-02-22 10:12:33,563 DEBUG : BROWSER_OUTPUT: 10:12:54 INFO - 2016-02-22 10:12:54,920 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46359/tests/v8_7/run.html (next: http://localhost:46359/tests/v8_7/run.html) 10:12:55 INFO - 2016-02-22 10:12:55,171 DEBUG : BROWSER_OUTPUT: RSS: Main: 421527552 10:12:55 INFO - 2016-02-22 10:12:55,171 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,172 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:12:55 INFO - 2016-02-22 10:12:55,172 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:12:55 INFO - 2016-02-22 10:12:55,172 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:12:55 INFO - 2016-02-22 10:12:55,172 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |0;Richards;164.12276382734285 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.79894528675015 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.39643652561244 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |4;RayTrace;956.9377990430622 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |5;Earley;543.773790103317 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 10:12:55 INFO - 2016-02-22 10:12:55,173 DEBUG : BROWSER_OUTPUT: |7;RegExp;24000 10:12:55 INFO - 2016-02-22 10:12:55,174 DEBUG : BROWSER_OUTPUT: |8;Splay;524.6589716684156 10:12:55 INFO - 2016-02-22 10:12:55,174 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 10:12:55 INFO - 2016-02-22 10:12:55,174 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:12:55 INFO - 2016-02-22 10:12:55,174 DEBUG : BROWSER_OUTPUT: __startTimestamp1456164775170__endTimestamp 10:12:55 INFO - 2016-02-22 10:12:55,174 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,175 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:12:55 INFO - 2016-02-22 10:12:55,175 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:12:55 INFO - 2016-02-22 10:12:55,175 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,175 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.12 Median:164.12 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,175 DEBUG : BROWSER_OUTPUT: Values: 164.1 10:12:55 INFO - 2016-02-22 10:12:55,176 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,176 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.80 Median:164.80 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,177 DEBUG : BROWSER_OUTPUT: Values: 164.8 10:12:55 INFO - 2016-02-22 10:12:55,177 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,177 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.40 Median:278.40 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,177 DEBUG : BROWSER_OUTPUT: Values: 278.4 10:12:55 INFO - 2016-02-22 10:12:55,177 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,178 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,178 DEBUG : BROWSER_OUTPUT: Values: 5202.1 10:12:55 INFO - 2016-02-22 10:12:55,178 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,178 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:956.94 Median:956.94 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,178 DEBUG : BROWSER_OUTPUT: Values: 956.9 10:12:55 INFO - 2016-02-22 10:12:55,179 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,179 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:543.77 Median:543.77 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,179 DEBUG : BROWSER_OUTPUT: Values: 543.8 10:12:55 INFO - 2016-02-22 10:12:55,179 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,179 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,180 DEBUG : BROWSER_OUTPUT: Values: 10526.3 10:12:55 INFO - 2016-02-22 10:12:55,180 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,180 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24000.00 Median:24000.00 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,180 DEBUG : BROWSER_OUTPUT: Values: 24000.0 10:12:55 INFO - 2016-02-22 10:12:55,180 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,181 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:524.66 Median:524.66 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,181 DEBUG : BROWSER_OUTPUT: Values: 524.7 10:12:55 INFO - 2016-02-22 10:12:55,181 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,181 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 10:12:55 INFO - 2016-02-22 10:12:55,181 DEBUG : BROWSER_OUTPUT: Values: 4845.4 10:12:55 INFO - 2016-02-22 10:12:55,182 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:12:55 INFO - 2016-02-22 10:12:55,182 DEBUG : BROWSER_OUTPUT: 10:12:55 INFO - 2016-02-22 10:12:55,826 INFO : Browser exited with error code: 0 10:12:55 INFO - 2016-02-22 10:12:55,843 INFO : Completed test v8_7 (00:00:39) 10:12:56 INFO - 2016-02-22 10:12:56,233 INFO : Completed test suite (00:20:07) 10:12:56 INFO - 2016-02-22 10:12:56,233 DEBUG : Working with test: dromaeo_css 10:12:56 INFO - 2016-02-22 10:12:56,233 DEBUG : Generating results file: dromaeo_css 10:12:56 INFO - 2016-02-22 10:12:56,234 DEBUG : Working with test: kraken 10:12:56 INFO - 2016-02-22 10:12:56,234 DEBUG : Generating results file: kraken 10:12:56 INFO - 2016-02-22 10:12:56,234 INFO : javascript benchmark 10:12:56 INFO - 2016-02-22 10:12:56,235 DEBUG : Working with test: v8_7 10:12:56 INFO - 2016-02-22 10:12:56,235 DEBUG : Generating results file: v8_7 10:12:56 INFO - 2016-02-22 10:12:56,235 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:12:56 INFO - 2016-02-22 10:12:56,293 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:12:56 INFO - 2016-02-22 10:12:56,293 DEBUG : process_Request line: dromaeo_css 7243.04 graph.html#tests=[[72,53,35]] 10:12:56 INFO - 2016-02-22 10:12:56,293 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:12:56 INFO - 2016-02-22 10:12:56,320 DEBUG : process_Request line: kraken 1465.30 graph.html#tests=[[232,53,35]] 10:12:56 INFO - 2016-02-22 10:12:56,320 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:12:56 INFO - 2016-02-22 10:12:56,355 DEBUG : process_Request line: v8_7 23318.10 graph.html#tests=[[230,53,35]] 10:12:56 INFO - 2016-02-22 10:12:56,356 INFO : javascript benchmark 10:12:56 INFO - 2016-02-22 10:12:56,359 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3283.9343213135735, 3327.48423994281, 3356.6643335666454, 3361.07570418135, 3383.509391138285, 5469.917951230735, 5488.478594533518, 5505.3393592768825, 5516.558675305971, 5529.198266251391, 5496.725163741817, 5506.284183056207, 5518.586106042043, 5536.141898005805, 5539.390667026624, 4870.220764677652, 5017.749112544377, 5028.547430731233, 5104.412992505872, 5112.642115051948, 4916.975415122919, 4977.6266779991465, 4979.004199160165, 4984.675996060245, 4985.6759310644875, 4954.479779623141, 4973.204287314031, 5025.3718285214345, 5029.47190544993, 5071.949280507203, 5051.343325367716, 5087.160618497944, 5117.02776472469, 5117.897642047175, 5144.691318520863, 4986.700797952134, 5046.318746969149, 5080.288759573626, 5104.464031276737, 5106.540411362958, 5175.637705360627, 5248.973755131238, 5272.6836389816735, 5351.491608297206, 5352.4915133062295, 5832.066869300891, 5866.76532938682, 5871.706414679292, 5879.941200587982, 5880.382559831199, 5659.434056594308, 5920.348761636216, 5921.289445266596, 5938.287405511323, 5952.761889524457, 5389.568834493231, 5410.48600382963, 5482.616216864861, 5485.232067510552, 5515.448455154493, 5928.881422371528, 5958.582899197015, 5971.5222782177325, 5976.581639285208, 5977.222961015127, 5605.187247849039, 5746.109353050284, 5776.046952252831, 5795.362510123883, 5802.970985145132, 5875.706214689249, 5922.170896074553, 5939.495142912814, 5941.70291485424, 5995.820125396245, 5778.479936805707, 5827.329857066492, 5833.854153646193, 5839.299284085937, 5854.2389489366105, 5672.0, 5746.454086861741, 5779.826605201851, 5831.241938548046, 5836.708164591753, 4492.775361231926, 4495.595396414338, 4497.797599108048, 4498.797554110073, 4512.503624601291, 4027.0737730321603, 4030.274550580865, 4036.2734707752297, 4037.7981100944835, 4058.6347228748964, 5472.0, 5558.666480011212, 5560.0, 5632.436756324417, 5638.915416268759, 5925.377835327315, 5953.910691339634, 5958.523318134538, 5961.986462301419, 5971.313298970587, 5626.493615574618, 5631.549476041989, 5634.436556344414, 5643.097104463348, 5647.209390685307, 4960.107180707508, 5027.824026159067, 5034.0686972910125, 5116.411612664517, 5149.0474262261605, 6065.059915713072, 6070.180525629072, 6081.452669259788, 6114.449699526975, 6116.785912493041, 5925.762969481172, 5928.822135335947, 5935.34711181761, 5939.4951429129005, 5939.673317967467, 5876.382979787061, 5938.168656388023, 5998.670073145844, 6049.697515124314, 6053.909191362045, 5853.824385268449, 5893.410658934073, 5902.822915312548, 5907.91138132937, 5931.3178984417345, 5967.343592204767, 5971.552133590085, 5974.731137098754, 5974.970125149346, 5981.790637327673, 5605.691686957257, 5636.267285252891, 5715.9142612860005, 5753.252077229933, 5778.075507918713, 5747.109198074305, 5830.679312637845, 5838.620489668158, 5839.0, 5842.737076831468, 5732.828015159552, 5754.856128596819, 5757.481826635623, 5769.0481070624555, 5769.74036168365, 5576.219329293983, 5675.233843431084, 5760.596758227051, 5854.385289544536, 5877.764889404547, 5587.525060369914, 5638.126090455986, 5649.265595472562, 5655.038643430709, 5667.291588551431], "unit": "ms", "name": "dojo.html", "value": 5433.327348825406}, {"lowerIsBetter": false, "replicates": [602.7498588085945, 629.7386584567407, 637.1557686065962, 646.8576913079122, 667.946564274858, 30186.641601127976, 30507.38985220294, 30531.389372212543, 30531.847340763292, 37763.24473510535, 44824.55175448245, 45478.772606137005, 45569.0, 45731.54268457314, 45817.770911145395, 8975.0, 9118.544072796367, 9150.359474836763, 9184.816303673922, 9194.172524472797, 8231.382646301521, 8400.537970411626, 8898.110188981082, 10417.791644167111, 10632.946835265813, 10141.847872281922, 10289.485525723721, 10290.43402612856, 10290.639827606035, 10300.175985921145, 10256.846147307797, 10299.0, 10363.0, 10374.688759337232, 10375.221858360615, 10200.642977495752, 10318.535665894977, 10332.58669653213, 10337.586496540129, 10342.32774869631, 38448.23103537913, 38827.611723882685, 38860.80569597162, 42946.78526607347, 43136.56863431357, 3711.4989476420606, 3720.479132921393, 3776.754510956779, 3803.1442925341853, 4167.91664166715, 30517.847410763025, 30775.0, 30794.538081928564, 30917.536236956465, 30931.072067838002, 4251.426057482231, 4277.1873344064525, 4313.84901528445, 4326.221280169569, 4331.805068771881, 281315.0, 283846.99999999796, 283935.0, 284526.0, 285560.0, 58712.0, 59438.0, 65846.34153658498, 66527.66736166384, 66955.66522167456, 66004.33995660079, 66054.33945660578, 66381.66809165923, 66432.0140791554, 66441.33558664448, 17059.744103838453, 17252.827471725373, 17286.13569321554, 17329.0, 17374.65250694979, 12470.189437686522, 12475.064370172091, 12485.250884946932, 12487.188332758342, 12490.937545312216, 22172.778272217394, 22420.55158896813, 22457.663135052986, 22542.887285563465, 22564.548709025727, 8255.25702686761, 8360.665573376997, 8392.664293428317, 8405.747827565183, 8455.112213217646, 1006.5822683586376, 1047.0210353319671, 1095.39753135775, 1121.3440137519478, 1199.8560172779323, 28785.0, 28848.0, 29218.0, 29464.410711785644, 29529.85235073811, 1084.2789544952518, 1122.938238396896, 1193.6657735833808, 1197.2397527570056, 1246.5948566715854, 15074.924625376803, 15650.843491565167, 15788.60528486797, 17337.306507739802, 18345.449636510926, 401496.0, 402801.0, 405285.0, 407777.0, 408119.0, 59759.70120149371, 60078.69960650169, 60220.69889650524, 60242.69878650666, 60267.0, 1422.4950142699192, 1442.4302400551724, 1446.2913172545586, 1460.1677044084981, 1463.0782606957762, 28591.0, 33836.66163338335, 34084.488732668535, 34147.65852341445, 34527.65472345244, 53498.19752703635, 53901.0, 54016.45983540272, 54036.72981635224, 54112.45887541074, 331148.0, 331973.0, 334086.0, 334685.0, 335198.0, 35081.64918350784, 40913.1817363657, 41503.7924810374, 52705.73647131893, 52890.0, 49507.50492495173, 51351.0, 51614.74192629013, 51676.0, 52828.73585632047, 41736.79131604323, 42502.149957001304, 43254.351184731626, 43727.78136109299, 44005.0, 42639.7868010658, 42895.7855210722, 43093.0, 43834.0, 43865.34201987037, 37868.2426351477, 42224.36663449989, 42574.1485170301, 47047.47143964148, 47132.52867471419, 35370.46944295889, 38142.23715525618, 38628.0, 39081.41377879391, 39374.2125157501, 28985.0, 29175.54122293919, 29492.705072948997, 29649.5552566716, 29677.406451871273], "unit": "ms", "name": "ext.html", "value": 20677.335897508005}, {"lowerIsBetter": false, "replicates": [384.4751913637885, 388.04153740261546, 388.77062533105465, 390.07552101519394, 398.26718837339297, 14876.256187190624, 17139.485815425527, 17923.82076179238, 19434.0, 19443.611127777436, 17094.572635684115, 17575.82424175758, 17639.91180044098, 17857.0, 18202.908985455055, 5057.69653820772, 5133.076046311662, 5160.56135228506, 5164.92252616211, 5180.818671346504, 4008.158286759784, 4132.5040995080635, 5123.077845987721, 5485.862853428676, 5637.548996080303, 5555.3611334696425, 5601.411851755568, 5602.551795856321, 5622.240997465351, 5627.493525582697, 5449.482299181572, 5567.109262517978, 5570.2480165177585, 5573.247611572447, 5576.86057848555, 5339.199120131972, 5362.222477740745, 5368.275282836845, 5381.838544843661, 5388.568914486832, 19250.614987700308, 21018.684719729215, 21396.679049814265, 21534.353969380943, 21541.353759387242, 2270.0579259607243, 2287.5081857400737, 2321.3152120124605, 2356.7289761677457, 2402.4354276744953, 9305.581248843751, 9445.291603129794, 9669.516524173763, 10052.24608154384, 10984.505697243643, 1885.8397036251936, 1947.6202140582593, 1954.5211423201265, 1984.82136607706, 2014.8085931836451, 61472.69263653698, 61674.69162654203, 62252.0, 63430.99999999954, 64028.35971640271, 10827.133829293638, 10888.836667449994, 10913.83629245562, 10921.071708904674, 10957.671269861918, 14312.713745725026, 14351.641208969859, 14368.13791172533, 14390.712185756227, 14416.567502974927, 7816.921830781619, 7929.603519823986, 7949.523028618186, 8003.8799418008775, 8024.117347091816, 7584.469087163956, 7587.886181707278, 7592.202818703957, 7633.313001829751, 7651.043619547557, 4631.143238500888, 4669.112868554964, 4671.766411679402, 4686.718796872198, 4704.9059018819435, 1742.8782064611187, 1812.9456116316533, 1830.1947143256925, 1926.2776458827939, 1932.7584051993501, 900.9819803604022, 902.1339514066439, 956.31623389277, 985.7387792235063, 1023.8993082436261, 14942.327595258239, 16522.83477165237, 17101.743473847902, 17785.288588456577, 18814.52963675919, 968.2931460034215, 968.5931908598264, 976.9364991275544, 986.6793292180071, 1005.9245556583139, 9241.260699144052, 9384.8592271116, 9760.853587196198, 9946.303758736964, 10425.687229383131, 442234.0, 455064.0, 624013.0, 647678.0, 651774.0, 81692.59153704194, 84418.57790711007, 86196.0, 88336.11663883408, 89629.55185224031, 1224.761171571535, 1226.4848763519344, 1262.1732765038914, 1272.4719241514854, 1304.8564657887625, 14474.0, 15022.399104036152, 15627.609309767347, 16303.0, 16406.261718222948, 36336.818315908255, 36467.27065458623, 36758.63241367552, 37493.4375984355, 37668.81165594154, 45406.545934540234, 45517.54482455266, 46689.29966050444, 46797.0, 46885.29672054854, 9928.900710992797, 10393.168546516412, 10502.264841461025, 10510.369377837354, 10677.09244714208, 12389.93805030969, 12407.751844963232, 12565.18326308787, 12566.811497827723, 17282.913585431994, 14842.073897496763, 15601.063936163866, 15721.764173537178, 15742.606434839221, 15780.526584202493, 15452.5364239073, 15484.767728484307, 15617.14105724196, 15883.841161588236, 15968.0, 11779.764404711686, 12456.252624842538, 14670.853291467376, 15270.77093843571, 16534.421295254608, 10007.149392301735, 10135.64525241613, 10218.948905255425, 10235.948820255851, 10269.794604108025, 10395.94802025985, 11704.94147529257, 12798.0, 14912.179830109015, 15579.376824927329], "unit": "ms", "name": "jquery.html", "value": 9146.574990424233}, {"lowerIsBetter": false, "replicates": [1490.5379332406956, 1538.6153461634588, 1610.9919450402747, 1689.1993015714643, 1810.5926166612514, 7547.773566792998, 8042.678292868278, 8054.637541310641, 8075.959620201891, 8077.959610201948, 7751.496152750082, 7794.94768206292, 7800.3759699224065, 7806.180351063128, 7815.413843961697, 2350.459394339303, 2374.6200607902783, 2415.855048697075, 2417.2506522977847, 2417.4802417480237, 2409.229046705055, 2438.804895608347, 2439.878006099697, 2440.2191298784305, 2440.633904914268, 1999.2902519605475, 2039.0416504243055, 2334.9182778602753, 2345.4839935214227, 2350.976490235093, 2346.1319311854636, 2352.3883790214495, 2373.0151986925407, 2376.5603363377745, 2379.988100059506, 2316.1661801751357, 2358.375030616871, 2359.622460406327, 2359.6814430051895, 2361.8110551155864, 6716.1604799400075, 7055.118110236221, 7428.331450169457, 7695.653695583712, 7706.113796913371, 4673.322368256584, 4706.199946009152, 4727.030958653502, 4733.644976626767, 4737.431508219001, 3112.548680441346, 3121.8595163217483, 3142.701443362877, 3150.2754366495606, 3240.627327857303, 1022.3303736052898, 1022.8772547294298, 1060.363781730963, 1060.8779990301136, 1061.4374381577777, 10819.621313254078, 10853.34879907208, 10878.238523303293, 10962.835557466644, 10967.122630189566, 4191.517975432804, 4232.4286221360335, 4320.697551171451, 4344.500382456058, 4353.216421044242, 9023.097690230925, 9023.639054437881, 9052.094790520896, 9069.047749986288, 9080.273578113867, 4738.099761045391, 4821.132196204647, 4834.299026641188, 4835.516448355207, 4836.032793441255, 4389.253826849444, 4418.911621767546, 4521.367008618796, 4525.52481989393, 4531.864044078683, 3570.0003998879833, 3570.0360902556163, 3587.1390866191928, 3593.1915319053005, 3614.5120408744483, 995.66147509847, 1007.8135544924213, 1012.86832711747, 1033.1631378583372, 1034.13132968307, 1191.8271850581616, 1192.8807119288, 1295.4559085184246, 1414.3918115210352, 1456.9198694071715, 7478.85042299151, 7501.0, 7610.315071643578, 7618.780995040767, 7629.122650895218, 518.1631664861222, 522.2271038862505, 522.4488164985976, 527.0197432775035, 538.8141091323487, 5793.826185214451, 6009.879802404015, 6097.085437184369, 6109.633421994695, 6114.144019837227, 8876.689315874042, 9323.114304141096, 9421.152096311365, 9446.197073248713, 9464.90535094654, 9107.817843643223, 9246.630134794668, 9275.443473391619, 9290.44257344548, 9339.626414943326, 7146.3568278855155, 7281.708731650675, 7285.052943117361, 7292.671829767673, 7315.049043624294, 7351.448641352027, 7401.962990185015, 7408.6666100026705, 7411.110666720028, 7442.0, 8164.5917704115745, 8203.0566484855, 8289.295409889986, 8317.126701696236, 8440.0, 8354.665813367397, 8397.70608028716, 8411.411201215857, 8586.484810911365, 8738.475691458532, 6683.8663226734225, 6743.2582415935185, 6760.729570817112, 6766.932330676827, 6768.950812624051, 7505.549667019996, 7693.153753087267, 7695.038120234971, 7716.266954639525, 7728.304452599293, 7026.051483049723, 7091.893621595577, 7104.715811367694, 7139.2503787101605, 7176.959340895645, 7087.929120708727, 7151.964240178766, 7165.641717913979, 7257.455690823315, 7277.963610181915, 7280.817979550554, 7319.853602927805, 7329.560226386434, 7343.926560734538, 7371.705131794668, 6210.503159747119, 6335.1130841683125, 6356.046593011085, 6803.93196068033, 6854.622995735083, 6717.126773519607, 6792.0830687856505, 6803.353681400259, 6805.081314022544, 6811.421029212573], "unit": "ms", "name": "mootools.html", "value": 4461.710955940397}, {"lowerIsBetter": false, "replicates": [340.1224839913025, 341.4991114053734, 343.90370696205065, 349.9055255081128, 350.0548518998704, 5751.884962300752, 5808.303003639564, 5876.412358764122, 5890.823275301743, 5985.88028239435, 5684.204211410395, 5841.065429531276, 5841.240638716961, 5858.091995740667, 5865.941340586593, 4570.314452832085, 4570.862874113774, 4572.7942242599165, 4577.992841574848, 4583.220852455074, 4406.206882761102, 4484.708493947899, 4532.52408497108, 4572.748498832563, 4573.8399156029545, 4496.213162696531, 4510.797014134372, 4521.751303678297, 4522.412086428776, 4532.886677833064, 4542.591166794988, 4548.272276435771, 4548.340490628873, 4550.954490455086, 4578.725276483422, 4055.9188816223805, 4059.025833799896, 4063.6952228582677, 4066.2477441673386, 4078.8164532596106, 5445.809396671098, 5561.805336813232, 5580.804671836507, 5612.550995920316, 5617.522510586605, 5247.895042099175, 5262.342207224097, 5271.578273738091, 5276.7889284428575, 5285.577153827685, 5952.0, 6274.905876411857, 6281.057841323793, 6285.120083188312, 6287.025511045796, 4266.978665106654, 4272.572742725733, 4320.0, 4321.243782338078, 4336.848210312655, 6411.711472983727, 6466.579672321331, 6475.83810404739, 6477.384648458342, 6478.74085036598, 4516.299973504047, 4519.5480451954545, 4520.593146616755, 4521.299198624153, 4526.728396296233, 4503.549645035536, 4576.725396476222, 4578.175928332932, 4581.931271030937, 4584.381108550369, 5258.5267325940085, 5263.210518422267, 5270.92093618596, 5272.762725677354, 5275.103232450492, 6294.8111556653375, 6310.0, 6311.274203466568, 6311.463525600284, 6318.462930650854, 1587.6030992251938, 1614.555997100783, 1615.063263307279, 1615.111688571281, 1615.2569817883905, 1084.2897901874285, 1086.869575650927, 1091.1325496230515, 1097.05653138301, 1114.1254115519278, 6039.456448919531, 6078.0, 6092.360302168208, 6092.5430592705725, 6098.390160983866, 6470.611763294218, 6473.967630161819, 6475.125858009203, 6478.319776423502, 6490.286068532458, 6035.426634469718, 6134.969325153345, 6153.261608606996, 6155.599886007395, 6163.3528479509905, 3489.8778542750883, 3490.650934906489, 3516.7538272320694, 3517.1383011161915, 3519.524864143359, 6655.933440665628, 6669.266380698119, 6675.0988616537115, 6678.165229346332, 6678.599284042973, 6566.080748695186, 6610.272869984298, 6624.8012559623285, 6626.734930602722, 6630.900536491957, 6499.610023398611, 6647.634380109141, 6666.400023997863, 6670.132882725215, 6671.766488172988, 6261.123442718114, 6366.0, 6476.255230648348, 6483.189601299838, 6489.837754056186, 6253.437190652863, 6266.436020758154, 6273.0, 6277.309495955533, 6408.03879418091, 6692.531522793358, 6702.329767023259, 6707.027481015128, 6712.765053223114, 6717.361850624183, 4431.379606855107, 4590.265557510782, 4590.793414296431, 4590.862274131781, 4596.195665758412, 6505.0, 6505.512086593619, 6512.381323774234, 6515.0553169791065, 6519.4784417245555, 6342.334054924167, 6359.20509936258, 6364.77723279683, 6366.5225108118, 6367.522435817425, 6219.284782250099, 6322.272938612118, 6330.208723909512, 6332.240131184103, 6335.239771227482, 6209.223847019122, 6220.720067596879, 6225.097360882737, 6226.377362263918, 6233.906491402723, 6339.841503962438, 6344.809655710336, 6351.364863513611, 6354.665520240616, 6377.330380310001, 6096.939030609815, 6216.036514340284, 6217.253929528305, 6218.067289906369, 6229.657368844757], "unit": "ms", "name": "prototype.html", "value": 4758.182789793045}, {"lowerIsBetter": false, "replicates": [762.36342653884, 769.6844293839523, 839.0770152831885, 901.1709227510689, 901.1754244865948, 13356.332183390829, 13621.318934053295, 13651.590452286448, 13654.863451365483, 13680.589582312534, 12509.374531273423, 12578.622641320751, 12600.180988235757, 12600.684982875433, 12668.0, 733.8825787873928, 748.3115533708974, 748.9513181643204, 749.4042236422031, 875.5184648443362, 885.154677283193, 893.9374243802937, 894.2354287084546, 894.3962825093037, 895.0, 923.4643906534194, 929.1776777551847, 936.1901954809075, 936.4053825820585, 937.9296552758536, 763.3130182835437, 776.6000509737489, 925.7222833150062, 927.2257664849858, 931.2270815223349, 581.9156222347779, 583.0467186150631, 674.8515326628134, 678.871014507247, 684.2815044203616, 13670.111442756288, 13739.450421983109, 13751.518696845562, 13768.862311376959, 13790.241536715477, 12319.568815091518, 12672.936635316857, 12801.167924084904, 13317.53388631403, 13374.732505349839, 11117.833232501518, 11159.442027898573, 11188.496517656726, 11441.084713223005, 11753.4710938008, 3808.6572208501093, 3829.3872980323013, 3875.2830726315724, 3879.941800872989, 3909.2181563687377, 48993.51006489961, 49292.0, 49657.50342496565, 49691.0, 51474.22788658173, 10487.265891387682, 10502.579896804195, 10557.788844223227, 10599.258051936291, 10721.731956701145, 18730.250789968522, 18774.1551630177, 19522.0, 19693.803061969196, 19761.901190493954, 7772.611369431619, 7839.0, 7927.5243485391065, 8072.354211663052, 8098.959505202554, 4322.416473776063, 4418.911621767546, 4450.777461126931, 4483.282674772021, 4676.134915040729, 3170.381775553791, 3175.7300629446295, 3197.088829683529, 3197.216681912946, 3205.0705295464522, 569.2940753465756, 583.0117950074583, 585.28887401807, 590.7459792289357, 595.6188039654625, 726.4878260826105, 736.5359823311279, 740.8962745215674, 754.7584772872738, 765.9846803064019, 8891.599878005505, 8979.922409310924, 9093.77265568366, 9162.541872906462, 9277.257819374434, 743.7917383132732, 754.5813869924507, 762.4738930138187, 763.050002746584, 778.766370088971, 4003.279409706281, 4369.08249267651, 4373.212821692063, 4439.16090843796, 4538.909221815546, 56681.149782753295, 56685.14972275419, 57136.0, 57272.42727572754, 57480.137797933065, 50664.49335506598, 51199.7440012805, 51523.22715159276, 51535.22697159545, 51926.0, 45558.54441455609, 46246.0, 47107.764461177474, 47232.0, 47737.76131119322, 8589.355798315277, 8757.824843502967, 8840.867386989328, 8933.508657023925, 8987.370884038055, 34987.769116647156, 35697.0, 36264.45603316005, 36572.0, 36647.0, 43292.40245585275, 45950.0, 46454.30318545157, 46569.13723451024, 46706.29940550827, 11730.472128754056, 11777.175597708077, 11926.940365298118, 12096.455659495168, 12239.0, 13015.154014989374, 13196.472141114247, 13222.272774997467, 13355.599332020054, 13820.23988680592, 11402.771944561227, 11842.644720658394, 12088.516459341528, 12274.87725122773, 12656.810147847973, 10845.0, 10852.620158294421, 10897.945510272399, 11145.712014955445, 11372.601958931398, 11778.175527712978, 11983.400829958293, 12324.50701971947, 12751.0, 12838.743225135258, 10637.840432393676, 10810.945945270223, 10825.945870270598, 10829.566817327219, 11408.429578521207, 10037.49812509357, 10147.49262536885, 10206.438645874548, 10523.368597884151, 10843.783124337628], "unit": "ms", "name": "yui.html", "value": 6617.939666920432}], "name": "dromaeo_css", "value": 7243.04524932757}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 103.0, 108.0, 109.0, 108.0, 98.0, 109.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 106.8}, {"lowerIsBetter": true, "replicates": [148.0, 122.0, 155.0, 165.0, 122.0, 152.0, 151.0, 122.0, 161.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.0}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 159.0, 160.0, 160.0, 160.0, 159.0, 163.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.3}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 156.0, 71.0, 72.0, 72.0, 70.0, 148.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.4}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 136.0, 91.0, 91.0, 92.0, 132.0, 92.0, 94.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 105.0}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 111.0, 108.0, 110.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 147.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 94.9}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 59.0, 58.0, 59.0, 58.0, 57.0, 57.0, 56.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [74.0, 78.0, 78.0, 77.0, 77.0, 78.0, 78.0, 76.0, 77.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.0}, {"lowerIsBetter": true, "replicates": [109.0, 123.0, 112.0, 109.0, 109.0, 111.0, 114.0, 118.0, 112.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.1}, {"lowerIsBetter": true, "replicates": [140.0, 135.0, 140.0, 131.0, 143.0, 145.0, 140.0, 139.0, 144.0, 131.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.8}, {"lowerIsBetter": true, "replicates": [67.0, 63.0, 64.0, 63.0, 63.0, 64.0, 62.0, 65.0, 62.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.5}], "name": "kraken", "value": 1465.2999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.12276382734285], "unit": "score", "name": "Richards", "value": 215.095086}, {"lowerIsBetter": false, "replicates": [164.79894528675015], "unit": "score", "name": "DeltaBlue", "value": 401.20402399999995}, {"lowerIsBetter": false, "replicates": [278.39643652561244], "unit": "score", "name": "Encrypt", "value": 956.1221520000003}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [956.9377990430622], "unit": "score", "name": "RayTrace", "value": 773.2885049999996}, {"lowerIsBetter": false, "replicates": [543.773790103317], "unit": "score", "name": "Earley", "value": 1225.6254570000006}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24000.0], "unit": "score", "name": "RegExp", "value": 37.95770833333335}, {"lowerIsBetter": false, "replicates": [524.6589716684156], "unit": "score", "name": "Splay", "value": 155.321846}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 23318.104103417085}]} 10:12:56 INFO - RETURN: dromaeo_css: 7243.04 10:12:56 INFO - RETURN: kraken: 1465.30 10:12:56 INFO - RETURN: v8_7: 23318.10 10:12:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23318.10"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1465.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7243.04"}}} 10:12:56 INFO - Return code: 0 10:12:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:12:56 INFO - # TBPL SUCCESS # 10:12:56 INFO - Running post-action listener: _resource_record_post_action 10:12:56 INFO - Running post-run listener: _resource_record_post_run 10:12:57 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 323584; Write bytes: 504516608; Read time: 272; Write time: 432240 10:12:57 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239616000; Read time: 0; Write time: 249324 10:12:57 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 323584; Write bytes: 264900608; Read time: 272; Write time: 182916 10:12:57 INFO - Running post-run listener: _upload_blobber_files 10:12:57 INFO - Blob upload gear active. 10:12:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:12:57 INFO - Copying logs to upload dir... 10:12:57 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1254.164837 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-02-22 10:12:57.288664) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-22 10:12:57.292600) ========= 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010129 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456154461/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-22 10:12:57.342771) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 10:12:57.343058) ========= 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=8887e36bb8e381bfd44124ac000001a8-1456163502.119540-1407245695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004588 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-22 10:12:57.392881) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-22 10:12:57.393197) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-22 10:12:57.393492) ========= ========= Total master_lag: 0.27 =========