builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1455678753.02 results: success (0) buildid: 20160216164752 builduid: a92223c3d2ea4de2abd596c6b1122d4c revision: ab104d03fabf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.024720) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.025276) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.025572) ========= 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006389 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.048423) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.048694) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.061799) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.062108) ========= 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004711 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.110620) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.110936) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.111276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.111551) ========= 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-16 19:12:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 30.3M=0s 2016-02-16 19:12:33 (30.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.315660 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.438832) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.439111) ========= 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018662 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.488223) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:33.488537) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ab104d03fabf --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 ab104d03fabf --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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-16 19:12:33,970 Setting DEBUG logging. 2016-02-16 19:12:33,970 attempt 1/10 2016-02-16 19:12:33,970 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ab104d03fabf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-16 19:12:34,228 unpacking tar archive at: mozilla-beta-ab104d03fabf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.542907 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:34.043964) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:34.044299) ========= echo ab104d03fabf in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'ab104d03fabf'] 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False ab104d03fabf program finished with exit code 0 elapsedTime=0.004428 script_repo_revision: 'ab104d03fabf' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:34.094191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:34.094491) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-16 19:12:34.104163) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-16 19:12:34.104441) ========= /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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:12:34 INFO - MultiFileLogger online at 20160216 19:12:34 in /builds/slave/test-pgo 19:12:34 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 19:12:34 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:12:34 INFO - {'append_to_log': False, 19:12:34 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:12:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:12:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:12:34 INFO - 'branch': 'Mozilla-Beta', 19:12:34 INFO - 'buildbot_json_path': 'buildprops.json', 19:12:34 INFO - 'config_files': ('talos/linux_config.py',), 19:12:34 INFO - 'default_actions': ('clobber', 19:12:34 INFO - 'read-buildbot-config', 19:12:34 INFO - 'download-and-extract', 19:12:34 INFO - 'populate-webroot', 19:12:34 INFO - 'create-virtualenv', 19:12:34 INFO - 'install', 19:12:34 INFO - 'run-tests'), 19:12:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:12:34 INFO - 'download_minidump_stackwalk': True, 19:12:34 INFO - 'download_symbols': 'ondemand', 19:12:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:12:34 INFO - 'tooltool.py': '/tools/tooltool.py', 19:12:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:12:34 INFO - '/tools/misc-python/virtualenv.py')}, 19:12:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:12:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:12:34 INFO - 'installer_path': 'installer.exe', 19:12:34 INFO - 'log_level': 'info', 19:12:34 INFO - 'log_name': 'talos', 19:12:34 INFO - 'log_to_console': True, 19:12:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:12:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:12:34 INFO - 'opt_config_files': (), 19:12:34 INFO - 'pip_index': False, 19:12:34 INFO - 'sps_profile': False, 19:12:34 INFO - 'sps_profile_interval': 0, 19:12:34 INFO - 'suite': 'dromaeojs', 19:12:34 INFO - 'system_bits': '32', 19:12:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:12:34 INFO - 'title': 'talos-linux64-ix-017', 19:12:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:12:34 INFO - 'use_talos_json': True, 19:12:34 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:12:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:12:34 INFO - 'work_dir': 'build'} 19:12:34 INFO - ##### 19:12:34 INFO - ##### Running clobber step. 19:12:34 INFO - ##### 19:12:34 INFO - Running pre-action listener: _resource_record_pre_action 19:12:34 INFO - Running main action method: clobber 19:12:34 INFO - rmtree: /builds/slave/test-pgo/build 19:12:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:12:35 INFO - Running post-action listener: _resource_record_post_action 19:12:35 INFO - ##### 19:12:35 INFO - ##### Running read-buildbot-config step. 19:12:35 INFO - ##### 19:12:35 INFO - Running pre-action listener: _resource_record_pre_action 19:12:35 INFO - Running main action method: read_buildbot_config 19:12:35 INFO - Using buildbot properties: 19:12:35 INFO - { 19:12:35 INFO - "properties": { 19:12:35 INFO - "buildnumber": 109, 19:12:35 INFO - "product": "firefox", 19:12:35 INFO - "script_repo_revision": "production", 19:12:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 19:12:35 INFO - "repository": "", 19:12:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 19:12:35 INFO - "buildid": "20160216164752", 19:12:35 INFO - "slavename": "talos-linux64-ix-017", 19:12:35 INFO - "pgo_build": "True", 19:12:35 INFO - "basedir": "/builds/slave/test-pgo", 19:12:35 INFO - "project": "", 19:12:35 INFO - "platform": "ubuntu64_hw", 19:12:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:12:35 INFO - "slavebuilddir": "test", 19:12:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 19:12:35 INFO - "branch": "mozilla-beta", 19:12:35 INFO - "repo_path": "releases/mozilla-beta", 19:12:35 INFO - "stage_platform": "linux64-pgo", 19:12:35 INFO - "builduid": "a92223c3d2ea4de2abd596c6b1122d4c", 19:12:35 INFO - "revision": "ab104d03fabf" 19:12:35 INFO - }, 19:12:35 INFO - "sourcestamp": { 19:12:35 INFO - "repository": "", 19:12:35 INFO - "hasPatch": false, 19:12:35 INFO - "project": "", 19:12:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 19:12:35 INFO - "changes": [ 19:12:35 INFO - { 19:12:35 INFO - "category": null, 19:12:35 INFO - "files": [ 19:12:35 INFO - { 19:12:35 INFO - "url": null, 19:12:35 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.tar.bz2" 19:12:35 INFO - } 19:12:35 INFO - ], 19:12:35 INFO - "repository": "", 19:12:35 INFO - "rev": "ab104d03fabf", 19:12:35 INFO - "who": "sendchange", 19:12:35 INFO - "when": 1455678664, 19:12:35 INFO - "number": 7613335, 19:12:35 INFO - "comments": "Bug 1246128: F11 displays the audio container in fullscreen instead of the browser. r=bz a=lhenry", 19:12:35 INFO - "project": "", 19:12:35 INFO - "at": "Tue 16 Feb 2016 19:11:04", 19:12:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 19:12:35 INFO - "revlink": "", 19:12:35 INFO - "properties": [ 19:12:35 INFO - [ 19:12:35 INFO - "buildid", 19:12:35 INFO - "20160216164752", 19:12:35 INFO - "Change" 19:12:35 INFO - ], 19:12:35 INFO - [ 19:12:35 INFO - "builduid", 19:12:35 INFO - "a92223c3d2ea4de2abd596c6b1122d4c", 19:12:35 INFO - "Change" 19:12:35 INFO - ], 19:12:35 INFO - [ 19:12:35 INFO - "pgo_build", 19:12:35 INFO - "True", 19:12:35 INFO - "Change" 19:12:35 INFO - ] 19:12:35 INFO - ], 19:12:35 INFO - "revision": "ab104d03fabf" 19:12:35 INFO - } 19:12:35 INFO - ], 19:12:35 INFO - "revision": "ab104d03fabf" 19:12:35 INFO - } 19:12:35 INFO - } 19:12:35 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.tar.bz2. 19:12:35 INFO - Running post-action listener: _resource_record_post_action 19:12:35 INFO - ##### 19:12:35 INFO - ##### Running download-and-extract step. 19:12:35 INFO - ##### 19:12:35 INFO - Running pre-action listener: _resource_record_pre_action 19:12:35 INFO - Running main action method: download_and_extract 19:12:35 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/test_packages.json 19:12:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:12:35 INFO - Downloaded 1270 bytes. 19:12:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:12:35 INFO - Using the following test package requirements: 19:12:35 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 19:12:35 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 19:12:35 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'jsshell-linux-x86_64.zip'], 19:12:35 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 19:12:35 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 19:12:35 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 19:12:35 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 19:12:35 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 19:12:35 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 19:12:35 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 19:12:35 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.common.tests.zip 19:12:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:36 INFO - Downloaded 22212023 bytes. 19:12:36 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 19:12:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 19:12:36 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 19:12:37 INFO - Return code: 0 19:12:37 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 19:12:37 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')]} 19:12:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.common.tests.zip 19:12:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:37 INFO - Downloaded 22212023 bytes. 19:12:37 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 19:12:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 19:12:37 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 19:12:38 INFO - Return code: 0 19:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 19:12:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:39 INFO - Downloaded 11132038 bytes. 19:12:39 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 19:12:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 19:12:39 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 19:12:39 INFO - Return code: 0 19:12:39 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')]} 19:12:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.tar.bz2 19:12:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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 19:12:41 INFO - Downloaded 58290539 bytes. 19:12:41 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.tar.bz2 19:12:41 INFO - mkdir: /builds/slave/test-pgo/properties 19:12:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:12:41 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:12:41 INFO - Contents: 19:12:41 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.tar.bz2 19:12:41 INFO - Running post-action listener: _resource_record_post_action 19:12:41 INFO - Running post-action listener: set_extra_try_arguments 19:12:41 INFO - ##### 19:12:41 INFO - ##### Running populate-webroot step. 19:12:41 INFO - ##### 19:12:41 INFO - Running pre-action listener: _resource_record_pre_action 19:12:41 INFO - Running main action method: populate_webroot 19:12:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:12:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:12:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:12:41 INFO - '--tppagecycles', 19:12:41 INFO - '7'], 19:12:41 INFO - 'tests': ['tsvgm']}}, 19:12:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:12:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:12:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:12:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'tests': ['damp', 'tps']}, 19:12:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['damp', 'tps']}, 19:12:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:12:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:41 INFO - 'other': {'tests': ['a11yr', 19:12:41 INFO - 'ts_paint', 19:12:41 INFO - 'tpaint', 19:12:41 INFO - 'sessionrestore', 19:12:41 INFO - 'sessionrestore_no_auto_restore']}, 19:12:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['a11yr', 19:12:41 INFO - 'ts_paint', 19:12:41 INFO - 'tpaint', 19:12:41 INFO - 'sessionrestore', 19:12:41 INFO - 'sessionrestore_no_auto_restore']}, 19:12:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['a11yr', 19:12:41 INFO - 'ts_paint', 19:12:41 INFO - 'tpaint', 19:12:41 INFO - 'sessionrestore', 19:12:41 INFO - 'sessionrestore_no_auto_restore']}, 19:12:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['a11yr', 19:12:41 INFO - 'ts_paint', 19:12:41 INFO - 'tpaint', 19:12:41 INFO - 'sessionrestore', 19:12:41 INFO - 'sessionrestore_no_auto_restore']}, 19:12:41 INFO - 'other_l64': {'tests': ['a11yr', 19:12:41 INFO - 'ts_paint', 19:12:41 INFO - 'tpaint', 19:12:41 INFO - 'sessionrestore', 19:12:41 INFO - 'sessionrestore_no_auto_restore']}, 19:12:41 INFO - 'other_nol64': {'tests': ['a11yr', 19:12:41 INFO - 'ts_paint', 19:12:41 INFO - 'tpaint', 19:12:41 INFO - 'sessionrestore', 19:12:41 INFO - 'sessionrestore_no_auto_restore']}, 19:12:41 INFO - 'svgr': {'tests': ['tsvgx', 19:12:41 INFO - 'tsvgr_opacity', 19:12:41 INFO - 'tart', 19:12:41 INFO - 'tscrollx', 19:12:41 INFO - 'cart']}, 19:12:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['tsvgx', 19:12:41 INFO - 'tsvgr_opacity', 19:12:41 INFO - 'tart', 19:12:41 INFO - 'tscrollx', 19:12:41 INFO - 'cart']}, 19:12:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'tests': ['tp5o']}, 19:12:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'talos_options': ['--e10s'], 19:12:41 INFO - 'tests': ['tp5o']}, 19:12:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'talos_options': ['--xperf_path', 19:12:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:41 INFO - 'tests': ['tp5n']}, 19:12:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:12:41 INFO - 'talos_options': ['--e10s', 19:12:41 INFO - '--xperf_path', 19:12:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:41 INFO - 'tests': ['tp5n']}}, 19:12:41 INFO - 'talos.zip': {'path': '', 19:12:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:12:41 INFO - Running post-action listener: _resource_record_post_action 19:12:41 INFO - ##### 19:12:41 INFO - ##### Running create-virtualenv step. 19:12:41 INFO - ##### 19:12:41 INFO - Running pre-action listener: _resource_record_pre_action 19:12:41 INFO - Running main action method: create_virtualenv 19:12:41 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:12:41 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 19:12:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:12:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:41 INFO - Using real prefix '/usr' 19:12:41 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:12:41 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:43 INFO - Installing pip.................done. 19:12:43 INFO - Return code: 0 19:12:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:43 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 19:12:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:12:43 INFO - Using env: {'DISPLAY': ':0', 19:12:43 INFO - 'HOME': '/home/cltbld', 19:12:43 INFO - 'LANG': 'en_US.UTF-8', 19:12:43 INFO - 'LANGUAGE': 'en_US:en', 19:12:43 INFO - 'LOGNAME': 'cltbld', 19:12:43 INFO - 'MAIL': '/var/mail/cltbld', 19:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:43 INFO - 'MOZ_NO_REMOTE': '1', 19:12:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:43 INFO - 'NO_EM_RESTART': '1', 19:12:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:43 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:43 INFO - 'SHELL': '/bin/bash', 19:12:43 INFO - 'SHLVL': '1', 19:12:43 INFO - 'TERM': 'linux', 19:12:43 INFO - 'TMOUT': '86400', 19:12:43 INFO - 'USER': 'cltbld', 19:12:43 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:43 INFO - '_': '/tools/buildbot/bin/python'} 19:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:44 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 19:12:44 INFO - Cleaning up... 19:12:44 INFO - Return code: 0 19:12:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:12:44 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')]} 19:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:44 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')]} 19:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:44 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:44 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 19:12:44 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 19:12:44 INFO - Using env: {'DISPLAY': ':0', 19:12:44 INFO - 'HOME': '/home/cltbld', 19:12:44 INFO - 'LANG': 'en_US.UTF-8', 19:12:44 INFO - 'LANGUAGE': 'en_US:en', 19:12:44 INFO - 'LOGNAME': 'cltbld', 19:12:44 INFO - 'MAIL': '/var/mail/cltbld', 19:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:44 INFO - 'MOZ_NO_REMOTE': '1', 19:12:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:44 INFO - 'NO_EM_RESTART': '1', 19:12:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:44 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:44 INFO - 'SHELL': '/bin/bash', 19:12:44 INFO - 'SHLVL': '1', 19:12:44 INFO - 'TERM': 'linux', 19:12:44 INFO - 'TMOUT': '86400', 19:12:44 INFO - 'USER': 'cltbld', 19:12:44 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:44 INFO - '_': '/tools/buildbot/bin/python'} 19:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:44 INFO - Downloading/unpacking psutil>=0.7.1 19:12:44 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 19:12:44 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 19:12:44 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 19:12:44 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 19:12:44 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 19:12:44 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 19:12:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:46 INFO - Installing collected packages: psutil 19:12:46 INFO - Running setup.py install for psutil 19:12:46 INFO - building 'psutil._psutil_linux' extension 19:12:46 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 19:12:46 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 19:12:46 INFO - building 'psutil._psutil_posix' extension 19:12:46 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 19:12:46 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 19:12:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:46 INFO - Successfully installed psutil 19:12:46 INFO - Cleaning up... 19:12:46 INFO - Return code: 0 19:12:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:46 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:46 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 19:12:46 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 19:12:46 INFO - Using env: {'DISPLAY': ':0', 19:12:46 INFO - 'HOME': '/home/cltbld', 19:12:46 INFO - 'LANG': 'en_US.UTF-8', 19:12:46 INFO - 'LANGUAGE': 'en_US:en', 19:12:46 INFO - 'LOGNAME': 'cltbld', 19:12:46 INFO - 'MAIL': '/var/mail/cltbld', 19:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:46 INFO - 'MOZ_NO_REMOTE': '1', 19:12:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:46 INFO - 'NO_EM_RESTART': '1', 19:12:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:46 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:46 INFO - 'SHELL': '/bin/bash', 19:12:46 INFO - 'SHLVL': '1', 19:12:46 INFO - 'TERM': 'linux', 19:12:46 INFO - 'TMOUT': '86400', 19:12:46 INFO - 'USER': 'cltbld', 19:12:46 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:46 INFO - '_': '/tools/buildbot/bin/python'} 19:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:12:47 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 19:12:47 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 19:12:47 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 19:12:47 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 19:12:47 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 19:12:47 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 19:12:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:12:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:12:49 INFO - Installing collected packages: mozsystemmonitor 19:12:49 INFO - Running setup.py install for mozsystemmonitor 19:12:49 INFO - Successfully installed mozsystemmonitor 19:12:49 INFO - Cleaning up... 19:12:49 INFO - Return code: 0 19:12:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:12:49 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')]} 19:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:49 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')]} 19:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:49 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:49 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 19:12:49 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 19:12:49 INFO - Using env: {'DISPLAY': ':0', 19:12:49 INFO - 'HOME': '/home/cltbld', 19:12:49 INFO - 'LANG': 'en_US.UTF-8', 19:12:49 INFO - 'LANGUAGE': 'en_US:en', 19:12:49 INFO - 'LOGNAME': 'cltbld', 19:12:49 INFO - 'MAIL': '/var/mail/cltbld', 19:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:49 INFO - 'MOZ_NO_REMOTE': '1', 19:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:49 INFO - 'NO_EM_RESTART': '1', 19:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:49 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:49 INFO - 'SHELL': '/bin/bash', 19:12:49 INFO - 'SHLVL': '1', 19:12:49 INFO - 'TERM': 'linux', 19:12:49 INFO - 'TMOUT': '86400', 19:12:49 INFO - 'USER': 'cltbld', 19:12:49 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:49 INFO - '_': '/tools/buildbot/bin/python'} 19:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:49 INFO - Downloading/unpacking blobuploader==1.2.4 19:12:49 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 19:12:49 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 19:12:49 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 19:12:49 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 19:12:49 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 19:12:49 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 19:12:51 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:12:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:51 INFO - Downloading docopt-0.6.1.tar.gz 19:12:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:51 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:51 INFO - Running setup.py install for blobuploader 19:12:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:52 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:12:52 INFO - Running setup.py install for requests 19:12:52 INFO - Running setup.py install for docopt 19:12:52 INFO - Successfully installed blobuploader requests docopt 19:12:52 INFO - Cleaning up... 19:12:52 INFO - Return code: 0 19:12:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:12:52 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')]} 19:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:52 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')]} 19:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:52 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:52 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 19:12:52 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 19:12:52 INFO - Using env: {'DISPLAY': ':0', 19:12:52 INFO - 'HOME': '/home/cltbld', 19:12:52 INFO - 'LANG': 'en_US.UTF-8', 19:12:52 INFO - 'LANGUAGE': 'en_US:en', 19:12:52 INFO - 'LOGNAME': 'cltbld', 19:12:52 INFO - 'MAIL': '/var/mail/cltbld', 19:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:52 INFO - 'MOZ_NO_REMOTE': '1', 19:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:52 INFO - 'NO_EM_RESTART': '1', 19:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:52 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:52 INFO - 'SHELL': '/bin/bash', 19:12:52 INFO - 'SHLVL': '1', 19:12:52 INFO - 'TERM': 'linux', 19:12:52 INFO - 'TMOUT': '86400', 19:12:52 INFO - 'USER': 'cltbld', 19:12:52 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:52 INFO - '_': '/tools/buildbot/bin/python'} 19:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:12:52 INFO - Running setup.py (path:/tmp/pip-j_3J_h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:12:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:12:52 INFO - Running setup.py (path:/tmp/pip-ecxFtG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:12:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:12:52 INFO - Running setup.py (path:/tmp/pip-Q1dii0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:12:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:12:52 INFO - Running setup.py (path:/tmp/pip-JHouqe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:12:53 INFO - Running setup.py (path:/tmp/pip-9ssJYs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:12:53 INFO - Running setup.py (path:/tmp/pip-_YhqVn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:12:53 INFO - Running setup.py (path:/tmp/pip-YcqcV9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:12:53 INFO - Running setup.py (path:/tmp/pip-wK3HwE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:12:53 INFO - Running setup.py (path:/tmp/pip-ruwJ5S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:12:53 INFO - Running setup.py (path:/tmp/pip-_xVESk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:12:53 INFO - Running setup.py (path:/tmp/pip-kctUv4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:12:53 INFO - Running setup.py (path:/tmp/pip-qW_5dm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:12:53 INFO - Running setup.py (path:/tmp/pip-cFlv8p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:12:53 INFO - Running setup.py (path:/tmp/pip-AqmBP_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:12:53 INFO - Running setup.py (path:/tmp/pip-yAXmrS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:12:53 INFO - Running setup.py (path:/tmp/pip-BaZqTr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:12:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:12:53 INFO - Running setup.py (path:/tmp/pip-dg38Ts-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:12:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:53 INFO - Running setup.py install for manifestparser 19:12:54 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Running setup.py install for mozcrash 19:12:54 INFO - Running setup.py install for mozdebug 19:12:54 INFO - Running setup.py install for mozdevice 19:12:54 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Running setup.py install for mozfile 19:12:54 INFO - Running setup.py install for mozhttpd 19:12:54 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Running setup.py install for mozinfo 19:12:54 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Running setup.py install for mozInstall 19:12:54 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:12:54 INFO - Running setup.py install for mozleak 19:12:54 INFO - Running setup.py install for mozlog 19:12:54 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Running setup.py install for moznetwork 19:12:55 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Running setup.py install for mozprocess 19:12:55 INFO - Running setup.py install for mozprofile 19:12:55 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Running setup.py install for mozrunner 19:12:55 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Running setup.py install for mozscreenshot 19:12:55 INFO - Running setup.py install for moztest 19:12:55 INFO - Running setup.py install for mozversion 19:12:55 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:12:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:55 INFO - Cleaning up... 19:12:55 INFO - Return code: 0 19:12:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:12:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:55 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:55 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 19:12:55 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 19:12:55 INFO - Using env: {'DISPLAY': ':0', 19:12:55 INFO - 'HOME': '/home/cltbld', 19:12:55 INFO - 'LANG': 'en_US.UTF-8', 19:12:55 INFO - 'LANGUAGE': 'en_US:en', 19:12:55 INFO - 'LOGNAME': 'cltbld', 19:12:55 INFO - 'MAIL': '/var/mail/cltbld', 19:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:55 INFO - 'MOZ_NO_REMOTE': '1', 19:12:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:55 INFO - 'NO_EM_RESTART': '1', 19:12:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:55 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:55 INFO - 'SHELL': '/bin/bash', 19:12:55 INFO - 'SHLVL': '1', 19:12:55 INFO - 'TERM': 'linux', 19:12:55 INFO - 'TMOUT': '86400', 19:12:55 INFO - 'USER': 'cltbld', 19:12:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:55 INFO - '_': '/tools/buildbot/bin/python'} 19:12:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:12:55 INFO - Running setup.py (path:/tmp/pip-ryxPGB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:12:56 INFO - Running setup.py (path:/tmp/pip-0ncKtv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:12:56 INFO - Running setup.py (path:/tmp/pip-WhIkYZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:12:56 INFO - Running setup.py (path:/tmp/pip-7FxPkX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:12:56 INFO - Running setup.py (path:/tmp/pip-7o2wfe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:12:56 INFO - Running setup.py (path:/tmp/pip-cWcvCu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:12:56 INFO - Running setup.py (path:/tmp/pip-3EB1GJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:12:56 INFO - Running setup.py (path:/tmp/pip-ZdTL5w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:12:56 INFO - Running setup.py (path:/tmp/pip-xWhevw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:12:56 INFO - Running setup.py (path:/tmp/pip-5X0ZZC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:12:56 INFO - Running setup.py (path:/tmp/pip-aJhm7B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:12:56 INFO - Running setup.py (path:/tmp/pip-l10qAP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:12:56 INFO - Running setup.py (path:/tmp/pip-LPKVDC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:12:56 INFO - Running setup.py (path:/tmp/pip-06K3GL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:12:56 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)) 19:12:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:12:56 INFO - Running setup.py (path:/tmp/pip-yFwtcq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:12:57 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)) 19:12:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:12:57 INFO - Running setup.py (path:/tmp/pip-OaNI2_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:12:57 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)) 19:12:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:12:57 INFO - Running setup.py (path:/tmp/pip-8HpkKL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:12:57 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)) 19:12:57 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)) 19:12:57 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)) 19:12:57 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)) 19:12:57 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)) 19:12:57 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)) 19:12:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:57 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 19:12:57 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 19:12:57 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 19:12:57 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 19:12:57 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 19:12:57 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 19:12:58 INFO - Downloading blessings-1.6.tar.gz 19:12:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:59 INFO - Installing collected packages: blessings 19:12:59 INFO - Running setup.py install for blessings 19:12:59 INFO - Successfully installed blessings 19:12:59 INFO - Cleaning up... 19:12:59 INFO - Return code: 0 19:12:59 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:12:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:12:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:12:59 INFO - Reading from file tmpfile_stdout 19:12:59 INFO - Current package versions: 19:12:59 INFO - argparse == 1.2.1 19:12:59 INFO - blessings == 1.6 19:12:59 INFO - blobuploader == 1.2.4 19:12:59 INFO - docopt == 0.6.1 19:12:59 INFO - manifestparser == 1.1 19:12:59 INFO - mozInstall == 1.12 19:12:59 INFO - mozcrash == 0.16 19:12:59 INFO - mozdebug == 0.1 19:12:59 INFO - mozdevice == 0.47 19:12:59 INFO - mozfile == 1.2 19:12:59 INFO - mozhttpd == 0.7 19:12:59 INFO - mozinfo == 0.9 19:12:59 INFO - mozleak == 0.1 19:12:59 INFO - mozlog == 3.1 19:12:59 INFO - moznetwork == 0.27 19:12:59 INFO - mozprocess == 0.22 19:12:59 INFO - mozprofile == 0.27 19:12:59 INFO - mozrunner == 6.11 19:12:59 INFO - mozscreenshot == 0.1 19:12:59 INFO - mozsystemmonitor == 0.0 19:12:59 INFO - moztest == 0.7 19:12:59 INFO - mozversion == 1.4 19:12:59 INFO - psutil == 3.1.1 19:12:59 INFO - requests == 1.2.3 19:12:59 INFO - wsgiref == 0.1.2 19:12:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:12:59 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')]} 19:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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')]} 19:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:59 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 19:12:59 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 19:12:59 INFO - Using env: {'DISPLAY': ':0', 19:12:59 INFO - 'HOME': '/home/cltbld', 19:12:59 INFO - 'LANG': 'en_US.UTF-8', 19:12:59 INFO - 'LANGUAGE': 'en_US:en', 19:12:59 INFO - 'LOGNAME': 'cltbld', 19:12:59 INFO - 'MAIL': '/var/mail/cltbld', 19:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:59 INFO - 'MOZ_NO_REMOTE': '1', 19:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:59 INFO - 'NO_EM_RESTART': '1', 19:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:59 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:59 INFO - 'SHELL': '/bin/bash', 19:12:59 INFO - 'SHLVL': '1', 19:12:59 INFO - 'TERM': 'linux', 19:12:59 INFO - 'TMOUT': '86400', 19:12:59 INFO - 'USER': 'cltbld', 19:12:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:59 INFO - '_': '/tools/buildbot/bin/python'} 19:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 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)) 19:12:59 INFO - Cleaning up... 19:12:59 INFO - Return code: 0 19:12:59 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:12:59 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')]} 19:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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')]} 19:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:12:59 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 19:12:59 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 19:12:59 INFO - Using env: {'DISPLAY': ':0', 19:12:59 INFO - 'HOME': '/home/cltbld', 19:12:59 INFO - 'LANG': 'en_US.UTF-8', 19:12:59 INFO - 'LANGUAGE': 'en_US:en', 19:12:59 INFO - 'LOGNAME': 'cltbld', 19:12:59 INFO - 'MAIL': '/var/mail/cltbld', 19:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:59 INFO - 'MOZ_NO_REMOTE': '1', 19:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:59 INFO - 'NO_EM_RESTART': '1', 19:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:12:59 INFO - 'PWD': '/builds/slave/test-pgo', 19:12:59 INFO - 'SHELL': '/bin/bash', 19:12:59 INFO - 'SHLVL': '1', 19:12:59 INFO - 'TERM': 'linux', 19:12:59 INFO - 'TMOUT': '86400', 19:12:59 INFO - 'USER': 'cltbld', 19:12:59 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:59 INFO - '_': '/tools/buildbot/bin/python'} 19:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:59 INFO - Downloading/unpacking jsonschema 19:12:59 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 19:12:59 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 19:12:59 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 19:12:59 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 19:12:59 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 19:12:59 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 19:13:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:13:01 INFO - Downloading/unpacking functools32 (from jsonschema) 19:13:01 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 19:13:01 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 19:13:01 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 19:13:01 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 19:13:01 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 19:13:01 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 19:13:01 INFO - Downloading functools32-3.2.3-2.tar.gz 19:13:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:13:01 INFO - warning: no files found matching '*.txt' 19:13:01 INFO - no previously-included directories found matching 'build' 19:13:01 INFO - no previously-included directories found matching 'dist' 19:13:01 INFO - no previously-included directories found matching '.git*' 19:13:01 INFO - Installing collected packages: jsonschema, functools32 19:13:02 INFO - Running setup.py install for functools32 19:13:02 INFO - warning: no files found matching '*.txt' 19:13:02 INFO - no previously-included directories found matching 'build' 19:13:02 INFO - no previously-included directories found matching 'dist' 19:13:02 INFO - no previously-included directories found matching '.git*' 19:13:02 INFO - Successfully installed jsonschema functools32 19:13:02 INFO - Cleaning up... 19:13:02 INFO - Return code: 0 19:13:02 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 19:13:02 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')]} 19:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:02 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')]} 19:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:02 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 0x7fd29aa09f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x290a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, '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': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', '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 19:13:02 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 19:13:02 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 19:13:02 INFO - Using env: {'DISPLAY': ':0', 19:13:02 INFO - 'HOME': '/home/cltbld', 19:13:02 INFO - 'LANG': 'en_US.UTF-8', 19:13:02 INFO - 'LANGUAGE': 'en_US:en', 19:13:02 INFO - 'LOGNAME': 'cltbld', 19:13:02 INFO - 'MAIL': '/var/mail/cltbld', 19:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:02 INFO - 'MOZ_NO_REMOTE': '1', 19:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:02 INFO - 'NO_EM_RESTART': '1', 19:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:13:02 INFO - 'PWD': '/builds/slave/test-pgo', 19:13:02 INFO - 'SHELL': '/bin/bash', 19:13:02 INFO - 'SHLVL': '1', 19:13:02 INFO - 'TERM': 'linux', 19:13:02 INFO - 'TMOUT': '86400', 19:13:02 INFO - 'USER': 'cltbld', 19:13:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:02 INFO - '_': '/tools/buildbot/bin/python'} 19:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:02 INFO - Downloading/unpacking flake8 19:13:02 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 19:13:02 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 19:13:02 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 19:13:02 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 19:13:02 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 19:13:02 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 19:13:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:13:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:13:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 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 19:13:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 19:13:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:13:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:13:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:13:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:13:04 INFO - no previously-included directories found matching 'docs/_build' 19:13:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:13:04 INFO - Running setup.py install for pep8 19:13:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:13:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:13:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:13:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:13:05 INFO - no previously-included directories found matching 'docs/_build' 19:13:05 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 19:13:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:13:05 INFO - Cleaning up... 19:13:05 INFO - Return code: 0 19:13:05 INFO - Running post-action listener: _resource_record_post_action 19:13:05 INFO - Running post-action listener: _start_resource_monitoring 19:13:05 INFO - Starting resource monitoring. 19:13:05 INFO - ##### 19:13:05 INFO - ##### Running install step. 19:13:05 INFO - ##### 19:13:05 INFO - Running pre-action listener: _resource_record_pre_action 19:13:05 INFO - Running main action method: install 19:13:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:13:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:13:05 INFO - Reading from file tmpfile_stdout 19:13:05 INFO - Detecting whether we're running mozinstall >=1.0... 19:13:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:13:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:13:05 INFO - Reading from file tmpfile_stdout 19:13:05 INFO - Output received: 19:13:05 INFO - Usage: mozinstall [options] installer 19:13:05 INFO - Options: 19:13:05 INFO - -h, --help show this help message and exit 19:13:05 INFO - -d DEST, --destination=DEST 19:13:05 INFO - Directory to install application into. [default: 19:13:05 INFO - "/builds/slave/test-pgo"] 19:13:05 INFO - --app=APP Application being installed. [default: firefox] 19:13:05 INFO - mkdir: /builds/slave/test-pgo/build/application 19:13:05 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'] 19:13:05 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 19:13:20 INFO - Reading from file tmpfile_stdout 19:13:20 INFO - Output received: 19:13:20 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:13:20 INFO - Running post-action listener: _resource_record_post_action 19:13:20 INFO - ##### 19:13:20 INFO - ##### Running run-tests step. 19:13:20 INFO - ##### 19:13:20 INFO - Running pre-action listener: _resource_record_pre_action 19:13:20 INFO - Running main action method: run_tests 19:13:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:13:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:13:20 INFO - Python 2.7.3 19:13:20 INFO - Return code: 0 19:13:20 INFO - grabbing minidump binary from tooltool 19:13:20 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')]} 19:13:20 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 0x2999350>, '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 0x299a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x299a6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:13:20 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 19:13:20 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 19:13:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:13:20 INFO - Return code: 0 19:13:20 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:13:20 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:13:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:13:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:13:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:13:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 19:13:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 19:13:21 INFO - Return code: 0 19:13:21 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/1455670072/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 19:13:21 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/1455670072/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost 19:13:21 INFO - Using env: {'DISPLAY': ':0', 19:13:21 INFO - 'HOME': '/home/cltbld', 19:13:21 INFO - 'LANG': 'en_US.UTF-8', 19:13:21 INFO - 'LANGUAGE': 'en_US:en', 19:13:21 INFO - 'LOGNAME': 'cltbld', 19:13:21 INFO - 'MAIL': '/var/mail/cltbld', 19:13:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:13:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:21 INFO - 'MOZ_NO_REMOTE': '1', 19:13:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:21 INFO - 'NO_EM_RESTART': '1', 19:13:21 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:13:21 INFO - 'PWD': '/builds/slave/test-pgo', 19:13:21 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:13:21 INFO - 'SHELL': '/bin/bash', 19:13:21 INFO - 'SHLVL': '1', 19:13:21 INFO - 'TERM': 'linux', 19:13:21 INFO - 'TMOUT': '86400', 19:13:21 INFO - 'USER': 'cltbld', 19:13:21 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003', 19:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:21 INFO - '_': '/tools/buildbot/bin/python'} 19:13:21 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/1455670072/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] with output_timeout 3600 19:13:21 INFO - mozversion INFO | application_buildid: 20160216164752 19:13:21 INFO - mozversion INFO | application_changeset: ab104d03fabf056944793086c5c08877f90d3377 19:13:21 INFO - mozversion INFO | application_display_name: Nightly 19:13:21 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:13:21 INFO - mozversion INFO | application_name: Firefox 19:13:21 INFO - mozversion INFO | application_remotingname: firefox 19:13:21 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:13:21 INFO - mozversion INFO | application_vendor: Mozilla 19:13:21 INFO - mozversion INFO | application_version: 45.0 19:13:21 INFO - mozversion INFO | platform_buildid: 20160216164752 19:13:21 INFO - mozversion INFO | platform_changeset: ab104d03fabf056944793086c5c08877f90d3377 19:13:21 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:13:21 INFO - mozversion INFO | platform_version: 45.0 19:13:21 INFO - 2016-02-16 19:13:21,386 DEBUG : using testdate: 1455678801 19:13:21 INFO - 2016-02-16 19:13:21,386 DEBUG : actual date: 1455678801 19:13:21 INFO - 2016-02-16 19:13:21,394 INFO : starting webserver on 'localhost:38791' 19:13:21 INFO - 2016-02-16 19:13:21,395 INFO : Starting test suite talos-linux64-ix-017 19:13:21 INFO - 2016-02-16 19:13:21,396 INFO : Starting test dromaeo_css 19:13:21 INFO - 2016-02-16 19:13:21,396 DEBUG : operating with platform_type : linux_ 19:13:21 INFO - 2016-02-16 19:13:21,396 INFO : Initialising browser for dromaeo_css test... 19:13:21 INFO - 2016-02-16 19:13:21,411 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaWKFCW/profile http://localhost:38791/getInfo.html 19:13:24 INFO - 2016-02-16 19:13:24,960 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:13:24 INFO - 2016-02-16 19:13:24,960 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:13:24 INFO - 2016-02-16 19:13:24,960 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:13:24 INFO - 2016-02-16 19:13:24,960 DEBUG : BROWSER_OUTPUT: __metrics 19:13:25 INFO - 2016-02-16 19:13:25,089 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:13:25 INFO - 2016-02-16 19:13:25,826 INFO : Browser initialized. 19:13:25 INFO - 2016-02-16 19:13:25,827 INFO : Running cycle 1/1 for dromaeo_css test... 19:13:25 INFO - 2016-02-16 19:13:25,827 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaWKFCW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:13:37 INFO - 2016-02-16 19:13:37,488 DEBUG : BROWSER_OUTPUT: RSS: Main: 132767744 19:13:37 INFO - 2016-02-16 19:13:37,489 DEBUG : BROWSER_OUTPUT: 19:14:29 INFO - 2016-02-16 19:14:29,218 DEBUG : BROWSER_OUTPUT: 1455678869217 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] 19:16:36 INFO - 2016-02-16 19:16:36,365 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38791/tests/dromaeo/cssquery-ext.html) 19:16:37 INFO - 2016-02-16 19:16:37,374 DEBUG : BROWSER_OUTPUT: RSS: Main: 214765568 19:16:37 INFO - 2016-02-16 19:16:37,374 DEBUG : BROWSER_OUTPUT: 19:19:39 INFO - 2016-02-16 19:19:39,604 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/dromaeo/cssquery-ext.html (next: http://localhost:38791/tests/dromaeo/cssquery-jquery.html) 19:19:40 INFO - 2016-02-16 19:19:40,166 DEBUG : BROWSER_OUTPUT: RSS: Main: 213090304 19:19:40 INFO - 2016-02-16 19:19:40,166 DEBUG : BROWSER_OUTPUT: 19:22:41 INFO - 2016-02-16 19:22:41,911 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38791/tests/dromaeo/cssquery-mootools.html) 19:22:42 INFO - 2016-02-16 19:22:42,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 218243072 19:22:42 INFO - 2016-02-16 19:22:42,473 DEBUG : BROWSER_OUTPUT: 19:25:44 INFO - 2016-02-16 19:25:44,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38791/tests/dromaeo/cssquery-prototype.html) 19:25:44 INFO - 2016-02-16 19:25:44,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 209567744 19:25:44 INFO - 2016-02-16 19:25:44,741 DEBUG : BROWSER_OUTPUT: 19:28:47 INFO - 2016-02-16 19:28:47,146 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38791/tests/dromaeo/cssquery-yui.html) 19:28:47 INFO - 2016-02-16 19:28:47,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 202391552 19:28:47 INFO - 2016-02-16 19:28:47,708 DEBUG : BROWSER_OUTPUT: 19:31:49 INFO - 2016-02-16 19:31:49,770 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/dromaeo/cssquery-yui.html (next: http://localhost:38791/tests/dromaeo/cssquery-dojo.html) 19:31:50 INFO - 2016-02-16 19:31:50,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 246386688 19:31:50 INFO - 2016-02-16 19:31:50,016 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,016 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:31:50 INFO - 2016-02-16 19:31:50,016 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:31:50 INFO - 2016-02-16 19:31:50,017 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:31:50 INFO - 2016-02-16 19:31:50,017 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:31:50 INFO - 2016-02-16 19:31:50,017 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3239.384516941782;3316.369889720953;3326.9833650831756;3335.5663763710722;3350.1289664687174;5514.11774116141;5526.474984876435;5528.710417301709;5534.446555344469;5569.164625306206;5544.6118771686;5565.833025009256;5575.135853942646;5577.44225577442;5591.384947655754;4956.2565615157655;5063.696178229318;5065.392152941652;5129.871753206181;5164.070467315901;4983.8504844854715;5012.774425150877;5020.196768517038;5024.221245706921;5043.974780126095;4944.876378090558;4972.801087956495;5035.043341765053;5036.169032109698;5040.949590504103;5042.974785126069;5089.134847075988;5158.097332966753;5194.740262986836;5196.376434827807;5028.723420211887;5096.745162741848;5101.311322971388;5115.309433226504;5131.948680513222;5190.351206099238;5247.10799164143;5345.412004679482;5351.25082488452;5391.4608539145775;5922.437368450033;5971.253593300838;5975.402459754033;5983.401659834025;5983.58114931955;5978.163057172;5994.640321580676;5998.4601385875485;6000.909986350208;6002.189704389895;5402.243685883979;5484.314460692413;5514.0901751211;5522.061249587579;5594;5999.040153575407;6026.156338112668;6063.8484037898525;6064.575479716377;6068.120122582201;5869.119632055226;5873.236479257669;5880.617759845682;5881.941180588225;5883.264591926009;5617.634853734576;5717.313922329347;5731.684757338303;5838.766449342063;5852.678102704307;5843.620164689367;5889.941100589025;5905.261842269716;5909.645421274738;5921.2006379138265;5660.575456840754;5891.3814049525035;5903.498202652822;5905.40945905406;5908.497777688859;4465.508794032654;4509.368688383629;4531.003179300548;4531.456225252925;4552.954470455253;4091.1408604193452;4094.242565125461;4096.180763847243;4097.13960068383;4120.1553681495225;5395.946040539623;5559.33288005442;5646.378898321182;5646.548276137899;5649.915251271234;5837.153612726216;5908.054711246266;5930.347661757203;5936.050231962865;5979.910301345483;5618.157276408489;5658.236138121383;5663.745131469093;5665.150227465913;5667.801626943037;4962.429320628102;5063.8480845574695;5089.109405853961;5190.896182076337;5192;6122.816315510542;6164.352742962014;6196.597221180609;6197.163382943335;6204.968975155186;6040.7885724000325;6060;6063.480335872814;6068.4841788448475;6100.908486372797;5892.410758924073;6069.5751297410525;6082.5438092142385;6188.164597779242;6225.035119556476;5893.5874488785375;5917.349091600006;5938.792142275;5941.376155503783;5964.194833697569;6025.0661147522205;6033.336333003454;6036.426539478743;6041.909371359346;6047.03247480401;5441.374241962225;5622.240997465454;5673.716314184357;5767.740451679769;5800;5788.652680839163;5836.241288632451;5839.678817665069;5842;5848.883022339614;5380.650257733235;5740.540756739534;5743.023685973311;5743.4256574342235;5753.942460575341;5692.5445964321925;5762.337331206797;5835.79574714883;5844.12338149281;5879.206307148651;5711.543076553782;5713.571482138773;5751.769929202785;5768.192453056659;5772.422757724194 19:31:50 INFO - 2016-02-16 19:31:50,019 DEBUG : BROWSER_OUTPUT: |1;ext.html;611.4008271893545;621.9129321894934;629.2543336146666;647.5693663713631;663.6548994522848;37110.81444592777;37852;37930.620693793055;37939.241215175716;38077.61922380776;44144.55855441445;45382.54617453824;45432.77283613577;45500.544994550044;45523;8029.197080291982;8146.592670366487;9047.095290470934;9168.495732734697;9220.907790922105;10764.13886889051;10931.836022459629;10969.506372213229;11007.119430445546;11022.944885275563;10701.197410194227;10785.892141078606;10808.567657293737;10823.971722686372;10834.891651083506;8330.958345208295;8524.488530688179;8529.488230706116;10656.627018054369;10776.5689372425;10493.160547156285;10514.21143414247;10666.946665266702;10668.94665526675;10670.093042091432;41831.90840457965;42138.1572368554;42232.366514502304;42309.78845105786;42333.15333693309;3493.615702272748;3495.720342372604;3562.697170740464;3721.274351501458;3899.122697393092;31492.685073149434;31608.209794755316;31644.683553164636;31807.840960795278;31917;4307.827686892552;4349.5215526292395;4394.582514661102;4449.532799056117;4539.977300113478;358824;363166;363857.9999999974;364063;365704;53968;55267;62859;63941.36058639354;64009.679951599945;74247.62876185584;74265;74388;74535.62732186304;74577.25422745812;15696.058236505845;15936.760948585781;15962.441314554168;15997.1201583914;16006.519804405887;11598.304101753922;11784.70538236551;11791.941040294916;11900;11923.523059077714;23317.650235246485;23673.816309184815;23677.526449470915;23677.644835327483;23783.048678052684;8794.384393092421;8798;8958.193762561401;8999.415038022507;9024.8646270306;935.8315503209351;949.8195342884829;957.3250858144991;958.8130314588727;1093.5680406239492;29900.70099298979;30048.39903201924;30739.538906916416;30854.537181942287;30999.69000309968;997.1005235027285;1090.231386872269;1125.5835340923911;1168.1530890104775;1255;14977.475788347356;15908.363665453484;17208.225629846685;17393.91303043502;18422.723659145122;406766;410039;410785;411067;412331;59113.70443147843;59350.40649593449;59651.40348596545;59891;60328.698356508816;1446.0383844743337;1460.5837336359086;1464.0410531102145;1469.235997281429;1472.7643577027839;32016.67983320137;32123.518147227343;32272.515912261806;32572;33380.165495861824;50637;51029.74485127551;51434;51465.7426712864;51497.22754158616;300962;302608;305671.47164264036;306436;307770;36127.45808812817;36522.269554608225;36561.634383656885;36692.44961325529;36736;46605.76697116493;47142.76428617972;47518.52481475141;47520.7623961878;47635.28547071866;39962.60037399589;40618.39072413857;45088;45570.31644525401;47426.76286618545;41598;42274.788626056674;42595.787021064694;43047.139057219305;43451.34822977721;40598.1880362397;41040.79479602583;41058.384124237564;41513;41610.79194604007;35658;36781.816090919376;36826.8158659205;37057.81471092627;40714;26570;26909.327266818487;27136.914523419633;27520.72479275262;28892.85553572219 19:31:50 INFO - 2016-02-16 19:31:50,020 DEBUG : BROWSER_OUTPUT: |2;jquery.html;391.60447947572953;395.68147641148875;397.13424734079695;397.3185986033953;403.10912882529607;14863.554093377215;16900.746488802648;17065.232064557094;18512.90743546282;18529;17082.914585427054;17505.474835754914;17518.737218941726;18325.175367108477;18707.625847483076;4913.115639184936;5001.17480615698;5007.674501157431;5029.9748501257445;5030.170021946375;3943.743656662308;4056.1076563156057;4993.325901003373;5627.409122042188;5764.51001664859;5697.886042279172;5710.229119068935;5710.657360558359;5728.971355143219;5752;5821.27234095738;6223.43989040984;6236.0334148206875;6236.282827474831;6240.500759939239;5454.12733962568;5556.888862222773;5576.693281869496;5585.441455854382;5590.776368945238;14271.286435678277;14298.213598252092;17814.91092544542;22102.77897221023;22375.88812055929;2342.1802369170823;2342.7540108288554;2430.3559556717482;2481.478889433203;2571.1643715792443;10270.38377697333;10380;10634.042936135707;11831.171817972745;13979;1979.416072258687;1988.0556735550642;1995.7704863940687;2019.5758890632985;2035.3283416472677;62974;64216.03675944818;65348.3465165347;65439;65568.67215663938;10913.743639980414;11084.836092210364;11324.32054076758;11330.37682927447;11424.714382140513;14909.627259318604;14910.850891491164;15057.397704091933;15060.171690557125;15078.396864125532;7967.203279672102;7987.3610111190965;8002.439829211899;8031;8081.0706768721175;7531.661075251627;7577.204393538709;7579.696812127563;7599.962000190074;7610.238976102456;4737;4780.163471392492;4815.422149342101;4840.177169881129;4846.8788280292565;1698.9830101699072;1703.684818308617;1748.5541186997234;1840.5674666453372;1857.384399766579;480.75962018990504;492.5911493460419;494.2265354719852;494.74767868386664;996.9003099689972;10990.945045274722;11205.215634905479;11292.774144517063;14055.22696251716;17234.741478877826;934.7990182110879;988.3723835364596;1032.143321043532;1049.333673117576;1058.2539309786737;9977.151942085004;9999.400035997864;10258.794824103625;10358.326708763907;10359.896401035892;638171;643895;658852;660307;668080;85924;86481;87604.56197719098;88120.55939720389;88270.55864720636;1197.455157903152;1204.1631066408936;1217.4582310871576;1218.3908045977012;1218.8049912014035;16389.59026024359;16409;16632.916835415745;16665.833341666428;16978.660426791146;37089;37916;38201.61798382092;38657;39076.0230994216;48820.75589622029;50206.748966254934;50279.74860125676;52418;53527.46472535225;10307.639232626821;10410.73973150647;10537.57849686004;10544.209184311054;10563.471826408802;13576.38906249241;18099.819001809814;18410.447686569423;18457.815421846146;18600.534986625444;15333.233338333263;15479.767803483182;15625.531234062997;15959.361625534848;15996.120213388376;15171.393144274105;15269;15314.693706126038;15578.376864925276;15698.686026279638;12846.293453860128;14699.853001469848;14831.851681483047;15197;15372.077675339067;9908.702738917844;9999.800004000024;10022.649207277711;10098.141657959155;10231.334963227664;10615.203859710708;10765.300255483682;11457.541698331974;14771.778423323874;15068.849311506745 19:31:50 INFO - 2016-02-16 19:31:50,024 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1536.4084827341474;1594.5136733296345;1616.8949018313815;1763;1878.6618408686431;7989.760307190779;8376.790580235498;8380.497170169794;8390.244877960982;8395.076541580429;6808.31916808319;6970.860582788354;7742.922570774291;7964.800879978004;8211.219934106266;2240.406292332533;2261.4459457432954;2279.555486680098;2289.954200915981;2291.3813270416977;2035.6946458031266;2304.043821813945;2323.6514522821544;2370.6918100646976;2374.169040835711;2014.0735261779598;2266.29744779119;2319.165100563804;2319.6288593824993;2322.454223257533;2247.3370355745096;2249.921252756154;2255.0979608156704;2303.9193628223097;2320.6983092197906;2344.10923848938;2347.0259842165483;2376.619740841457;2387.152560840894;2390.2829151254728;8183.918160818375;8251.917480825176;8260.421770476069;8260.917390826135;8269.793255168608;4845.394325709286;4959.628027897886;4968.254761785761;4969.925451118236;4971.1797553404;3163.1617621330365;3170.7780455368134;3223.049200485839;3269.640339562646;3287.03032605382;974.9025097490265;998.1316254858319;1065.7442213868617;1069.9144068474504;1116.7431490757172;11223.607173748962;11237.438128093645;11255.268407553483;11286.54853805855;11299.096072314278;4360.563943605677;4375.518692943773;4403.515613282536;4448.621867141264;4454.064646424223;9728.805423891483;9729.027097290214;9738.513074346396;9743.610255589698;9743.805123897624;4946.9010619787405;4980.05378977993;4983.227599722049;4986.875328116754;4987.750612469362;4527.411436513298;4538.886527836765;4545.636349092131;4547.522510136388;4547.613452856478;3823.6749876260274;3867.284552357823;3881.86413475527;3920.2355540669296;3922.588128246493;1091.1870656360902;1154.0998021543041;1157.6932112990064;1169.426980779412;1181.7518321152654;1074.897884700952;1129.4578602270792;1164.650604818551;1167.0197034491068;1195.838561794164;7709.151993280847;7711.421643376657;7797.610119494003;7806.765797026098;8050.798730031796;1183.5147589488267;1188.1326631559011;1197.3175290084741;1200.610784538779;1209.0448545649015;6202.869137865809;6230.283517395467;6299.181106456132;6302.464290535264;6302.558820882586;9904.405735655884;9941;10106.949465252774;10138.594456221668;10142.847857282146;9681.757956051015;9840.11438970496;10002.249831262685;10011.048950349705;10026.899731002743;7570.242975702496;7648.158702542716;7743.806404839924;7748.34139098183;7757.224277572198;7492.400607951242;7569.545827250383;7581.052368453943;7585.506942048749;7612.96193519051;8567;8583.527905965231;8750.781230469289;8795;8810.867836982323;8793.472391656522;9030;9032.729018129467;9036.23192028685;9038.508646073324;7109.82225444368;7174.067371241706;7188.532745371744;7189.065421495172;7196.604186769778;7609.505382150142;7704;7747.612619369122;8022.197780221931;8035.758927232192;6592.406683398517;7630.542167469969;7661.463697541119;7677.577733224726;7750.341220996053;6760.830979225362;7480.962595186989;7512.924870751441;7600.581967991813;7654.196309387434;7682.846343073219;7703.691852326068;7716.2669546393;7726.459147859596;7727.884081738891;6964.442844572321;7085.645717713991;7137.10786151731;7137.250588688113;7138;7118.216996130525;7147.46394020461;7192.100987376578;7202.675879585327;7217.314355136254 19:31:50 INFO - 2016-02-16 19:31:50,025 DEBUG : BROWSER_OUTPUT: |4;prototype.html;364.4023800966415;369.1656855506555;369.9500567423397;374.4794735317907;382.1554364853673;6002;6052.546059045572;6122.81631551053;6127.63234205948;6143.04782758672;5812.389699081599;5859.824205273838;5937.4656280934705;6018.729157187926;6032.396760323976;4652.302154676809;4675.438947326325;4684.718916864999;4687.90624187516;4688.413948256468;4443.133588950156;4687.90624187516;4688.976555117237;4691.390119284488;4692.906141877161;4473.9552604473865;4528.252838281696;4648.256278995362;4664.3003549467685;4671.766411679419;4608.470025947009;4632.513586073481;4635.165670179366;4635.443746750378;4644.187267228255;4110.0752330725645;4113.1568028554375;4119.814608342632;4120.793960302003;4123.670106391482;5605.887882242373;5743.10981797822;5747.712614369265;5753.626014309057;5755.827325180209;5482.890342193134;5507.504324610804;5514.172874068881;5517.972410137963;5522.061249587579;6463.644499552523;6470.45001174896;6470.74117035318;6475.611463312216;6484.513661475408;4654.627629789608;4655.511171326996;4657.673962822604;4658.301254811771;4662.603678687316;6529.902051469232;6596.703148358288;6677.031830384567;6681.933180668179;6692.431143352821;4688.929666055012;4705.670603057753;4708.458527269339;4710.105080034782;4714.057188562301;4737.8341758038305;4766.6901651392545;4769.403824521934;4772;4779.856604301807;5409;5422.322209723785;5437.700926449083;5442.891142177214;5451.48210919962;6345.270293916259;6491.837704057436;6499.54503184782;6504.772332968323;6509.479241660656;1696.2366934879353;1705.1900347334918;1717.9656406872043;1718.905460199676;1720.5698575356162;1163.1450883600546;1173;1179.0744265751516;1183.7928362536522;1197.1619866093595;6303.149074874925;6319.842003949938;6320.8419789504715;6322.178116844872;6322.525810564226;6517.837054073591;6678.1318428604945;6688.862893308149;6702;6704;6154.969225153935;6274.811755647338;6352.7141278642575;6361.713722882481;6377.298497165308;3572.8749493767596;3625.619309972415;3632.1282892105705;3632.8738091191817;3634.200475895299;6758.560693554903;6865.862682746317;6899.379055884994;6902.171739391305;6907.827304317432;6883.518153729191;6899.068625735562;6907.171139463297;6908.930910690829;6919.8962015569805;6900.585964842126;6902.654867256617;6904.965475172692;6907.75822846208;6911.6544172791155;6634.170728658918;6692.230393504809;6715.731370745116;6742.797716068526;6743.393094621508;6592.868142637216;6620.404163625297;6625.4037136657935;6627.436667883091;6630.90053649186;6864.691088900911;6878.449724022166;6885.380315771605;6890.586564806128;6895.827604309932;4688.249880019181;4767.237242041257;4774.427068751633;4775.498572649822;4776.283557466408;6703.664816759045;6713.731450742111;6714.060031595678;6715.093462382712;6719.899201512079;6453.128827608401;6465.418112369909;6467.3209313023335;6472.773452929125;6555.410013098844;6440.420362167428;6532.673366331569;6537.313582073813;6544.574602650812;6562.310957349409;6439.098526206241;6440.259370172303;6441.3880681335195;6444.194475690539;6451.967740161457;6566.409023187936;6573.046908198189;6576.8355791105605;6578.967105164444;6579.934200657932;6233.002719565029;6401.039844023434;6425.7750978717395;6428.453581445442;6483.319251478527 19:31:50 INFO - 2016-02-16 19:31:50,026 DEBUG : BROWSER_OUTPUT: |5;yui.html;801.2828518475964;835.3150416658335;888.9288856891453;912.1015799437557;939.1453777062874;14160.291985400727;14620.707585848277;14630.634234144152;14632.92683536582;14651;13714.451421943111;13761.105528140664;13792.44830206793;13824.170549766995;13835.861641383608;738.841149152932;745.7911784700293;876.1676407412936;876.8684697295425;886.0961818944672;881.3478026260585;884.4383816276678;884.9646014159426;890.4167770110588;895.4850960697593;763.3816608547095;911.8768966189579;919.5356345045742;919.5815903763771;920.4477313611847;768.1972338905829;769.776764738228;907.7412937312868;931.2596485793811;932.6083045120997;689.1454596300551;691.0394551573318;691.180950573572;691.5263044814268;691.5297597633607;13683.24742139182;13724.65688357789;13823.447062117502;13840.100393474493;13844;12369.505219791197;12887.484500619965;12894.290814005226;12914.612561623166;13161.407736651778;10678;10879.292845965067;10934;11004.284721493077;11343.149263805248;3575.7496975211748;3626.9274614507826;3633.418653015531;3693.741438099334;3706.3328600851837;47974.52025479735;48443.757781210865;49662;49763.999999999636;49889.25166122475;11031.669049928514;11121.388323642277;11272.154588405903;11293.37886416255;11410.486528106256;18651.81348186528;18771.81228187728;18789.718154227696;18834.24663013464;18878.716819247722;7803.921960780433;7925.28672419485;7964.641591128413;8097.635606397608;8104.270615644502;4238.3430568261965;4283.87148385549;4354.825806967686;4390.648748100144;4511.8646440606835;3305.9008229753144;3306.437905556061;3319.3527262184125;3321.817300048472;3322.0200040987966;563.4421922296897;574.9453801888898;584.6959581017846;588.9617174883618;590.0765302301969;620.6710443464971;664.9069130321759;675.3415419965494;721.1526456413693;764.2930289482207;8449.619767110495;8614.095519970313;8641.30869530436;8735.475871447734;8748.431351962103;563.9436056394328;625.4964753373487;687.8727435424463;690.7444245629149;692.6882902693808;4299.505556861001;4530.365748795171;4547.954520454819;4573.501642951615;4599.701019433726;57154.428455715744;57404.71297643569;57413.712931435075;57459.42540574541;58098.419015809304;52243.21635175475;52808.73595632074;53156.67108322323;53246.467535324926;53328.73335633297;47591.28613070807;47730.28404573934;47778.76110619494;48756.26865597019;48994;8854.203121718818;9002.774930626787;9110.544472776468;9148.771280718036;9486.857697134687;35082.47376289307;35161;35869.461958070126;36068.09829754277;36967.44548831716;43741.78129109334;44500.77749611361;44759;44905;45218.32172517481;11390.715232119263;11534.653960381202;11805.645830625095;11956.820647690465;12308.384580770746;13142.145760525535;13575.79636305476;13785.586432407044;13801.309934503033;14000.719985600435;11551.422428878355;11727.941360293144;11774.52901883915;11836.112291578338;11951.641450756144;11551.768964620493;11966.760664786829;12817.935910320388;13071.673208169872;13304.667383315495;12369;12463.5014599415;12511.937440313104;12572.434240459019;12948.805767913676;10426.29404447295;10646.73383165427;10707.250492465453;10952.507137179005;10963.83554246671;10174.542145603318;10453.163746900382;10459.163266938474;10507.737306567396;10591.788164236827 19:31:50 INFO - 2016-02-16 19:31:50,026 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:31:50 INFO - 2016-02-16 19:31:50,026 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:31:50 INFO - 2016-02-16 19:31:50,026 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,754 19:31:50 INFO - 2016-02-16 19:31:50,026 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: __startTimestamp1455679910016__endTimestamp 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5509.85 Median:5711.54 stddev:613.67 (10.7%) stddev-sans-first:589.13 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: Values: 3239.4 3316.4 3327.0 3335.6 3350.1 5514.1 5526.5 5528.7 5534.4 5569.2 5544.6 5565.8 5575.1 5577.4 5591.4 4956.3 5063.7 5065.4 5129.9 5164.1 4983.9 5012.8 5020.2 5024.2 5044.0 4944.9 4972.8 5035.0 5036.2 5040.9 5043.0 5089.1 5158.1 5194.7 5196.4 5028.7 5096.7 5101.3 5115.3 5131.9 5190.4 5247.1 5345.4 5351.3 5391.5 5922.4 5971.3 5975.4 5983.4 5983.6 5978.2 5994.6 5998.5 6000.9 6002.2 5402.2 5484.3 5514.1 5522.1 5594.0 5999.0 6026.2 6063.8 6064.6 6068.1 5869.1 5873.2 5880.6 5881.9 5883.3 5617.6 5717.3 5731.7 5838.8 5852.7 5843.6 5889.9 5905.3 5909.6 5921.2 5660.6 5891.4 5903.5 5905.4 5908.5 4465.5 4509.4 4531.0 4531.5 4553.0 4091.1 4094.2 4096.2 4097.1 4120.2 5395.9 5559.3 5646.4 5646.5 5649.9 5837.2 5908.1 5930.3 5936.1 5979.9 5618.2 5658.2 5663.7 5665.2 5667.8 4962.4 5063.8 5089.1 5190.9 5192.0 6122.8 6164.4 6196.6 6197.2 6205.0 6040.8 6060.0 6063.5 6068.5 6100.9 5892.4 6069.6 6082.5 6188.2 6225.0 5893.6 5917.3 5938.8 5941.4 5964.2 6025.1 6033.3 6036.4 6041.9 6047.0 5441.4 5622.2 5673.7 5767.7 5800.0 5788.7 5836.2 5839.7 5842.0 5848.9 5380.7 5740.5 5743.0 5743.4 5753.9 5692.5 5762.3 5835.8 5844.1 5879.2 5711.5 5713.6 5751.8 5768.2 5772.4 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,027 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54415.27 Median:20786.64 stddev:94982.04 (456.9%) stddev-sans-first:95162.58 19:31:50 INFO - 2016-02-16 19:31:50,029 DEBUG : BROWSER_OUTPUT: Values: 611.4 621.9 629.3 647.6 663.7 37110.8 37852.0 37930.6 37939.2 38077.6 44144.6 45382.5 45432.8 45500.5 45523.0 8029.2 8146.6 9047.1 9168.5 9220.9 10764.1 10931.8 10969.5 11007.1 11022.9 10701.2 10785.9 10808.6 10824.0 10834.9 8331.0 8524.5 8529.5 10656.6 10776.6 10493.2 10514.2 10666.9 10668.9 10670.1 41831.9 42138.2 42232.4 42309.8 42333.2 3493.6 3495.7 3562.7 3721.3 3899.1 31492.7 31608.2 31644.7 31807.8 31917.0 4307.8 4349.5 4394.6 4449.5 4540.0 358824.0 363166.0 363858.0 364063.0 365704.0 53968.0 55267.0 62859.0 63941.4 64009.7 74247.6 74265.0 74388.0 74535.6 74577.3 15696.1 15936.8 15962.4 15997.1 16006.5 11598.3 11784.7 11791.9 11900.0 11923.5 23317.7 23673.8 23677.5 23677.6 23783.0 8794.4 8798.0 8958.2 8999.4 9024.9 935.8 949.8 957.3 958.8 1093.6 29900.7 30048.4 30739.5 30854.5 30999.7 997.1 1090.2 1125.6 1168.2 1255.0 14977.5 15908.4 17208.2 17393.9 18422.7 406766.0 410039.0 410785.0 411067.0 412331.0 59113.7 59350.4 59651.4 59891.0 60328.7 1446.0 1460.6 1464.0 1469.2 1472.8 32016.7 32123.5 32272.5 32572.0 33380.2 50637.0 51029.7 51434.0 51465.7 51497.2 300962.0 302608.0 305671.5 306436.0 307770.0 36127.5 36522.3 36561.6 36692.4 36736.0 46605.8 47142.8 47518.5 47520.8 47635.3 39962.6 40618.4 45088.0 45570.3 47426.8 41598.0 42274.8 42595.8 43047.1 43451.3 40598.2 41040.8 41058.4 41513.0 41610.8 35658.0 36781.8 36826.8 37057.8 40714.0 26570.0 26909.3 27136.9 27520.7 28892.9 19:31:50 INFO - 2016-02-16 19:31:50,029 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,029 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32748.40 Median:2027.45 stddev:106834.34 (5269.4%) stddev-sans-first:107106.41 19:31:50 INFO - 2016-02-16 19:31:50,029 DEBUG : BROWSER_OUTPUT: Values: 391.6 395.7 397.1 397.3 403.1 14863.6 16900.7 17065.2 18512.9 18529.0 17082.9 17505.5 17518.7 18325.2 18707.6 4913.1 5001.2 5007.7 5030.0 5030.2 3943.7 4056.1 4993.3 5627.4 5764.5 5697.9 5710.2 5710.7 5729.0 5752.0 5821.3 6223.4 6236.0 6236.3 6240.5 5454.1 5556.9 5576.7 5585.4 5590.8 14271.3 14298.2 17814.9 22102.8 22375.9 2342.2 2342.8 2430.4 2481.5 2571.2 10270.4 10380.0 10634.0 11831.2 13979.0 1979.4 1988.1 1995.8 2019.6 2035.3 62974.0 64216.0 65348.3 65439.0 65568.7 10913.7 11084.8 11324.3 11330.4 11424.7 14909.6 14910.9 15057.4 15060.2 15078.4 7967.2 7987.4 8002.4 8031.0 8081.1 7531.7 7577.2 7579.7 7600.0 7610.2 4737.0 4780.2 4815.4 4840.2 4846.9 1699.0 1703.7 1748.6 1840.6 1857.4 480.8 492.6 494.2 494.7 996.9 10990.9 11205.2 11292.8 14055.2 17234.7 934.8 988.4 1032.1 1049.3 1058.3 9977.2 9999.4 10258.8 10358.3 10359.9 638171.0 643895.0 658852.0 660307.0 668080.0 85924.0 86481.0 87604.6 88120.6 88270.6 1197.5 1204.2 1217.5 1218.4 1218.8 16389.6 16409.0 16632.9 16665.8 16978.7 37089.0 37916.0 38201.6 38657.0 39076.0 48820.8 50206.7 50279.7 52418.0 53527.5 10307.6 10410.7 10537.6 10544.2 10563.5 13576.4 18099.8 18410.4 18457.8 18600.5 15333.2 15479.8 15625.5 15959.4 15996.1 15171.4 15269.0 15314.7 15578.4 15698.7 12846.3 14699.9 14831.9 15197.0 15372.1 9908.7 9999.8 10022.6 10098.1 10231.3 10615.2 10765.3 11457.5 14771.8 15068.8 19:31:50 INFO - 2016-02-16 19:31:50,029 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,029 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5765.49 Median:6264.73 stddev:3014.60 (48.1%) stddev-sans-first:3006.30 19:31:50 INFO - 2016-02-16 19:31:50,030 DEBUG : BROWSER_OUTPUT: Values: 1536.4 1594.5 1616.9 1763.0 1878.7 7989.8 8376.8 8380.5 8390.2 8395.1 6808.3 6970.9 7742.9 7964.8 8211.2 2240.4 2261.4 2279.6 2290.0 2291.4 2035.7 2304.0 2323.7 2370.7 2374.2 2014.1 2266.3 2319.2 2319.6 2322.5 2247.3 2249.9 2255.1 2303.9 2320.7 2344.1 2347.0 2376.6 2387.2 2390.3 8183.9 8251.9 8260.4 8260.9 8269.8 4845.4 4959.6 4968.3 4969.9 4971.2 3163.2 3170.8 3223.0 3269.6 3287.0 974.9 998.1 1065.7 1069.9 1116.7 11223.6 11237.4 11255.3 11286.5 11299.1 4360.6 4375.5 4403.5 4448.6 4454.1 9728.8 9729.0 9738.5 9743.6 9743.8 4946.9 4980.1 4983.2 4986.9 4987.8 4527.4 4538.9 4545.6 4547.5 4547.6 3823.7 3867.3 3881.9 3920.2 3922.6 1091.2 1154.1 1157.7 1169.4 1181.8 1074.9 1129.5 1164.7 1167.0 1195.8 7709.2 7711.4 7797.6 7806.8 8050.8 1183.5 1188.1 1197.3 1200.6 1209.0 6202.9 6230.3 6299.2 6302.5 6302.6 9904.4 9941.0 10106.9 10138.6 10142.8 9681.8 9840.1 10002.2 10011.0 10026.9 7570.2 7648.2 7743.8 7748.3 7757.2 7492.4 7569.5 7581.1 7585.5 7613.0 8567.0 8583.5 8750.8 8795.0 8810.9 8793.5 9030.0 9032.7 9036.2 9038.5 7109.8 7174.1 7188.5 7189.1 7196.6 7609.5 7704.0 7747.6 8022.2 8035.8 6592.4 7630.5 7661.5 7677.6 7750.3 6760.8 7481.0 7512.9 7600.6 7654.2 7682.8 7703.7 7716.3 7726.5 7727.9 6964.4 7085.6 7137.1 7137.3 7138.0 7118.2 7147.5 7192.1 7202.7 7217.3 19:31:50 INFO - 2016-02-16 19:31:50,030 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,030 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5444.22 Median:6193.99 stddev:1614.56 (26.1%) stddev-sans-first:1573.43 19:31:50 INFO - 2016-02-16 19:31:50,031 DEBUG : BROWSER_OUTPUT: Values: 364.4 369.2 370.0 374.5 382.2 6002.0 6052.5 6122.8 6127.6 6143.0 5812.4 5859.8 5937.5 6018.7 6032.4 4652.3 4675.4 4684.7 4687.9 4688.4 4443.1 4687.9 4689.0 4691.4 4692.9 4474.0 4528.3 4648.3 4664.3 4671.8 4608.5 4632.5 4635.2 4635.4 4644.2 4110.1 4113.2 4119.8 4120.8 4123.7 5605.9 5743.1 5747.7 5753.6 5755.8 5482.9 5507.5 5514.2 5518.0 5522.1 6463.6 6470.5 6470.7 6475.6 6484.5 4654.6 4655.5 4657.7 4658.3 4662.6 6529.9 6596.7 6677.0 6681.9 6692.4 4688.9 4705.7 4708.5 4710.1 4714.1 4737.8 4766.7 4769.4 4772.0 4779.9 5409.0 5422.3 5437.7 5442.9 5451.5 6345.3 6491.8 6499.5 6504.8 6509.5 1696.2 1705.2 1718.0 1718.9 1720.6 1163.1 1173.0 1179.1 1183.8 1197.2 6303.1 6319.8 6320.8 6322.2 6322.5 6517.8 6678.1 6688.9 6702.0 6704.0 6155.0 6274.8 6352.7 6361.7 6377.3 3572.9 3625.6 3632.1 3632.9 3634.2 6758.6 6865.9 6899.4 6902.2 6907.8 6883.5 6899.1 6907.2 6908.9 6919.9 6900.6 6902.7 6905.0 6907.8 6911.7 6634.2 6692.2 6715.7 6742.8 6743.4 6592.9 6620.4 6625.4 6627.4 6630.9 6864.7 6878.4 6885.4 6890.6 6895.8 4688.2 4767.2 4774.4 4775.5 4776.3 6703.7 6713.7 6714.1 6715.1 6719.9 6453.1 6465.4 6467.3 6472.8 6555.4 6440.4 6532.7 6537.3 6544.6 6562.3 6439.1 6440.3 6441.4 6444.2 6452.0 6566.4 6573.0 6576.8 6579.0 6579.9 6233.0 6401.0 6425.8 6428.5 6483.3 19:31:50 INFO - 2016-02-16 19:31:50,031 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,031 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14338.96 Median:24445.71 stddev:16181.26 (66.2%) stddev-sans-first:16194.72 19:31:50 INFO - 2016-02-16 19:31:50,031 DEBUG : BROWSER_OUTPUT: Values: 801.3 835.3 888.9 912.1 939.1 14160.3 14620.7 14630.6 14632.9 14651.0 13714.5 13761.1 13792.4 13824.2 13835.9 738.8 745.8 876.2 876.9 886.1 881.3 884.4 885.0 890.4 895.5 763.4 911.9 919.5 919.6 920.4 768.2 769.8 907.7 931.3 932.6 689.1 691.0 691.2 691.5 691.5 13683.2 13724.7 13823.4 13840.1 13844.0 12369.5 12887.5 12894.3 12914.6 13161.4 10678.0 10879.3 10934.0 11004.3 11343.1 3575.7 3626.9 3633.4 3693.7 3706.3 47974.5 48443.8 49662.0 49764.0 49889.3 11031.7 11121.4 11272.2 11293.4 11410.5 18651.8 18771.8 18789.7 18834.2 18878.7 7803.9 7925.3 7964.6 8097.6 8104.3 4238.3 4283.9 4354.8 4390.6 4511.9 3305.9 3306.4 3319.4 3321.8 3322.0 563.4 574.9 584.7 589.0 590.1 620.7 664.9 675.3 721.2 764.3 8449.6 8614.1 8641.3 8735.5 8748.4 563.9 625.5 687.9 690.7 692.7 4299.5 4530.4 4548.0 4573.5 4599.7 57154.4 57404.7 57413.7 57459.4 58098.4 52243.2 52808.7 53156.7 53246.5 53328.7 47591.3 47730.3 47778.8 48756.3 48994.0 8854.2 9002.8 9110.5 9148.8 9486.9 35082.5 35161.0 35869.5 36068.1 36967.4 43741.8 44500.8 44759.0 44905.0 45218.3 11390.7 11534.7 11805.6 11956.8 12308.4 13142.1 13575.8 13785.6 13801.3 14000.7 11551.4 11727.9 11774.5 11836.1 11951.6 11551.8 11966.8 12817.9 13071.7 13304.7 12369.0 12463.5 12511.9 12572.4 12948.8 10426.3 10646.7 10707.3 10952.5 10963.8 10174.5 10453.2 10459.2 10507.7 10591.8 19:31:50 INFO - 2016-02-16 19:31:50,031 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:31:50 INFO - 2016-02-16 19:31:50,031 DEBUG : BROWSER_OUTPUT: 19:31:50 INFO - 2016-02-16 19:31:50,610 INFO : Browser exited with error code: 0 19:31:50 INFO - 2016-02-16 19:31:50,634 INFO : Completed test dromaeo_css (00:18:29) 19:31:50 INFO - 2016-02-16 19:31:50,634 INFO : Starting test kraken 19:31:50 INFO - 2016-02-16 19:31:50,634 DEBUG : operating with platform_type : linux_ 19:31:50 INFO - 2016-02-16 19:31:50,634 INFO : Initialising browser for kraken test... 19:31:50 INFO - 2016-02-16 19:31:50,640 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphjILUd/profile http://localhost:38791/getInfo.html 19:31:54 INFO - 2016-02-16 19:31:54,105 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:31:54 INFO - 2016-02-16 19:31:54,105 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:31:54 INFO - 2016-02-16 19:31:54,105 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:31:54 INFO - 2016-02-16 19:31:54,105 DEBUG : BROWSER_OUTPUT: __metrics 19:31:54 INFO - 2016-02-16 19:31:54,268 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:31:54 INFO - 2016-02-16 19:31:54,922 INFO : Browser initialized. 19:31:54 INFO - 2016-02-16 19:31:54,923 INFO : Running cycle 1/1 for kraken test... 19:31:54 INFO - 2016-02-16 19:31:54,923 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphjILUd/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:32:06 INFO - 2016-02-16 19:32:06,550 DEBUG : BROWSER_OUTPUT: RSS: Main: 132562944 19:32:06 INFO - 2016-02-16 19:32:06,550 DEBUG : BROWSER_OUTPUT: 19:32:08 INFO - 2016-02-16 19:32:08,964 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38791/tests/kraken/driver.html?testName=audio-beat-detection) 19:32:09 INFO - 2016-02-16 19:32:09,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 165380096 19:32:09 INFO - 2016-02-16 19:32:09,531 DEBUG : BROWSER_OUTPUT: 19:32:12 INFO - 2016-02-16 19:32:12,100 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38791/tests/kraken/driver.html?testName=audio-dft) 19:32:12 INFO - 2016-02-16 19:32:12,666 DEBUG : BROWSER_OUTPUT: RSS: Main: 161443840 19:32:12 INFO - 2016-02-16 19:32:12,666 DEBUG : BROWSER_OUTPUT: 19:32:15 INFO - 2016-02-16 19:32:15,469 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38791/tests/kraken/driver.html?testName=audio-fft) 19:32:16 INFO - 2016-02-16 19:32:16,051 DEBUG : BROWSER_OUTPUT: RSS: Main: 159780864 19:32:16 INFO - 2016-02-16 19:32:16,051 DEBUG : BROWSER_OUTPUT: 19:32:18 INFO - 2016-02-16 19:32:18,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38791/tests/kraken/driver.html?testName=audio-oscillator) 19:32:18 INFO - 2016-02-16 19:32:18,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 157650944 19:32:18 INFO - 2016-02-16 19:32:18,583 DEBUG : BROWSER_OUTPUT: 19:32:20 INFO - 2016-02-16 19:32:20,744 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:32:21 INFO - 2016-02-16 19:32:21,310 DEBUG : BROWSER_OUTPUT: RSS: Main: 159023104 19:32:21 INFO - 2016-02-16 19:32:21,311 DEBUG : BROWSER_OUTPUT: 19:32:24 INFO - 2016-02-16 19:32:24,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38791/tests/kraken/driver.html?testName=imaging-darkroom) 19:32:24 INFO - 2016-02-16 19:32:24,695 DEBUG : BROWSER_OUTPUT: RSS: Main: 159166464 19:32:24 INFO - 2016-02-16 19:32:24,696 DEBUG : BROWSER_OUTPUT: 19:32:28 INFO - 2016-02-16 19:32:28,037 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38791/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:28 INFO - 2016-02-16 19:32:28,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 158384128 19:32:28 INFO - 2016-02-16 19:32:28,604 DEBUG : BROWSER_OUTPUT: 19:32:31 INFO - 2016-02-16 19:32:31,322 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38791/tests/kraken/driver.html?testName=json-parse-financial) 19:32:31 INFO - 2016-02-16 19:32:31,889 DEBUG : BROWSER_OUTPUT: RSS: Main: 159694848 19:32:31 INFO - 2016-02-16 19:32:31,889 DEBUG : BROWSER_OUTPUT: 19:32:33 INFO - 2016-02-16 19:32:33,430 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:33 INFO - 2016-02-16 19:32:33,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 164888576 19:32:33 INFO - 2016-02-16 19:32:33,991 DEBUG : BROWSER_OUTPUT: 19:32:35 INFO - 2016-02-16 19:32:35,762 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:36 INFO - 2016-02-16 19:32:36,356 DEBUG : BROWSER_OUTPUT: RSS: Main: 163577856 19:32:36 INFO - 2016-02-16 19:32:36,356 DEBUG : BROWSER_OUTPUT: 19:32:38 INFO - 2016-02-16 19:32:38,328 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:38 INFO - 2016-02-16 19:32:38,902 DEBUG : BROWSER_OUTPUT: RSS: Main: 166391808 19:32:38 INFO - 2016-02-16 19:32:38,902 DEBUG : BROWSER_OUTPUT: 19:32:41 INFO - 2016-02-16 19:32:41,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:41 INFO - 2016-02-16 19:32:41,829 DEBUG : BROWSER_OUTPUT: RSS: Main: 173690880 19:32:41 INFO - 2016-02-16 19:32:41,829 DEBUG : BROWSER_OUTPUT: 19:32:44 INFO - 2016-02-16 19:32:44,288 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:44 INFO - 2016-02-16 19:32:44,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 171163648 19:32:44 INFO - 2016-02-16 19:32:44,857 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38791/tests/kraken/driver.html?testName=ai-astar) 19:32:46 INFO - 2016-02-16 19:32:46,742 DEBUG : BROWSER_OUTPUT: RSS: Main: 229662720 19:32:46 INFO - 2016-02-16 19:32:46,742 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,742 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:32:46 INFO - 2016-02-16 19:32:46,742 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:32:46 INFO - 2016-02-16 19:32:46,742 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:32:46 INFO - 2016-02-16 19:32:46,743 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:32:46 INFO - 2016-02-16 19:32:46,743 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;110;103;107;109;108;98;107;108;110 19:32:46 INFO - 2016-02-16 19:32:46,743 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;120;154;152;126;144;149;120;166;122 19:32:46 INFO - 2016-02-16 19:32:46,744 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;163;161;162;161;161;161;160;160;159 19:32:46 INFO - 2016-02-16 19:32:46,744 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;73;71;152;73;71;72;71;157;71 19:32:46 INFO - 2016-02-16 19:32:46,744 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;134;92;92;92;147;91;91;132 19:32:46 INFO - 2016-02-16 19:32:46,744 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;108;109;109;109;109;109;109 19:32:46 INFO - 2016-02-16 19:32:46,744 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;146;145;146;145;146;146;146 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;96;95;95;95;95;95 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;59;56;57;56;68;56;55;55 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;65;64;66;64;65;64;64;65 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;76;76;77;77;78;76;77;78;79 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;111;122;115;124;115;123;116;109;112 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;150;137;138;145;138;138;139;138;139;129 19:32:46 INFO - 2016-02-16 19:32:46,745 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;65;63;61;63;65;62;62;62;61 19:32:46 INFO - 2016-02-16 19:32:46,746 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:32:46 INFO - 2016-02-16 19:32:46,746 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:32:46 INFO - 2016-02-16 19:32:46,746 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,915 19:32:46 INFO - 2016-02-16 19:32:46,746 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:32:46 INFO - 2016-02-16 19:32:46,746 DEBUG : BROWSER_OUTPUT: __startTimestamp1455679966741__endTimestamp 19:32:46 INFO - 2016-02-16 19:32:46,746 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.70 Median:108.50 stddev:3.65 (3.4%) stddev-sans-first:3.87 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: Values: 107.0 110.0 103.0 107.0 109.0 108.0 98.0 107.0 108.0 110.0 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,750 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.50 Median:150.50 stddev:16.91 (11.2%) stddev-sans-first:17.42 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: Values: 152.0 120.0 154.0 152.0 126.0 144.0 149.0 120.0 166.0 122.0 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.15 (0.7%) stddev-sans-first:1.17 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: Values: 162.0 163.0 161.0 162.0 161.0 161.0 161.0 160.0 160.0 159.0 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.10 Median:71.00 stddev:35.03 (49.3%) stddev-sans-first:36.54 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: Values: 70.0 73.0 71.0 152.0 73.0 71.0 72.0 71.0 157.0 71.0 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:92.00 stddev:22.40 (24.3%) stddev-sans-first:23.28 19:32:46 INFO - 2016-02-16 19:32:46,751 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 134.0 92.0 92.0 92.0 147.0 91.0 91.0 132.0 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 108.0 109.0 109.0 109.0 109.0 109.0 109.0 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,752 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.40 Median:56.50 stddev:3.92 (6.9%) stddev-sans-first:4.06 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 59.0 56.0 57.0 56.0 68.0 56.0 55.0 55.0 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 64.0 66.0 64.0 65.0 64.0 64.0 65.0 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.00 stddev:1.05 (1.4%) stddev-sans-first:1.05 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: Values: 76.0 76.0 76.0 77.0 77.0 78.0 76.0 77.0 78.0 79.0 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.60 Median:117.50 stddev:5.23 (4.5%) stddev-sans-first:5.48 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: Values: 119.0 111.0 122.0 115.0 124.0 115.0 123.0 116.0 109.0 112.0 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,753 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.10 Median:138.50 stddev:5.43 (3.9%) stddev-sans-first:4.08 19:32:46 INFO - 2016-02-16 19:32:46,754 DEBUG : BROWSER_OUTPUT: Values: 150.0 137.0 138.0 145.0 138.0 138.0 139.0 138.0 139.0 129.0 19:32:46 INFO - 2016-02-16 19:32:46,754 DEBUG : BROWSER_OUTPUT: 19:32:46 INFO - 2016-02-16 19:32:46,754 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:63.00 stddev:1.48 (2.3%) stddev-sans-first:1.50 19:32:46 INFO - 2016-02-16 19:32:46,754 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 63.0 61.0 63.0 65.0 62.0 62.0 62.0 61.0 19:32:46 INFO - 2016-02-16 19:32:46,754 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:32:46 INFO - 2016-02-16 19:32:46,754 DEBUG : BROWSER_OUTPUT: 19:32:47 INFO - 2016-02-16 19:32:47,435 INFO : Browser exited with error code: 0 19:32:47 INFO - 2016-02-16 19:32:47,485 INFO : Completed test kraken (00:00:56) 19:32:47 INFO - 2016-02-16 19:32:47,486 INFO : Starting test v8_7 19:32:47 INFO - 2016-02-16 19:32:47,486 DEBUG : operating with platform_type : linux_ 19:32:47 INFO - 2016-02-16 19:32:47,486 INFO : Initialising browser for v8_7 test... 19:32:47 INFO - 2016-02-16 19:32:47,492 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzZsW_G/profile http://localhost:38791/getInfo.html 19:32:50 INFO - 2016-02-16 19:32:50,683 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:32:50 INFO - 2016-02-16 19:32:50,683 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:32:50 INFO - 2016-02-16 19:32:50,683 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:32:50 INFO - 2016-02-16 19:32:50,683 DEBUG : BROWSER_OUTPUT: __metrics 19:32:50 INFO - 2016-02-16 19:32:50,800 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:32:51 INFO - 2016-02-16 19:32:51,830 INFO : Browser initialized. 19:32:51 INFO - 2016-02-16 19:32:51,831 INFO : Running cycle 1/1 for v8_7 test... 19:32:51 INFO - 2016-02-16 19:32:51,831 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzZsW_G/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:33:03 INFO - 2016-02-16 19:33:03,509 DEBUG : BROWSER_OUTPUT: RSS: Main: 132263936 19:33:03 INFO - 2016-02-16 19:33:03,509 DEBUG : BROWSER_OUTPUT: 19:33:24 INFO - 2016-02-16 19:33:24,905 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:38791/tests/v8_7/run.html (next: http://localhost:38791/tests/v8_7/run.html) 19:33:25 INFO - 2016-02-16 19:33:25,155 DEBUG : BROWSER_OUTPUT: RSS: Main: 424873984 19:33:25 INFO - 2016-02-16 19:33:25,155 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,155 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:33:25 INFO - 2016-02-16 19:33:25,155 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: |0;Richards;171.82130584192439 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;172.47326664367023 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.4694783573807 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5128.205128205128 19:33:25 INFO - 2016-02-16 19:33:25,156 DEBUG : BROWSER_OUTPUT: |4;RayTrace;955.1098376313277 19:33:25 INFO - 2016-02-16 19:33:25,157 DEBUG : BROWSER_OUTPUT: |5;Earley;540.8328826392644 19:33:25 INFO - 2016-02-16 19:33:25,157 DEBUG : BROWSER_OUTPUT: |6;Boyer;10589.473684210527 19:33:25 INFO - 2016-02-16 19:33:25,157 DEBUG : BROWSER_OUTPUT: |7;RegExp;24071.428571428572 19:33:25 INFO - 2016-02-16 19:33:25,157 DEBUG : BROWSER_OUTPUT: |8;Splay;521.9206680584551 19:33:25 INFO - 2016-02-16 19:33:25,157 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 19:33:25 INFO - 2016-02-16 19:33:25,157 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:33:25 INFO - 2016-02-16 19:33:25,158 DEBUG : BROWSER_OUTPUT: __startTimestamp1455680005154__endTimestamp 19:33:25 INFO - 2016-02-16 19:33:25,158 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,158 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:33:25 INFO - 2016-02-16 19:33:25,158 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:33:25 INFO - 2016-02-16 19:33:25,158 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,158 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:171.82 Median:171.82 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,159 DEBUG : BROWSER_OUTPUT: Values: 171.8 19:33:25 INFO - 2016-02-16 19:33:25,159 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,159 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:172.47 Median:172.47 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,159 DEBUG : BROWSER_OUTPUT: Values: 172.5 19:33:25 INFO - 2016-02-16 19:33:25,160 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,160 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.47 Median:277.47 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,160 DEBUG : BROWSER_OUTPUT: Values: 277.5 19:33:25 INFO - 2016-02-16 19:33:25,160 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,161 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5128.21 Median:5128.21 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,161 DEBUG : BROWSER_OUTPUT: Values: 5128.2 19:33:25 INFO - 2016-02-16 19:33:25,161 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,161 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:955.11 Median:955.11 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,162 DEBUG : BROWSER_OUTPUT: Values: 955.1 19:33:25 INFO - 2016-02-16 19:33:25,162 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,162 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:540.83 Median:540.83 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,162 DEBUG : BROWSER_OUTPUT: Values: 540.8 19:33:25 INFO - 2016-02-16 19:33:25,162 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10589.47 Median:10589.47 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: Values: 10589.5 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24071.43 Median:24071.43 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: Values: 24071.4 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:521.92 Median:521.92 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,163 DEBUG : BROWSER_OUTPUT: Values: 521.9 19:33:25 INFO - 2016-02-16 19:33:25,164 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,164 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 19:33:25 INFO - 2016-02-16 19:33:25,164 DEBUG : BROWSER_OUTPUT: Values: 4822.1 19:33:25 INFO - 2016-02-16 19:33:25,164 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:33:25 INFO - 2016-02-16 19:33:25,164 DEBUG : BROWSER_OUTPUT: 19:33:25 INFO - 2016-02-16 19:33:25,823 INFO : Browser exited with error code: 0 19:33:25 INFO - 2016-02-16 19:33:25,837 INFO : Completed test v8_7 (00:00:38) 19:33:26 INFO - 2016-02-16 19:33:26,136 INFO : Completed test suite (00:20:04) 19:33:26 INFO - 2016-02-16 19:33:26,136 DEBUG : Working with test: dromaeo_css 19:33:26 INFO - 2016-02-16 19:33:26,137 DEBUG : Generating results file: dromaeo_css 19:33:26 INFO - 2016-02-16 19:33:26,138 DEBUG : Working with test: kraken 19:33:26 INFO - 2016-02-16 19:33:26,138 DEBUG : Generating results file: kraken 19:33:26 INFO - 2016-02-16 19:33:26,139 INFO : javascript benchmark 19:33:26 INFO - 2016-02-16 19:33:26,139 DEBUG : Working with test: v8_7 19:33:26 INFO - 2016-02-16 19:33:26,139 DEBUG : Generating results file: v8_7 19:33:26 INFO - 2016-02-16 19:33:26,140 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:33:26 INFO - 2016-02-16 19:33:26,202 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 19:33:26 INFO - 2016-02-16 19:33:26,202 DEBUG : process_Request line: dromaeo_css 7369.85 graph.html#tests=[[72,53,35]] 19:33:26 INFO - 2016-02-16 19:33:26,202 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:33:26 INFO - 2016-02-16 19:33:26,238 DEBUG : process_Request line: kraken 1469.20 graph.html#tests=[[232,53,35]] 19:33:26 INFO - 2016-02-16 19:33:26,238 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:33:26 INFO - 2016-02-16 19:33:26,275 DEBUG : process_Request line: v8_7 23166.11 graph.html#tests=[[230,53,35]] 19:33:26 INFO - 2016-02-16 19:33:26,277 INFO : javascript benchmark 19:33:26 INFO - 2016-02-16 19:33:26,279 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3239.384516941782, 3316.369889720953, 3326.9833650831756, 3335.5663763710722, 3350.1289664687174, 5514.11774116141, 5526.474984876435, 5528.710417301709, 5534.446555344469, 5569.164625306206, 5544.6118771686, 5565.833025009256, 5575.135853942646, 5577.44225577442, 5591.384947655754, 4956.2565615157655, 5063.696178229318, 5065.392152941652, 5129.871753206181, 5164.070467315901, 4983.8504844854715, 5012.774425150877, 5020.196768517038, 5024.221245706921, 5043.974780126095, 4944.876378090558, 4972.801087956495, 5035.043341765053, 5036.169032109698, 5040.949590504103, 5042.974785126069, 5089.134847075988, 5158.097332966753, 5194.740262986836, 5196.376434827807, 5028.723420211887, 5096.745162741848, 5101.311322971388, 5115.309433226504, 5131.948680513222, 5190.351206099238, 5247.10799164143, 5345.412004679482, 5351.25082488452, 5391.4608539145775, 5922.437368450033, 5971.253593300838, 5975.402459754033, 5983.401659834025, 5983.58114931955, 5978.163057172, 5994.640321580676, 5998.4601385875485, 6000.909986350208, 6002.189704389895, 5402.243685883979, 5484.314460692413, 5514.0901751211, 5522.061249587579, 5594.0, 5999.040153575407, 6026.156338112668, 6063.8484037898525, 6064.575479716377, 6068.120122582201, 5869.119632055226, 5873.236479257669, 5880.617759845682, 5881.941180588225, 5883.264591926009, 5617.634853734576, 5717.313922329347, 5731.684757338303, 5838.766449342063, 5852.678102704307, 5843.620164689367, 5889.941100589025, 5905.261842269716, 5909.645421274738, 5921.2006379138265, 5660.575456840754, 5891.3814049525035, 5903.498202652822, 5905.40945905406, 5908.497777688859, 4465.508794032654, 4509.368688383629, 4531.003179300548, 4531.456225252925, 4552.954470455253, 4091.1408604193452, 4094.242565125461, 4096.180763847243, 4097.13960068383, 4120.1553681495225, 5395.946040539623, 5559.33288005442, 5646.378898321182, 5646.548276137899, 5649.915251271234, 5837.153612726216, 5908.054711246266, 5930.347661757203, 5936.050231962865, 5979.910301345483, 5618.157276408489, 5658.236138121383, 5663.745131469093, 5665.150227465913, 5667.801626943037, 4962.429320628102, 5063.8480845574695, 5089.109405853961, 5190.896182076337, 5192.0, 6122.816315510542, 6164.352742962014, 6196.597221180609, 6197.163382943335, 6204.968975155186, 6040.7885724000325, 6060.0, 6063.480335872814, 6068.4841788448475, 6100.908486372797, 5892.410758924073, 6069.5751297410525, 6082.5438092142385, 6188.164597779242, 6225.035119556476, 5893.5874488785375, 5917.349091600006, 5938.792142275, 5941.376155503783, 5964.194833697569, 6025.0661147522205, 6033.336333003454, 6036.426539478743, 6041.909371359346, 6047.03247480401, 5441.374241962225, 5622.240997465454, 5673.716314184357, 5767.740451679769, 5800.0, 5788.652680839163, 5836.241288632451, 5839.678817665069, 5842.0, 5848.883022339614, 5380.650257733235, 5740.540756739534, 5743.023685973311, 5743.4256574342235, 5753.942460575341, 5692.5445964321925, 5762.337331206797, 5835.79574714883, 5844.12338149281, 5879.206307148651, 5711.543076553782, 5713.571482138773, 5751.769929202785, 5768.192453056659, 5772.422757724194], "unit": "ms", "name": "dojo.html", "value": 5470.048623312317}, {"lowerIsBetter": false, "replicates": [611.4008271893545, 621.9129321894934, 629.2543336146666, 647.5693663713631, 663.6548994522848, 37110.81444592777, 37852.0, 37930.620693793055, 37939.241215175716, 38077.61922380776, 44144.55855441445, 45382.54617453824, 45432.77283613577, 45500.544994550044, 45523.0, 8029.197080291982, 8146.592670366487, 9047.095290470934, 9168.495732734697, 9220.907790922105, 10764.13886889051, 10931.836022459629, 10969.506372213229, 11007.119430445546, 11022.944885275563, 10701.197410194227, 10785.892141078606, 10808.567657293737, 10823.971722686372, 10834.891651083506, 8330.958345208295, 8524.488530688179, 8529.488230706116, 10656.627018054369, 10776.5689372425, 10493.160547156285, 10514.21143414247, 10666.946665266702, 10668.94665526675, 10670.093042091432, 41831.90840457965, 42138.1572368554, 42232.366514502304, 42309.78845105786, 42333.15333693309, 3493.615702272748, 3495.720342372604, 3562.697170740464, 3721.274351501458, 3899.122697393092, 31492.685073149434, 31608.209794755316, 31644.683553164636, 31807.840960795278, 31917.0, 4307.827686892552, 4349.5215526292395, 4394.582514661102, 4449.532799056117, 4539.977300113478, 358824.0, 363166.0, 363857.9999999974, 364063.0, 365704.0, 53968.0, 55267.0, 62859.0, 63941.36058639354, 64009.679951599945, 74247.62876185584, 74265.0, 74388.0, 74535.62732186304, 74577.25422745812, 15696.058236505845, 15936.760948585781, 15962.441314554168, 15997.1201583914, 16006.519804405887, 11598.304101753922, 11784.70538236551, 11791.941040294916, 11900.0, 11923.523059077714, 23317.650235246485, 23673.816309184815, 23677.526449470915, 23677.644835327483, 23783.048678052684, 8794.384393092421, 8798.0, 8958.193762561401, 8999.415038022507, 9024.8646270306, 935.8315503209351, 949.8195342884829, 957.3250858144991, 958.8130314588727, 1093.5680406239492, 29900.70099298979, 30048.39903201924, 30739.538906916416, 30854.537181942287, 30999.69000309968, 997.1005235027285, 1090.231386872269, 1125.5835340923911, 1168.1530890104775, 1255.0, 14977.475788347356, 15908.363665453484, 17208.225629846685, 17393.91303043502, 18422.723659145122, 406766.0, 410039.0, 410785.0, 411067.0, 412331.0, 59113.70443147843, 59350.40649593449, 59651.40348596545, 59891.0, 60328.698356508816, 1446.0383844743337, 1460.5837336359086, 1464.0410531102145, 1469.235997281429, 1472.7643577027839, 32016.67983320137, 32123.518147227343, 32272.515912261806, 32572.0, 33380.165495861824, 50637.0, 51029.74485127551, 51434.0, 51465.7426712864, 51497.22754158616, 300962.0, 302608.0, 305671.47164264036, 306436.0, 307770.0, 36127.45808812817, 36522.269554608225, 36561.634383656885, 36692.44961325529, 36736.0, 46605.76697116493, 47142.76428617972, 47518.52481475141, 47520.7623961878, 47635.28547071866, 39962.60037399589, 40618.39072413857, 45088.0, 45570.31644525401, 47426.76286618545, 41598.0, 42274.788626056674, 42595.787021064694, 43047.139057219305, 43451.34822977721, 40598.1880362397, 41040.79479602583, 41058.384124237564, 41513.0, 41610.79194604007, 35658.0, 36781.816090919376, 36826.8158659205, 37057.81471092627, 40714.0, 26570.0, 26909.327266818487, 27136.914523419633, 27520.72479275262, 28892.85553572219], "unit": "ms", "name": "ext.html", "value": 20637.603632933133}, {"lowerIsBetter": false, "replicates": [391.60447947572953, 395.68147641148875, 397.13424734079695, 397.3185986033953, 403.10912882529607, 14863.554093377215, 16900.746488802648, 17065.232064557094, 18512.90743546282, 18529.0, 17082.914585427054, 17505.474835754914, 17518.737218941726, 18325.175367108477, 18707.625847483076, 4913.115639184936, 5001.17480615698, 5007.674501157431, 5029.9748501257445, 5030.170021946375, 3943.743656662308, 4056.1076563156057, 4993.325901003373, 5627.409122042188, 5764.51001664859, 5697.886042279172, 5710.229119068935, 5710.657360558359, 5728.971355143219, 5752.0, 5821.27234095738, 6223.43989040984, 6236.0334148206875, 6236.282827474831, 6240.500759939239, 5454.12733962568, 5556.888862222773, 5576.693281869496, 5585.441455854382, 5590.776368945238, 14271.286435678277, 14298.213598252092, 17814.91092544542, 22102.77897221023, 22375.88812055929, 2342.1802369170823, 2342.7540108288554, 2430.3559556717482, 2481.478889433203, 2571.1643715792443, 10270.38377697333, 10380.0, 10634.042936135707, 11831.171817972745, 13979.0, 1979.416072258687, 1988.0556735550642, 1995.7704863940687, 2019.5758890632985, 2035.3283416472677, 62974.0, 64216.03675944818, 65348.3465165347, 65439.0, 65568.67215663938, 10913.743639980414, 11084.836092210364, 11324.32054076758, 11330.37682927447, 11424.714382140513, 14909.627259318604, 14910.850891491164, 15057.397704091933, 15060.171690557125, 15078.396864125532, 7967.203279672102, 7987.3610111190965, 8002.439829211899, 8031.0, 8081.0706768721175, 7531.661075251627, 7577.204393538709, 7579.696812127563, 7599.962000190074, 7610.238976102456, 4737.0, 4780.163471392492, 4815.422149342101, 4840.177169881129, 4846.8788280292565, 1698.9830101699072, 1703.684818308617, 1748.5541186997234, 1840.5674666453372, 1857.384399766579, 480.75962018990504, 492.5911493460419, 494.2265354719852, 494.74767868386664, 996.9003099689972, 10990.945045274722, 11205.215634905479, 11292.774144517063, 14055.22696251716, 17234.741478877826, 934.7990182110879, 988.3723835364596, 1032.143321043532, 1049.333673117576, 1058.2539309786737, 9977.151942085004, 9999.400035997864, 10258.794824103625, 10358.326708763907, 10359.896401035892, 638171.0, 643895.0, 658852.0, 660307.0, 668080.0, 85924.0, 86481.0, 87604.56197719098, 88120.55939720389, 88270.55864720636, 1197.455157903152, 1204.1631066408936, 1217.4582310871576, 1218.3908045977012, 1218.8049912014035, 16389.59026024359, 16409.0, 16632.916835415745, 16665.833341666428, 16978.660426791146, 37089.0, 37916.0, 38201.61798382092, 38657.0, 39076.0230994216, 48820.75589622029, 50206.748966254934, 50279.74860125676, 52418.0, 53527.46472535225, 10307.639232626821, 10410.73973150647, 10537.57849686004, 10544.209184311054, 10563.471826408802, 13576.38906249241, 18099.819001809814, 18410.447686569423, 18457.815421846146, 18600.534986625444, 15333.233338333263, 15479.767803483182, 15625.531234062997, 15959.361625534848, 15996.120213388376, 15171.393144274105, 15269.0, 15314.693706126038, 15578.376864925276, 15698.686026279638, 12846.293453860128, 14699.853001469848, 14831.851681483047, 15197.0, 15372.077675339067, 9908.702738917844, 9999.800004000024, 10022.649207277711, 10098.141657959155, 10231.334963227664, 10615.203859710708, 10765.300255483682, 11457.541698331974, 14771.778423323874, 15068.849311506745], "unit": "ms", "name": "jquery.html", "value": 9188.852788817165}, {"lowerIsBetter": false, "replicates": [1536.4084827341474, 1594.5136733296345, 1616.8949018313815, 1763.0, 1878.6618408686431, 7989.760307190779, 8376.790580235498, 8380.497170169794, 8390.244877960982, 8395.076541580429, 6808.31916808319, 6970.860582788354, 7742.922570774291, 7964.800879978004, 8211.219934106266, 2240.406292332533, 2261.4459457432954, 2279.555486680098, 2289.954200915981, 2291.3813270416977, 2035.6946458031266, 2304.043821813945, 2323.6514522821544, 2370.6918100646976, 2374.169040835711, 2014.0735261779598, 2266.29744779119, 2319.165100563804, 2319.6288593824993, 2322.454223257533, 2247.3370355745096, 2249.921252756154, 2255.0979608156704, 2303.9193628223097, 2320.6983092197906, 2344.10923848938, 2347.0259842165483, 2376.619740841457, 2387.152560840894, 2390.2829151254728, 8183.918160818375, 8251.917480825176, 8260.421770476069, 8260.917390826135, 8269.793255168608, 4845.394325709286, 4959.628027897886, 4968.254761785761, 4969.925451118236, 4971.1797553404, 3163.1617621330365, 3170.7780455368134, 3223.049200485839, 3269.640339562646, 3287.03032605382, 974.9025097490265, 998.1316254858319, 1065.7442213868617, 1069.9144068474504, 1116.7431490757172, 11223.607173748962, 11237.438128093645, 11255.268407553483, 11286.54853805855, 11299.096072314278, 4360.563943605677, 4375.518692943773, 4403.515613282536, 4448.621867141264, 4454.064646424223, 9728.805423891483, 9729.027097290214, 9738.513074346396, 9743.610255589698, 9743.805123897624, 4946.9010619787405, 4980.05378977993, 4983.227599722049, 4986.875328116754, 4987.750612469362, 4527.411436513298, 4538.886527836765, 4545.636349092131, 4547.522510136388, 4547.613452856478, 3823.6749876260274, 3867.284552357823, 3881.86413475527, 3920.2355540669296, 3922.588128246493, 1091.1870656360902, 1154.0998021543041, 1157.6932112990064, 1169.426980779412, 1181.7518321152654, 1074.897884700952, 1129.4578602270792, 1164.650604818551, 1167.0197034491068, 1195.838561794164, 7709.151993280847, 7711.421643376657, 7797.610119494003, 7806.765797026098, 8050.798730031796, 1183.5147589488267, 1188.1326631559011, 1197.3175290084741, 1200.610784538779, 1209.0448545649015, 6202.869137865809, 6230.283517395467, 6299.181106456132, 6302.464290535264, 6302.558820882586, 9904.405735655884, 9941.0, 10106.949465252774, 10138.594456221668, 10142.847857282146, 9681.757956051015, 9840.11438970496, 10002.249831262685, 10011.048950349705, 10026.899731002743, 7570.242975702496, 7648.158702542716, 7743.806404839924, 7748.34139098183, 7757.224277572198, 7492.400607951242, 7569.545827250383, 7581.052368453943, 7585.506942048749, 7612.96193519051, 8567.0, 8583.527905965231, 8750.781230469289, 8795.0, 8810.867836982323, 8793.472391656522, 9030.0, 9032.729018129467, 9036.23192028685, 9038.508646073324, 7109.82225444368, 7174.067371241706, 7188.532745371744, 7189.065421495172, 7196.604186769778, 7609.505382150142, 7704.0, 7747.612619369122, 8022.197780221931, 8035.758927232192, 6592.406683398517, 7630.542167469969, 7661.463697541119, 7677.577733224726, 7750.341220996053, 6760.830979225362, 7480.962595186989, 7512.924870751441, 7600.581967991813, 7654.196309387434, 7682.846343073219, 7703.691852326068, 7716.2669546393, 7726.459147859596, 7727.884081738891, 6964.442844572321, 7085.645717713991, 7137.10786151731, 7137.250588688113, 7138.0, 7118.216996130525, 7147.46394020461, 7192.100987376578, 7202.675879585327, 7217.314355136254], "unit": "ms", "name": "mootools.html", "value": 4706.040121678857}, {"lowerIsBetter": false, "replicates": [364.4023800966415, 369.1656855506555, 369.9500567423397, 374.4794735317907, 382.1554364853673, 6002.0, 6052.546059045572, 6122.81631551053, 6127.63234205948, 6143.04782758672, 5812.389699081599, 5859.824205273838, 5937.4656280934705, 6018.729157187926, 6032.396760323976, 4652.302154676809, 4675.438947326325, 4684.718916864999, 4687.90624187516, 4688.413948256468, 4443.133588950156, 4687.90624187516, 4688.976555117237, 4691.390119284488, 4692.906141877161, 4473.9552604473865, 4528.252838281696, 4648.256278995362, 4664.3003549467685, 4671.766411679419, 4608.470025947009, 4632.513586073481, 4635.165670179366, 4635.443746750378, 4644.187267228255, 4110.0752330725645, 4113.1568028554375, 4119.814608342632, 4120.793960302003, 4123.670106391482, 5605.887882242373, 5743.10981797822, 5747.712614369265, 5753.626014309057, 5755.827325180209, 5482.890342193134, 5507.504324610804, 5514.172874068881, 5517.972410137963, 5522.061249587579, 6463.644499552523, 6470.45001174896, 6470.74117035318, 6475.611463312216, 6484.513661475408, 4654.627629789608, 4655.511171326996, 4657.673962822604, 4658.301254811771, 4662.603678687316, 6529.902051469232, 6596.703148358288, 6677.031830384567, 6681.933180668179, 6692.431143352821, 4688.929666055012, 4705.670603057753, 4708.458527269339, 4710.105080034782, 4714.057188562301, 4737.8341758038305, 4766.6901651392545, 4769.403824521934, 4772.0, 4779.856604301807, 5409.0, 5422.322209723785, 5437.700926449083, 5442.891142177214, 5451.48210919962, 6345.270293916259, 6491.837704057436, 6499.54503184782, 6504.772332968323, 6509.479241660656, 1696.2366934879353, 1705.1900347334918, 1717.9656406872043, 1718.905460199676, 1720.5698575356162, 1163.1450883600546, 1173.0, 1179.0744265751516, 1183.7928362536522, 1197.1619866093595, 6303.149074874925, 6319.842003949938, 6320.8419789504715, 6322.178116844872, 6322.525810564226, 6517.837054073591, 6678.1318428604945, 6688.862893308149, 6702.0, 6704.0, 6154.969225153935, 6274.811755647338, 6352.7141278642575, 6361.713722882481, 6377.298497165308, 3572.8749493767596, 3625.619309972415, 3632.1282892105705, 3632.8738091191817, 3634.200475895299, 6758.560693554903, 6865.862682746317, 6899.379055884994, 6902.171739391305, 6907.827304317432, 6883.518153729191, 6899.068625735562, 6907.171139463297, 6908.930910690829, 6919.8962015569805, 6900.585964842126, 6902.654867256617, 6904.965475172692, 6907.75822846208, 6911.6544172791155, 6634.170728658918, 6692.230393504809, 6715.731370745116, 6742.797716068526, 6743.393094621508, 6592.868142637216, 6620.404163625297, 6625.4037136657935, 6627.436667883091, 6630.90053649186, 6864.691088900911, 6878.449724022166, 6885.380315771605, 6890.586564806128, 6895.827604309932, 4688.249880019181, 4767.237242041257, 4774.427068751633, 4775.498572649822, 4776.283557466408, 6703.664816759045, 6713.731450742111, 6714.060031595678, 6715.093462382712, 6719.899201512079, 6453.128827608401, 6465.418112369909, 6467.3209313023335, 6472.773452929125, 6555.410013098844, 6440.420362167428, 6532.673366331569, 6537.313582073813, 6544.574602650812, 6562.310957349409, 6439.098526206241, 6440.259370172303, 6441.3880681335195, 6444.194475690539, 6451.967740161457, 6566.409023187936, 6573.046908198189, 6576.8355791105605, 6578.967105164444, 6579.934200657932, 6233.002719565029, 6401.039844023434, 6425.7750978717395, 6428.453581445442, 6483.319251478527], "unit": "ms", "name": "prototype.html", "value": 4934.229438163663}, {"lowerIsBetter": false, "replicates": [801.2828518475964, 835.3150416658335, 888.9288856891453, 912.1015799437557, 939.1453777062874, 14160.291985400727, 14620.707585848277, 14630.634234144152, 14632.92683536582, 14651.0, 13714.451421943111, 13761.105528140664, 13792.44830206793, 13824.170549766995, 13835.861641383608, 738.841149152932, 745.7911784700293, 876.1676407412936, 876.8684697295425, 886.0961818944672, 881.3478026260585, 884.4383816276678, 884.9646014159426, 890.4167770110588, 895.4850960697593, 763.3816608547095, 911.8768966189579, 919.5356345045742, 919.5815903763771, 920.4477313611847, 768.1972338905829, 769.776764738228, 907.7412937312868, 931.2596485793811, 932.6083045120997, 689.1454596300551, 691.0394551573318, 691.180950573572, 691.5263044814268, 691.5297597633607, 13683.24742139182, 13724.65688357789, 13823.447062117502, 13840.100393474493, 13844.0, 12369.505219791197, 12887.484500619965, 12894.290814005226, 12914.612561623166, 13161.407736651778, 10678.0, 10879.292845965067, 10934.0, 11004.284721493077, 11343.149263805248, 3575.7496975211748, 3626.9274614507826, 3633.418653015531, 3693.741438099334, 3706.3328600851837, 47974.52025479735, 48443.757781210865, 49662.0, 49763.999999999636, 49889.25166122475, 11031.669049928514, 11121.388323642277, 11272.154588405903, 11293.37886416255, 11410.486528106256, 18651.81348186528, 18771.81228187728, 18789.718154227696, 18834.24663013464, 18878.716819247722, 7803.921960780433, 7925.28672419485, 7964.641591128413, 8097.635606397608, 8104.270615644502, 4238.3430568261965, 4283.87148385549, 4354.825806967686, 4390.648748100144, 4511.8646440606835, 3305.9008229753144, 3306.437905556061, 3319.3527262184125, 3321.817300048472, 3322.0200040987966, 563.4421922296897, 574.9453801888898, 584.6959581017846, 588.9617174883618, 590.0765302301969, 620.6710443464971, 664.9069130321759, 675.3415419965494, 721.1526456413693, 764.2930289482207, 8449.619767110495, 8614.095519970313, 8641.30869530436, 8735.475871447734, 8748.431351962103, 563.9436056394328, 625.4964753373487, 687.8727435424463, 690.7444245629149, 692.6882902693808, 4299.505556861001, 4530.365748795171, 4547.954520454819, 4573.501642951615, 4599.701019433726, 57154.428455715744, 57404.71297643569, 57413.712931435075, 57459.42540574541, 58098.419015809304, 52243.21635175475, 52808.73595632074, 53156.67108322323, 53246.467535324926, 53328.73335633297, 47591.28613070807, 47730.28404573934, 47778.76110619494, 48756.26865597019, 48994.0, 8854.203121718818, 9002.774930626787, 9110.544472776468, 9148.771280718036, 9486.857697134687, 35082.47376289307, 35161.0, 35869.461958070126, 36068.09829754277, 36967.44548831716, 43741.78129109334, 44500.77749611361, 44759.0, 44905.0, 45218.32172517481, 11390.715232119263, 11534.653960381202, 11805.645830625095, 11956.820647690465, 12308.384580770746, 13142.145760525535, 13575.79636305476, 13785.586432407044, 13801.309934503033, 14000.719985600435, 11551.422428878355, 11727.941360293144, 11774.52901883915, 11836.112291578338, 11951.641450756144, 11551.768964620493, 11966.760664786829, 12817.935910320388, 13071.673208169872, 13304.667383315495, 12369.0, 12463.5014599415, 12511.937440313104, 12572.434240459019, 12948.805767913676, 10426.29404447295, 10646.73383165427, 10707.250492465453, 10952.507137179005, 10963.83554246671, 10174.542145603318, 10453.163746900382, 10459.163266938474, 10507.737306567396, 10591.788164236827], "unit": "ms", "name": "yui.html", "value": 6651.594860504687}], "name": "dromaeo_css", "value": 7369.850916829122}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 110.0, 103.0, 107.0, 109.0, 108.0, 98.0, 107.0, 108.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 106.7}, {"lowerIsBetter": true, "replicates": [152.0, 120.0, 154.0, 152.0, 126.0, 144.0, 149.0, 120.0, 166.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.5}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 161.0, 162.0, 161.0, 161.0, 161.0, 160.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [70.0, 73.0, 71.0, 152.0, 73.0, 71.0, 72.0, 71.0, 157.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.1}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 134.0, 92.0, 92.0, 92.0, 147.0, 91.0, 91.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 59.0, 56.0, 57.0, 56.0, 68.0, 56.0, 55.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 64.0, 66.0, 64.0, 65.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.5}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 76.0, 77.0, 77.0, 78.0, 76.0, 77.0, 78.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.0}, {"lowerIsBetter": true, "replicates": [119.0, 111.0, 122.0, 115.0, 124.0, 115.0, 123.0, 116.0, 109.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.6}, {"lowerIsBetter": true, "replicates": [150.0, 137.0, 138.0, 145.0, 138.0, 138.0, 139.0, 138.0, 139.0, 129.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.1}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 63.0, 61.0, 63.0, 65.0, 62.0, 62.0, 62.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.8}], "name": "kraken", "value": 1469.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [171.82130584192439], "unit": "score", "name": "Richards", "value": 205.45764000000003}, {"lowerIsBetter": false, "replicates": [172.47326664367023], "unit": "score", "name": "DeltaBlue", "value": 383.35216400000013}, {"lowerIsBetter": false, "replicates": [277.4694783573807], "unit": "score", "name": "Encrypt", "value": 959.316324}, {"lowerIsBetter": false, "replicates": [5128.205128205128], "unit": "score", "name": "Decrypt", "value": 51.90529499999997}, {"lowerIsBetter": false, "replicates": [955.1098376313277], "unit": "score", "name": "RayTrace", "value": 774.7684829999998}, {"lowerIsBetter": false, "replicates": [540.8328826392644], "unit": "score", "name": "Earley", "value": 1232.290087}, {"lowerIsBetter": false, "replicates": [10589.473684210527], "unit": "score", "name": "Boyer", "value": 62.93636679920471}, {"lowerIsBetter": false, "replicates": [24071.428571428572], "unit": "score", "name": "RegExp", "value": 37.845074183976266}, {"lowerIsBetter": false, "replicates": [521.9206680584551], "unit": "score", "name": "Splay", "value": 156.13675599999996}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23166.111328023326}]} 19:33:26 INFO - RETURN: dromaeo_css: 7369.85 19:33:26 INFO - RETURN: kraken: 1469.20 19:33:26 INFO - RETURN: v8_7: 23166.11 19:33:26 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23166.11"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1469.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7369.85"}}} 19:33:26 INFO - Return code: 0 19:33:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:33:26 INFO - # TBPL SUCCESS # 19:33:26 INFO - Running post-action listener: _resource_record_post_action 19:33:26 INFO - Running post-run listener: _resource_record_post_run 19:33:27 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 319488; Write bytes: 522190848; Read time: 148; Write time: 531988 19:33:27 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 4096; Write bytes: 258420736; Read time: 100; Write time: 347468 19:33:27 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 315392; Write bytes: 263430144; Read time: 48; Write time: 184500 19:33:27 INFO - Running post-run listener: _upload_blobber_files 19:33:27 INFO - Blob upload gear active. 19:33:27 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:33:27 INFO - Copying logs to upload dir... 19:33:27 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1252.959158 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-16 19:33:27.077806) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-16 19:33:27.081241) ========= 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009959 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455670072/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-16 19:33:27.131902) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:33:27.132260) ========= 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=0333a256a5e4f052382dd180000001b1-1455675494.994888-1884610003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-16 19:33:27.181693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-16 19:33:27.182011) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-16 19:33:27.182311) ========= ========= Total master_lag: 0.26 =========