builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1455746931.36 results: success (0) buildid: 20160217090854 builduid: f8eec9df67b8418ea804a744abd0f928 revision: 6fb8393e40e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.362980) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.363460) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.363746) ========= 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006219 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.386245) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.386550) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.400254) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.400629) ========= 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004599 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.449722) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.450043) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.450391) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.450667) ========= 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-17 14:08:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.0M=0s 2016-02-17 14:08:51 (38.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174717 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.637814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.638123) ========= 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017360 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.687687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:51.688046) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6fb8393e40e7 --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 6fb8393e40e7 --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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-17 14:08:51,978 Setting DEBUG logging. 2016-02-17 14:08:51,978 attempt 1/10 2016-02-17 14:08:51,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6fb8393e40e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-17 14:08:52,230 unpacking tar archive at: mozilla-beta-6fb8393e40e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.522447 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:52.223244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:52.223594) ========= echo 6fb8393e40e7 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'6fb8393e40e7'] 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 6fb8393e40e7 program finished with exit code 0 elapsedTime=0.003366 script_repo_revision: '6fb8393e40e7' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:52.271564) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:52.271880) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-17 14:08:52.281771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-17 14:08:52.282136) ========= /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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:08:52 INFO - MultiFileLogger online at 20160217 14:08:52 in /builds/slave/test-pgo 14:08:52 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 14:08:52 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:08:52 INFO - {'append_to_log': False, 14:08:52 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:08:52 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:08:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:08:52 INFO - 'branch': 'Mozilla-Beta', 14:08:52 INFO - 'buildbot_json_path': 'buildprops.json', 14:08:52 INFO - 'config_files': ('talos/linux_config.py',), 14:08:52 INFO - 'default_actions': ('clobber', 14:08:52 INFO - 'read-buildbot-config', 14:08:52 INFO - 'download-and-extract', 14:08:52 INFO - 'populate-webroot', 14:08:52 INFO - 'create-virtualenv', 14:08:52 INFO - 'install', 14:08:52 INFO - 'run-tests'), 14:08:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:08:52 INFO - 'download_minidump_stackwalk': True, 14:08:52 INFO - 'download_symbols': 'ondemand', 14:08:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:08:52 INFO - 'tooltool.py': '/tools/tooltool.py', 14:08:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:08:52 INFO - '/tools/misc-python/virtualenv.py')}, 14:08:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:08:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:08:52 INFO - 'installer_path': 'installer.exe', 14:08:52 INFO - 'log_level': 'info', 14:08:52 INFO - 'log_name': 'talos', 14:08:52 INFO - 'log_to_console': True, 14:08:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:08:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:08:52 INFO - 'opt_config_files': (), 14:08:52 INFO - 'pip_index': False, 14:08:52 INFO - 'sps_profile': False, 14:08:52 INFO - 'sps_profile_interval': 0, 14:08:52 INFO - 'suite': 'dromaeojs', 14:08:52 INFO - 'system_bits': '32', 14:08:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:08:52 INFO - 'title': 'talos-linux64-ix-017', 14:08:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:08:52 INFO - 'use_talos_json': True, 14:08:52 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:08:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:08:52 INFO - 'work_dir': 'build'} 14:08:52 INFO - ##### 14:08:52 INFO - ##### Running clobber step. 14:08:52 INFO - ##### 14:08:52 INFO - Running pre-action listener: _resource_record_pre_action 14:08:52 INFO - Running main action method: clobber 14:08:52 INFO - rmtree: /builds/slave/test-pgo/build 14:08:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:08:53 INFO - Running post-action listener: _resource_record_post_action 14:08:53 INFO - ##### 14:08:53 INFO - ##### Running read-buildbot-config step. 14:08:53 INFO - ##### 14:08:53 INFO - Running pre-action listener: _resource_record_pre_action 14:08:53 INFO - Running main action method: read_buildbot_config 14:08:53 INFO - Using buildbot properties: 14:08:53 INFO - { 14:08:53 INFO - "properties": { 14:08:53 INFO - "buildnumber": 112, 14:08:53 INFO - "product": "firefox", 14:08:53 INFO - "script_repo_revision": "production", 14:08:53 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:08:53 INFO - "repository": "", 14:08:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:08:53 INFO - "buildid": "20160217090854", 14:08:53 INFO - "slavename": "talos-linux64-ix-017", 14:08:53 INFO - "pgo_build": "True", 14:08:53 INFO - "basedir": "/builds/slave/test-pgo", 14:08:53 INFO - "project": "", 14:08:53 INFO - "platform": "ubuntu64_hw", 14:08:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:08:53 INFO - "slavebuilddir": "test", 14:08:53 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:08:53 INFO - "branch": "mozilla-beta", 14:08:53 INFO - "repo_path": "releases/mozilla-beta", 14:08:53 INFO - "stage_platform": "linux64-pgo", 14:08:53 INFO - "builduid": "f8eec9df67b8418ea804a744abd0f928", 14:08:53 INFO - "revision": "6fb8393e40e7" 14:08:53 INFO - }, 14:08:53 INFO - "sourcestamp": { 14:08:53 INFO - "repository": "", 14:08:53 INFO - "hasPatch": false, 14:08:53 INFO - "project": "", 14:08:53 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:08:53 INFO - "changes": [ 14:08:53 INFO - { 14:08:53 INFO - "category": null, 14:08:53 INFO - "files": [ 14:08:53 INFO - { 14:08:53 INFO - "url": null, 14:08:53 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2" 14:08:53 INFO - } 14:08:53 INFO - ], 14:08:53 INFO - "repository": "", 14:08:53 INFO - "rev": "6fb8393e40e7", 14:08:53 INFO - "who": "sendchange", 14:08:53 INFO - "when": 1455746805, 14:08:53 INFO - "number": 7618537, 14:08:53 INFO - "comments": "No bug. Update Loop_s run all tests helper script to match reality - browser_UITour_loop is skipped at the moment. rs=me,a=NPOTB", 14:08:53 INFO - "project": "", 14:08:53 INFO - "at": "Wed 17 Feb 2016 14:06:45", 14:08:53 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 14:08:53 INFO - "revlink": "", 14:08:53 INFO - "properties": [ 14:08:53 INFO - [ 14:08:53 INFO - "buildid", 14:08:53 INFO - "20160217090854", 14:08:53 INFO - "Change" 14:08:53 INFO - ], 14:08:53 INFO - [ 14:08:53 INFO - "builduid", 14:08:53 INFO - "f8eec9df67b8418ea804a744abd0f928", 14:08:53 INFO - "Change" 14:08:53 INFO - ], 14:08:53 INFO - [ 14:08:53 INFO - "pgo_build", 14:08:53 INFO - "True", 14:08:53 INFO - "Change" 14:08:53 INFO - ] 14:08:53 INFO - ], 14:08:53 INFO - "revision": "6fb8393e40e7" 14:08:53 INFO - } 14:08:53 INFO - ], 14:08:53 INFO - "revision": "6fb8393e40e7" 14:08:53 INFO - } 14:08:53 INFO - } 14:08:53 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2. 14:08:53 INFO - Running post-action listener: _resource_record_post_action 14:08:53 INFO - ##### 14:08:53 INFO - ##### Running download-and-extract step. 14:08:53 INFO - ##### 14:08:53 INFO - Running pre-action listener: _resource_record_pre_action 14:08:53 INFO - Running main action method: download_and_extract 14:08:53 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/test_packages.json 14:08:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:08:53 INFO - Downloaded 1270 bytes. 14:08:53 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:08:53 INFO - Using the following test package requirements: 14:08:53 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 14:08:53 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:08:53 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'jsshell-linux-x86_64.zip'], 14:08:53 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:08:53 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 14:08:53 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 14:08:53 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 14:08:53 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:08:53 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 14:08:53 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 14:08:53 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:08:53 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:08:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:54 INFO - Downloaded 22255758 bytes. 14:08:54 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 14:08:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:08:54 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 14:08:55 INFO - Return code: 0 14:08:55 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 14:08: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')]} 14:08:55 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:08:55 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:55 INFO - Downloaded 22255758 bytes. 14:08:55 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 14:08:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 14:08:55 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 14:08:56 INFO - Return code: 0 14:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:56 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 14:08:56 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:57 INFO - Downloaded 11132038 bytes. 14:08:57 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 14:08:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 14:08:57 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 14:08:57 INFO - Return code: 0 14:08:57 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')]} 14:08:57 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:08:57 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/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 14:08:58 INFO - Downloaded 58323646 bytes. 14:08:58 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:08:58 INFO - mkdir: /builds/slave/test-pgo/properties 14:08:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:08:58 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:08:58 INFO - Contents: 14:08:58 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2 14:08:58 INFO - Running post-action listener: _resource_record_post_action 14:08:58 INFO - Running post-action listener: set_extra_try_arguments 14:08:58 INFO - ##### 14:08:58 INFO - ##### Running populate-webroot step. 14:08:58 INFO - ##### 14:08:58 INFO - Running pre-action listener: _resource_record_pre_action 14:08:58 INFO - Running main action method: populate_webroot 14:08:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:08:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:08:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:08:58 INFO - '--tppagecycles', 14:08:58 INFO - '7'], 14:08:58 INFO - 'tests': ['tsvgm']}}, 14:08:58 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:08:58 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:08:58 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:08:58 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:08:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:08:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:08:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'tests': ['damp', 'tps']}, 14:08:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['damp', 'tps']}, 14:08:58 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:08:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:08:58 INFO - 'other': {'tests': ['a11yr', 14:08:58 INFO - 'ts_paint', 14:08:58 INFO - 'tpaint', 14:08:58 INFO - 'sessionrestore', 14:08:58 INFO - 'sessionrestore_no_auto_restore']}, 14:08:58 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['a11yr', 14:08:58 INFO - 'ts_paint', 14:08:58 INFO - 'tpaint', 14:08:58 INFO - 'sessionrestore', 14:08:58 INFO - 'sessionrestore_no_auto_restore']}, 14:08:58 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['a11yr', 14:08:58 INFO - 'ts_paint', 14:08:58 INFO - 'tpaint', 14:08:58 INFO - 'sessionrestore', 14:08:58 INFO - 'sessionrestore_no_auto_restore']}, 14:08:58 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['a11yr', 14:08:58 INFO - 'ts_paint', 14:08:58 INFO - 'tpaint', 14:08:58 INFO - 'sessionrestore', 14:08:58 INFO - 'sessionrestore_no_auto_restore']}, 14:08:58 INFO - 'other_l64': {'tests': ['a11yr', 14:08:58 INFO - 'ts_paint', 14:08:58 INFO - 'tpaint', 14:08:58 INFO - 'sessionrestore', 14:08:58 INFO - 'sessionrestore_no_auto_restore']}, 14:08:58 INFO - 'other_nol64': {'tests': ['a11yr', 14:08:58 INFO - 'ts_paint', 14:08:58 INFO - 'tpaint', 14:08:58 INFO - 'sessionrestore', 14:08:58 INFO - 'sessionrestore_no_auto_restore']}, 14:08:58 INFO - 'svgr': {'tests': ['tsvgx', 14:08:58 INFO - 'tsvgr_opacity', 14:08:58 INFO - 'tart', 14:08:58 INFO - 'tscrollx', 14:08:58 INFO - 'cart']}, 14:08:58 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['tsvgx', 14:08:58 INFO - 'tsvgr_opacity', 14:08:58 INFO - 'tart', 14:08:58 INFO - 'tscrollx', 14:08:58 INFO - 'cart']}, 14:08:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'tests': ['tp5o']}, 14:08:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'talos_options': ['--e10s'], 14:08:58 INFO - 'tests': ['tp5o']}, 14:08:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'talos_options': ['--xperf_path', 14:08:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:08:58 INFO - 'tests': ['tp5n']}, 14:08:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:58 INFO - 'talos_options': ['--e10s', 14:08:58 INFO - '--xperf_path', 14:08:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:08:58 INFO - 'tests': ['tp5n']}}, 14:08:58 INFO - 'talos.zip': {'path': '', 14:08:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:08:58 INFO - Running post-action listener: _resource_record_post_action 14:08:58 INFO - ##### 14:08:58 INFO - ##### Running create-virtualenv step. 14:08:58 INFO - ##### 14:08:58 INFO - Running pre-action listener: _resource_record_pre_action 14:08:58 INFO - Running main action method: create_virtualenv 14:08:58 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:08:58 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 14:08:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:08:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:08:58 INFO - Using real prefix '/usr' 14:08:58 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:08:59 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:01 INFO - Installing pip.................done. 14:09:01 INFO - Return code: 0 14:09:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:09:01 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')]} 14:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:01 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')]} 14:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:01 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:01 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 14:09:01 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 14:09:01 INFO - Using env: {'DISPLAY': ':0', 14:09:01 INFO - 'HOME': '/home/cltbld', 14:09:01 INFO - 'LANG': 'en_US.UTF-8', 14:09:01 INFO - 'LANGUAGE': 'en_US:en', 14:09:01 INFO - 'LOGNAME': 'cltbld', 14:09:01 INFO - 'MAIL': '/var/mail/cltbld', 14:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:01 INFO - 'MOZ_NO_REMOTE': '1', 14:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:01 INFO - 'NO_EM_RESTART': '1', 14:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:01 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:01 INFO - 'SHELL': '/bin/bash', 14:09:01 INFO - 'SHLVL': '1', 14:09:01 INFO - 'TERM': 'linux', 14:09:01 INFO - 'TMOUT': '86400', 14:09:01 INFO - 'USER': 'cltbld', 14:09:01 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:01 INFO - '_': '/tools/buildbot/bin/python'} 14:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:01 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 14:09:01 INFO - Cleaning up... 14:09:01 INFO - Return code: 0 14:09:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:09:01 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')]} 14:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:01 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')]} 14:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:01 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:01 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 14:09:01 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 14:09:01 INFO - Using env: {'DISPLAY': ':0', 14:09:01 INFO - 'HOME': '/home/cltbld', 14:09:01 INFO - 'LANG': 'en_US.UTF-8', 14:09:01 INFO - 'LANGUAGE': 'en_US:en', 14:09:01 INFO - 'LOGNAME': 'cltbld', 14:09:01 INFO - 'MAIL': '/var/mail/cltbld', 14:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:01 INFO - 'MOZ_NO_REMOTE': '1', 14:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:01 INFO - 'NO_EM_RESTART': '1', 14:09:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:01 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:01 INFO - 'SHELL': '/bin/bash', 14:09:01 INFO - 'SHLVL': '1', 14:09:01 INFO - 'TERM': 'linux', 14:09:01 INFO - 'TMOUT': '86400', 14:09:01 INFO - 'USER': 'cltbld', 14:09:01 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:01 INFO - '_': '/tools/buildbot/bin/python'} 14:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:01 INFO - Downloading/unpacking psutil>=0.7.1 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:03 INFO - Installing collected packages: psutil 14:09:03 INFO - Running setup.py install for psutil 14:09:03 INFO - building 'psutil._psutil_linux' extension 14:09:03 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 14:09:03 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 14:09:03 INFO - building 'psutil._psutil_posix' extension 14:09:03 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 14:09:03 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 14:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:03 INFO - Successfully installed psutil 14:09:03 INFO - Cleaning up... 14:09:03 INFO - Return code: 0 14:09:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:09:03 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')]} 14:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:03 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')]} 14:09:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:03 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:03 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 14:09:03 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 14:09:03 INFO - Using env: {'DISPLAY': ':0', 14:09:03 INFO - 'HOME': '/home/cltbld', 14:09:03 INFO - 'LANG': 'en_US.UTF-8', 14:09:03 INFO - 'LANGUAGE': 'en_US:en', 14:09:03 INFO - 'LOGNAME': 'cltbld', 14:09:03 INFO - 'MAIL': '/var/mail/cltbld', 14:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:03 INFO - 'MOZ_NO_REMOTE': '1', 14:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:03 INFO - 'NO_EM_RESTART': '1', 14:09:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:03 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:03 INFO - 'SHELL': '/bin/bash', 14:09:03 INFO - 'SHLVL': '1', 14:09:03 INFO - 'TERM': 'linux', 14:09:03 INFO - 'TMOUT': '86400', 14:09:03 INFO - 'USER': 'cltbld', 14:09:03 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:03 INFO - '_': '/tools/buildbot/bin/python'} 14:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:09:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:09:06 INFO - Installing collected packages: mozsystemmonitor 14:09:06 INFO - Running setup.py install for mozsystemmonitor 14:09:06 INFO - Successfully installed mozsystemmonitor 14:09:06 INFO - Cleaning up... 14:09:06 INFO - Return code: 0 14:09:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:09:06 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')]} 14:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:06 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')]} 14:09:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:06 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:06 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 14:09:06 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 14:09:06 INFO - Using env: {'DISPLAY': ':0', 14:09:06 INFO - 'HOME': '/home/cltbld', 14:09:06 INFO - 'LANG': 'en_US.UTF-8', 14:09:06 INFO - 'LANGUAGE': 'en_US:en', 14:09:06 INFO - 'LOGNAME': 'cltbld', 14:09:06 INFO - 'MAIL': '/var/mail/cltbld', 14:09:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:06 INFO - 'MOZ_NO_REMOTE': '1', 14:09:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:06 INFO - 'NO_EM_RESTART': '1', 14:09:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:06 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:06 INFO - 'SHELL': '/bin/bash', 14:09:06 INFO - 'SHLVL': '1', 14:09:06 INFO - 'TERM': 'linux', 14:09:06 INFO - 'TMOUT': '86400', 14:09:06 INFO - 'USER': 'cltbld', 14:09:06 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:06 INFO - '_': '/tools/buildbot/bin/python'} 14:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:06 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:06 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 14:09:06 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 14:09:06 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 14:09:06 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 14:09:06 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 14:09:06 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 14:09:08 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:09:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 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 14:09:08 INFO - Downloading docopt-0.6.1.tar.gz 14:09:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:08 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:08 INFO - Running setup.py install for blobuploader 14:09:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:08 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:09:09 INFO - Running setup.py install for requests 14:09:09 INFO - Running setup.py install for docopt 14:09:09 INFO - Successfully installed blobuploader requests docopt 14:09:09 INFO - Cleaning up... 14:09:09 INFO - Return code: 0 14:09:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:09:09 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')]} 14:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:09 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')]} 14:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:09 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:09 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 14:09:09 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 14:09:09 INFO - Using env: {'DISPLAY': ':0', 14:09:09 INFO - 'HOME': '/home/cltbld', 14:09:09 INFO - 'LANG': 'en_US.UTF-8', 14:09:09 INFO - 'LANGUAGE': 'en_US:en', 14:09:09 INFO - 'LOGNAME': 'cltbld', 14:09:09 INFO - 'MAIL': '/var/mail/cltbld', 14:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:09 INFO - 'MOZ_NO_REMOTE': '1', 14:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:09 INFO - 'NO_EM_RESTART': '1', 14:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:09 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:09 INFO - 'SHELL': '/bin/bash', 14:09:09 INFO - 'SHLVL': '1', 14:09:09 INFO - 'TERM': 'linux', 14:09:09 INFO - 'TMOUT': '86400', 14:09:09 INFO - 'USER': 'cltbld', 14:09:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:09 INFO - '_': '/tools/buildbot/bin/python'} 14:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:09:09 INFO - Running setup.py (path:/tmp/pip-QMRvlS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:09:09 INFO - Running setup.py (path:/tmp/pip-I0ZVek-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:09:09 INFO - Running setup.py (path:/tmp/pip-CNmvKm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:09:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:09:09 INFO - Running setup.py (path:/tmp/pip-BXtcpm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:09:10 INFO - Running setup.py (path:/tmp/pip-fJsQVM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:09:10 INFO - Running setup.py (path:/tmp/pip-nA_iqg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:09:10 INFO - Running setup.py (path:/tmp/pip-OIRi4r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:09:10 INFO - Running setup.py (path:/tmp/pip-Zcq5br-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:09:10 INFO - Running setup.py (path:/tmp/pip-2vb5Fc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:09:10 INFO - Running setup.py (path:/tmp/pip-Ag2IGP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:09:10 INFO - Running setup.py (path:/tmp/pip-j9pczs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:09:10 INFO - Running setup.py (path:/tmp/pip-ocWpST-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:09:10 INFO - Running setup.py (path:/tmp/pip-kIRrmL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:09:10 INFO - Running setup.py (path:/tmp/pip-DyE7Rs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:09:10 INFO - Running setup.py (path:/tmp/pip-IhBXle-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:09:10 INFO - Running setup.py (path:/tmp/pip-iLig8y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:09:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:09:10 INFO - Running setup.py (path:/tmp/pip-KmPxfR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:09:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:10 INFO - Running setup.py install for manifestparser 14:09:11 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Running setup.py install for mozcrash 14:09:11 INFO - Running setup.py install for mozdebug 14:09:11 INFO - Running setup.py install for mozdevice 14:09:11 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Running setup.py install for mozfile 14:09:11 INFO - Running setup.py install for mozhttpd 14:09:11 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Running setup.py install for mozinfo 14:09:11 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Running setup.py install for mozInstall 14:09:11 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Running setup.py install for mozleak 14:09:11 INFO - Running setup.py install for mozlog 14:09:11 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:09:11 INFO - Running setup.py install for moznetwork 14:09:12 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:09:12 INFO - Running setup.py install for mozprocess 14:09:12 INFO - Running setup.py install for mozprofile 14:09:12 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:09:12 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:09:12 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:09:12 INFO - Running setup.py install for mozrunner 14:09:12 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:09:12 INFO - Running setup.py install for mozscreenshot 14:09:12 INFO - Running setup.py install for moztest 14:09:12 INFO - Running setup.py install for mozversion 14:09:12 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:09:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:12 INFO - Cleaning up... 14:09:12 INFO - Return code: 0 14:09:12 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:12 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:12 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 14:09:12 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 14:09:12 INFO - Using env: {'DISPLAY': ':0', 14:09:12 INFO - 'HOME': '/home/cltbld', 14:09:12 INFO - 'LANG': 'en_US.UTF-8', 14:09:12 INFO - 'LANGUAGE': 'en_US:en', 14:09:12 INFO - 'LOGNAME': 'cltbld', 14:09:12 INFO - 'MAIL': '/var/mail/cltbld', 14:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:12 INFO - 'MOZ_NO_REMOTE': '1', 14:09:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:12 INFO - 'NO_EM_RESTART': '1', 14:09:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:12 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:12 INFO - 'SHELL': '/bin/bash', 14:09:12 INFO - 'SHLVL': '1', 14:09:12 INFO - 'TERM': 'linux', 14:09:12 INFO - 'TMOUT': '86400', 14:09:12 INFO - 'USER': 'cltbld', 14:09:12 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:12 INFO - '_': '/tools/buildbot/bin/python'} 14:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:09:12 INFO - Running setup.py (path:/tmp/pip-7PUMYM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:09:13 INFO - Running setup.py (path:/tmp/pip-YLUvw8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:09:13 INFO - Running setup.py (path:/tmp/pip-l2jLJ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:09:13 INFO - Running setup.py (path:/tmp/pip-ekCJmb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:09:13 INFO - Running setup.py (path:/tmp/pip-Gxy_4d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:09:13 INFO - Running setup.py (path:/tmp/pip-AQ2M3V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:09:13 INFO - Running setup.py (path:/tmp/pip-HRlFS1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:09:13 INFO - Running setup.py (path:/tmp/pip-UNH7NY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:09:13 INFO - Running setup.py (path:/tmp/pip-rvCyUC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:09:13 INFO - Running setup.py (path:/tmp/pip-HKDTy5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:09:13 INFO - Running setup.py (path:/tmp/pip-Q5qjFM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:09:13 INFO - Running setup.py (path:/tmp/pip-O2DjoJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:09:13 INFO - Running setup.py (path:/tmp/pip-n3wPwH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:09:13 INFO - Running setup.py (path:/tmp/pip-zZ2K6y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:09:13 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)) 14:09:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:09:13 INFO - Running setup.py (path:/tmp/pip-vKfb5x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:09:14 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)) 14:09:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:09:14 INFO - Running setup.py (path:/tmp/pip-94hikc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:09:14 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)) 14:09:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:09:14 INFO - Running setup.py (path:/tmp/pip-Rg2YN6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:09:14 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)) 14:09:14 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)) 14:09:14 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)) 14:09:14 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)) 14:09:14 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)) 14:09:14 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)) 14:09:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:15 INFO - Downloading blessings-1.6.tar.gz 14:09:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:09:16 INFO - Installing collected packages: blessings 14:09:16 INFO - Running setup.py install for blessings 14:09:16 INFO - Successfully installed blessings 14:09:16 INFO - Cleaning up... 14:09:16 INFO - Return code: 0 14:09:16 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:09:16 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:09:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:09:16 INFO - Reading from file tmpfile_stdout 14:09:16 INFO - Current package versions: 14:09:16 INFO - argparse == 1.2.1 14:09:16 INFO - blessings == 1.6 14:09:16 INFO - blobuploader == 1.2.4 14:09:16 INFO - docopt == 0.6.1 14:09:16 INFO - manifestparser == 1.1 14:09:16 INFO - mozInstall == 1.12 14:09:16 INFO - mozcrash == 0.16 14:09:16 INFO - mozdebug == 0.1 14:09:16 INFO - mozdevice == 0.47 14:09:16 INFO - mozfile == 1.2 14:09:16 INFO - mozhttpd == 0.7 14:09:16 INFO - mozinfo == 0.9 14:09:16 INFO - mozleak == 0.1 14:09:16 INFO - mozlog == 3.1 14:09:16 INFO - moznetwork == 0.27 14:09:16 INFO - mozprocess == 0.22 14:09:16 INFO - mozprofile == 0.27 14:09:16 INFO - mozrunner == 6.11 14:09:16 INFO - mozscreenshot == 0.1 14:09:16 INFO - mozsystemmonitor == 0.0 14:09:16 INFO - moztest == 0.7 14:09:16 INFO - mozversion == 1.4 14:09:16 INFO - psutil == 3.1.1 14:09:16 INFO - requests == 1.2.3 14:09:16 INFO - wsgiref == 0.1.2 14:09:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 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 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:16 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 14:09:16 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 14:09:16 INFO - Using env: {'DISPLAY': ':0', 14:09:16 INFO - 'HOME': '/home/cltbld', 14:09:16 INFO - 'LANG': 'en_US.UTF-8', 14:09:16 INFO - 'LANGUAGE': 'en_US:en', 14:09:16 INFO - 'LOGNAME': 'cltbld', 14:09:16 INFO - 'MAIL': '/var/mail/cltbld', 14:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:16 INFO - 'MOZ_NO_REMOTE': '1', 14:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:16 INFO - 'NO_EM_RESTART': '1', 14:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:16 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:16 INFO - 'SHELL': '/bin/bash', 14:09:16 INFO - 'SHLVL': '1', 14:09:16 INFO - 'TERM': 'linux', 14:09:16 INFO - 'TMOUT': '86400', 14:09:16 INFO - 'USER': 'cltbld', 14:09:16 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:16 INFO - '_': '/tools/buildbot/bin/python'} 14:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 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)) 14:09:16 INFO - Cleaning up... 14:09:16 INFO - Return code: 0 14:09:16 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 14:09:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:09:16 INFO - Using env: {'DISPLAY': ':0', 14:09:16 INFO - 'HOME': '/home/cltbld', 14:09:16 INFO - 'LANG': 'en_US.UTF-8', 14:09:16 INFO - 'LANGUAGE': 'en_US:en', 14:09:16 INFO - 'LOGNAME': 'cltbld', 14:09:16 INFO - 'MAIL': '/var/mail/cltbld', 14:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:16 INFO - 'MOZ_NO_REMOTE': '1', 14:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:16 INFO - 'NO_EM_RESTART': '1', 14:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:16 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:16 INFO - 'SHELL': '/bin/bash', 14:09:16 INFO - 'SHLVL': '1', 14:09:16 INFO - 'TERM': 'linux', 14:09:16 INFO - 'TMOUT': '86400', 14:09:16 INFO - 'USER': 'cltbld', 14:09:16 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:16 INFO - '_': '/tools/buildbot/bin/python'} 14:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:16 INFO - Downloading/unpacking jsonschema 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:18 INFO - Downloading/unpacking functools32 (from jsonschema) 14:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:18 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:18 INFO - warning: no files found matching '*.txt' 14:09:18 INFO - no previously-included directories found matching 'build' 14:09:18 INFO - no previously-included directories found matching 'dist' 14:09:18 INFO - no previously-included directories found matching '.git*' 14:09:18 INFO - Installing collected packages: jsonschema, functools32 14:09:18 INFO - Running setup.py install for functools32 14:09:19 INFO - warning: no files found matching '*.txt' 14:09:19 INFO - no previously-included directories found matching 'build' 14:09:19 INFO - no previously-included directories found matching 'dist' 14:09:19 INFO - no previously-included directories found matching '.git*' 14:09:19 INFO - Successfully installed jsonschema functools32 14:09:19 INFO - Cleaning up... 14:09:19 INFO - Return code: 0 14:09:19 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe71b339f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18458a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1912600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, '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-1455746930.940943-1782087825', '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 14:09:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 14:09:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:09:19 INFO - Using env: {'DISPLAY': ':0', 14:09:19 INFO - 'HOME': '/home/cltbld', 14:09:19 INFO - 'LANG': 'en_US.UTF-8', 14:09:19 INFO - 'LANGUAGE': 'en_US:en', 14:09:19 INFO - 'LOGNAME': 'cltbld', 14:09:19 INFO - 'MAIL': '/var/mail/cltbld', 14:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:19 INFO - 'MOZ_NO_REMOTE': '1', 14:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:19 INFO - 'NO_EM_RESTART': '1', 14:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:19 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:19 INFO - 'SHELL': '/bin/bash', 14:09:19 INFO - 'SHLVL': '1', 14:09:19 INFO - 'TERM': 'linux', 14:09:19 INFO - 'TMOUT': '86400', 14:09:19 INFO - 'USER': 'cltbld', 14:09:19 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:19 INFO - '_': '/tools/buildbot/bin/python'} 14:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:19 INFO - Downloading/unpacking flake8 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:09:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:09:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:09:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:21 INFO - no previously-included directories found matching 'docs/_build' 14:09:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:09:21 INFO - Running setup.py install for pep8 14:09:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:22 INFO - no previously-included directories found matching 'docs/_build' 14:09:22 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:09:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:09:22 INFO - Cleaning up... 14:09:22 INFO - Return code: 0 14:09:22 INFO - Running post-action listener: _resource_record_post_action 14:09:22 INFO - Running post-action listener: _start_resource_monitoring 14:09:22 INFO - Starting resource monitoring. 14:09:22 INFO - ##### 14:09:22 INFO - ##### Running install step. 14:09:22 INFO - ##### 14:09:22 INFO - Running pre-action listener: _resource_record_pre_action 14:09:22 INFO - Running main action method: install 14:09:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:09:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:09:22 INFO - Reading from file tmpfile_stdout 14:09:22 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:09:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:09:22 INFO - Reading from file tmpfile_stdout 14:09:22 INFO - Output received: 14:09:22 INFO - Usage: mozinstall [options] installer 14:09:22 INFO - Options: 14:09:22 INFO - -h, --help show this help message and exit 14:09:22 INFO - -d DEST, --destination=DEST 14:09:22 INFO - Directory to install application into. [default: 14:09:22 INFO - "/builds/slave/test-pgo"] 14:09:22 INFO - --app=APP Application being installed. [default: firefox] 14:09:22 INFO - mkdir: /builds/slave/test-pgo/build/application 14:09:22 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'] 14:09:22 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 14:09:37 INFO - Reading from file tmpfile_stdout 14:09:37 INFO - Output received: 14:09:37 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:09:37 INFO - Running post-action listener: _resource_record_post_action 14:09:37 INFO - ##### 14:09:37 INFO - ##### Running run-tests step. 14:09:37 INFO - ##### 14:09:37 INFO - Running pre-action listener: _resource_record_pre_action 14:09:37 INFO - Running main action method: run_tests 14:09:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:09:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:09:37 INFO - Python 2.7.3 14:09:37 INFO - Return code: 0 14:09:37 INFO - grabbing minidump binary from tooltool 14:09: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')]} 14:09:37 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 0x19a1350>, '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 0x19a2250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a26e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:09:37 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 14:09:37 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 14:09:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:09:37 INFO - Return code: 0 14:09:37 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:09:37 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:09:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:09:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:09:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:09:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:09:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:09:38 INFO - Return code: 0 14:09:38 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/1455728934/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:09:38 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/1455728934/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost 14:09:38 INFO - Using env: {'DISPLAY': ':0', 14:09:38 INFO - 'HOME': '/home/cltbld', 14:09:38 INFO - 'LANG': 'en_US.UTF-8', 14:09:38 INFO - 'LANGUAGE': 'en_US:en', 14:09:38 INFO - 'LOGNAME': 'cltbld', 14:09:38 INFO - 'MAIL': '/var/mail/cltbld', 14:09:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:09:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:38 INFO - 'MOZ_NO_REMOTE': '1', 14:09:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:09:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:38 INFO - 'NO_EM_RESTART': '1', 14:09:38 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:09:38 INFO - 'PWD': '/builds/slave/test-pgo', 14:09:38 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:09:38 INFO - 'SHELL': '/bin/bash', 14:09:38 INFO - 'SHLVL': '1', 14:09:38 INFO - 'TERM': 'linux', 14:09:38 INFO - 'TMOUT': '86400', 14:09:38 INFO - 'USER': 'cltbld', 14:09:38 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1455746930.940943-1782087825', 14:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:38 INFO - '_': '/tools/buildbot/bin/python'} 14:09:38 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/1455728934/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost'] with output_timeout 3600 14:09:38 INFO - mozversion INFO | application_buildid: 20160217090854 14:09:38 INFO - mozversion INFO | application_changeset: 6fb8393e40e7732f0af5c194772ca850e94840be 14:09:38 INFO - mozversion INFO | application_display_name: Nightly 14:09:38 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:09:38 INFO - mozversion INFO | application_name: Firefox 14:09:38 INFO - mozversion INFO | application_remotingname: firefox 14:09:38 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:09:38 INFO - mozversion INFO | application_vendor: Mozilla 14:09:38 INFO - mozversion INFO | application_version: 45.0 14:09:38 INFO - mozversion INFO | platform_buildid: 20160217090854 14:09:38 INFO - mozversion INFO | platform_changeset: 6fb8393e40e7732f0af5c194772ca850e94840be 14:09:38 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:09:38 INFO - mozversion INFO | platform_version: 45.0 14:09:38 INFO - 2016-02-17 14:09:38,433 DEBUG : using testdate: 1455746978 14:09:38 INFO - 2016-02-17 14:09:38,433 DEBUG : actual date: 1455746978 14:09:38 INFO - 2016-02-17 14:09:38,442 INFO : starting webserver on 'localhost:45919' 14:09:38 INFO - 2016-02-17 14:09:38,443 INFO : Starting test suite talos-linux64-ix-017 14:09:38 INFO - 2016-02-17 14:09:38,443 INFO : Starting test dromaeo_css 14:09:38 INFO - 2016-02-17 14:09:38,443 DEBUG : operating with platform_type : linux_ 14:09:38 INFO - 2016-02-17 14:09:38,444 INFO : Initialising browser for dromaeo_css test... 14:09:38 INFO - 2016-02-17 14:09:38,458 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjmRPKP/profile http://localhost:45919/getInfo.html 14:09:41 INFO - 2016-02-17 14:09:41,994 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:09:41 INFO - 2016-02-17 14:09:41,994 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:09:41 INFO - 2016-02-17 14:09:41,994 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:09:41 INFO - 2016-02-17 14:09:41,994 DEBUG : BROWSER_OUTPUT: __metrics 14:09:42 INFO - 2016-02-17 14:09:42,111 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] 14:09:42 INFO - 2016-02-17 14:09:42,817 INFO : Browser initialized. 14:09:42 INFO - 2016-02-17 14:09:42,818 INFO : Running cycle 1/1 for dromaeo_css test... 14:09:42 INFO - 2016-02-17 14:09:42,818 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjmRPKP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:09:54 INFO - 2016-02-17 14:09:54,456 DEBUG : BROWSER_OUTPUT: RSS: Main: 133365760 14:09:54 INFO - 2016-02-17 14:09:54,457 DEBUG : BROWSER_OUTPUT: 14:10:45 INFO - 2016-02-17 14:10:45,254 DEBUG : BROWSER_OUTPUT: 1455747045250 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] 14:12:53 INFO - 2016-02-17 14:12:53,304 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45919/tests/dromaeo/cssquery-ext.html) 14:12:54 INFO - 2016-02-17 14:12:54,497 DEBUG : BROWSER_OUTPUT: RSS: Main: 254738432 14:12:54 INFO - 2016-02-17 14:12:54,498 DEBUG : BROWSER_OUTPUT: 14:15:56 INFO - 2016-02-17 14:15:56,676 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/dromaeo/cssquery-ext.html (next: http://localhost:45919/tests/dromaeo/cssquery-jquery.html) 14:15:57 INFO - 2016-02-17 14:15:57,238 DEBUG : BROWSER_OUTPUT: RSS: Main: 214913024 14:15:57 INFO - 2016-02-17 14:15:57,238 DEBUG : BROWSER_OUTPUT: 14:18:58 INFO - 2016-02-17 14:18:58,899 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45919/tests/dromaeo/cssquery-mootools.html) 14:18:59 INFO - 2016-02-17 14:18:59,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 219779072 14:18:59 INFO - 2016-02-17 14:18:59,461 DEBUG : BROWSER_OUTPUT: 14:22:01 INFO - 2016-02-17 14:22:01,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45919/tests/dromaeo/cssquery-prototype.html) 14:22:01 INFO - 2016-02-17 14:22:01,738 DEBUG : BROWSER_OUTPUT: RSS: Main: 202108928 14:22:01 INFO - 2016-02-17 14:22:01,738 DEBUG : BROWSER_OUTPUT: 14:25:04 INFO - 2016-02-17 14:25:04,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45919/tests/dromaeo/cssquery-yui.html) 14:25:04 INFO - 2016-02-17 14:25:04,702 DEBUG : BROWSER_OUTPUT: RSS: Main: 199770112 14:25:04 INFO - 2016-02-17 14:25:04,702 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,717 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/dromaeo/cssquery-yui.html (next: http://localhost:45919/tests/dromaeo/cssquery-dojo.html) 14:28:06 INFO - 2016-02-17 14:28:06,968 DEBUG : BROWSER_OUTPUT: RSS: Main: 243200000 14:28:06 INFO - 2016-02-17 14:28:06,968 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,969 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:28:06 INFO - 2016-02-17 14:28:06,969 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:28:06 INFO - 2016-02-17 14:28:06,969 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:28:06 INFO - 2016-02-17 14:28:06,969 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:28:06 INFO - 2016-02-17 14:28:06,971 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3256.088295277322;3343.314620502797;3368.6294507604175;3368.6799754023373;3402.7958322500644;5485.122380419134;5548.389677135512;5550.944490555094;5551.417101204371;5578.9163162552495;5518.834434966948;5571.860703482415;5585.609007369486;5590.496855283024;5591.2731344925205;4986.950130498703;5048.091343558167;5061.645684802065;5146.871328216787;5150.27896094547;5025.0954828130925;5086.262491938666;5086.6947983120945;5089.491050894918;5090.516400941923;4996.675216110959;5103.362079740033;5143.074246635604;5165.81919632813;5177.016366890298;5070.72111033893;5148.253503242027;5254.894902101936;5255.710935898524;5262.210668399733;5128.461511541309;5178.40448348441;5208.583313334924;5214.765335559909;5243.4232234454175;5246.475352464723;5318.494742999409;5353.518183363477;5447.937652157831;5451;5964.284285885678;5966.67183304918;5987.191729116601;5996.310424301218;6009.609375390585;5996.220491336098;5998.4601385875485;5998.550108741862;6003.399660034005;6006.96996515019;5414.5668346532575;5479.835604931858;5528.668279903219;5600.551955843523;5601.467860553241;6088.878222435526;6144.41628045335;6206.534509911775;6207.068939658997;6213.471854892385;5908.763649453974;5913.172155898178;5919.644821310735;5926.081457374114;5945.3757355477055;5787.102999035156;5899.174115623816;5998.310194327708;6002.579819412686;6019.428154325332;5869.677167755729;5960.731767070405;5969.582129250997;5970.34326224115;6010.098485227163;5938.435848594377;5960.76156953717;5970.49250813677;5986.610870293504;5991.340952495222;4486.057927835126;4511;4514.300283456069;4556.521565235669;4576.107659006462;4091.1204091120553;4121.464209652786;4130.793460327031;4133.607307305801;4141.503019637663;5667.036603777367;5711.286089238845;5739.799107031234;5740.454656807597;5744.167095771089;5809.070548712186;5921.230240068763;5954.880902382015;6070.817875463743;6085.847853803691;5426.430224826416;5687.772489100473;5710.885782284414;5716.171155182476;5717.599768016282;5027.773750181177;5052.9494705052475;5054.1407960646775;5054.721990290569;5084.898302033938;6109.11417844419;6124.5100391968535;6214.751409943642;6218.533609979269;6221.191245138103;5988.970055149696;6091.330152681228;6093.104306696539;6096.918227110297;6100.468305653104;6087.66517841523;6287.905681414874;6306.148669929685;6309.621422714652;6313;5831.620944638585;5866.384029676822;5906.734196961061;5910.4089591042275;5957.553183511341;6026.819195424145;6039.094135879477;6060.272767267779;6060.454559089703;6066.545009124245;5664.745086471036;5771.68255745938;5916.792912248051;5918.674472904032;5923.170756094064;5797.565182611237;5872.265966754156;5872.647641141545;5875.148103525051;5899.67551784656;5791.218185544897;5840.474357307863;5841.9707901461925;5848.678322692293;5849.356570777296;5779.739911704068;5784.5083167929515;5859.677717725566;5931.288245410578;5949.524038076857;5600.467955544217;5632.7183640816975;5669.291338582677;5670.971645141748;5699.401562835843 14:28:06 INFO - 2016-02-17 14:28:06,974 DEBUG : BROWSER_OUTPUT: |1;ext.html;544.9782008719651;600.1777564736312;602.5179856115108;619.7366119399254;619.7644894939925;30494.390112197743;39813.60186398135;40463.39304910425;40497.39253911186;40515;45280.547194528124;45849.08301833961;45891.0821783565;45939.77030114853;45975;7950.880736788953;8042.155573664769;8052;9019.052999435064;9184.632614695403;10889;11104.55581776732;11131.888681113207;11138.665840024812;11154.553817847316;10811.135109191286;10949.726256843564;10950.34297942126;10953.780924381508;10963;10696.304740191901;10773.83839242412;10824;10837.403942783143;10844.511996960076;10632.946835265851;10708.87556806532;10722.249442539025;10732.73168170794;10736.570537178504;42729.78635106805;42958.57041429577;43079;43121.35317970233;43162.35256471155;3661.4690869823994;4003.73975691582;4042.272390969624;4299.634531064863;4425.889352766175;32094.839525802454;32440.83779581087;32456.513152302734;32495.51256731151;32788.6721132788;4136.689748268892;4231.576842315774;4290.721103128287;4300.053988122608;4315.136972605492;288212;288487;288893;289577;289598;55189.44810551843;56162;61513.38486615166;74589;76655.23344766481;66424.66787666127;66809.33190668128;66926.66536667285;66950;66953;14967.850321496646;15036.62408439776;15055.096694198382;15126.470573529874;15172.393104275705;12464.688382790322;12565.560205392767;12599.622011339674;12606.369681515887;12629.11596188258;22409.775902241094;22795.88602056979;22831;22903.7709622905;22949.196778112684;7689.76930692069;7779.8833017504785;7922.524648521107;7961.138847658881;8003.439759216915;987.5013118375175;1000.1798525209404;1012.099231683802;1137.2835113878205;1187.7861984842639;29458.263543411587;29938.25154371158;30559.388812224075;30731.38537229243;30971.535426968163;1071.6731396924013;1080.713610893114;1115.9218854680094;1130.9038731707733;1154.2901115813713;13614.795778063337;14011.369488373048;14642.341094650767;16264.268107935171;16628.418005369753;822825;892503;898190;898824;899069.5511746916;62107;62277;62378.37621623726;62395.6880215596;62432;1387.431153227172;1398.0772690024535;1522.5660686704014;1535.8848086393566;1573.5279416175101;31816.069438683946;32191.678083219806;32450.513242301855;32738.83630581927;33091;44975.775121124185;45009.32486012647;45852.31221531613;46112;46207.30689039734;225807;226862;245730;245963;246468;35003.82498087493;35208.823955880056;35732.4640130393;35752.46371304485;36038.27923441569;45454.318185226584;46000.079998399175;46218.76890615525;46473.302900457194;46499;38431.03922401962;38752.6124738749;38789.22421551609;44521.55478445174;45134.54865451303;39648.40527392149;40572.1885562293;45562;45886;47676.284855727885;38431;39253.60746392499;39699.60300397075;39772.2045559093;39803.60196397999;34508.65491345055;34902.65097348994;35161.824190878884;35329;35342;26576.601350979363;26837.14309803555;26892.865535672197;27186.592201117393;27198.864005679847 14:28:06 INFO - 2016-02-17 14:28:06,974 DEBUG : BROWSER_OUTPUT: |2;jquery.html;366.8495916674164;367.18301778542747;370.5812431951895;372.1923426165221;372.7632953074798;14596;15263.542093737206;17153.742693859604;17528.474145775614;18228.63542729146;16505.587360316;16548.338066477358;16749.246283917222;17514.912425437855;17530.47408577745;5011.724355160474;5640.266765320503;5642;5643.68959707216;5643.8024669136585;4305.461817272841;4421.712588681741;5653.886922261552;6097.69511524422;6248.343923887995;6021.42796434338;6134.263888333407;6156.292026416974;6165;6183.81448556544;6224.9417599008275;6247.344028876948;6249.750009999594;6252.687365631723;6259.061140828867;5142.434332223453;5465.836024919259;5466.043442397565;5466.070767969455;5487.725613719298;13595.116317439335;13728.588142355646;13814.516491922835;13853.930730346385;13924.30378481072;2121.3741946125774;2198.65920782279;2215.4461384653837;2252.1104163855184;2302.251768175333;10211.336263142946;10460.058594726435;10982.945085274523;11486.253393529394;14562.781558276634;1907.542189874434;1915.6073005034075;1968.1536939116174;1969.8719583227187;1992.2728204205505;63654.36345636577;65180.674096629686;65541.39522326319;66561.66719166422;67892;10864.402457864893;10899.891001090045;10926.125909927187;10927.235093543535;10936.179786516042;14089.436422542984;14216;14226.430942762381;14234.359453824603;14240.287985600678;7415.036045314183;7453.85092298151;7929.246721561442;7938.801529961797;7988;7566.810829729191;7569.318761311509;7576.507527010726;7619.199984001711;7672.693092276358;4462.955370446319;4532.776150439428;4534.659900507475;4627.07458508293;4639.907201856012;1711.3154738104608;1754.8069712331633;1797.6854050541365;1826.4246762269843;1835.3667984545311;933.4399360383716;944.5831253120319;1035.254616675992;1048.7483004078965;1076.9192310576739;14875.33061012257;16248.431304904272;16308.918455407884;17001.48995530136;17182.65634687299;924.505389616552;965.6282331302499;983.1986930651497;1012.6506355307407;1019.6889948565611;9600.423974561549;9950.353227040365;10013.148882345082;10020.849687254697;10106.29255952091;633179;640885;641089;641860;659725;84771;85072;88047;88582;88882.55558722295;1214.8663646998823;1218.9756204875853;1222.8532576090927;1224.5285565057518;1226.625879106863;15949.920250398674;16014.759778603564;16152.273147708147;16183.838161618232;16271.918640406722;37520.62479375171;37933;38092.238155236184;38915.6108438912;39540.2091958165;49696.25455618096;51380.74309628428;51412.88696904164;53526.1971070442;53565.732171338896;9989.950050249992;10099.091081802673;10207.183425325808;10207.59169633236;10209.74475638115;11716.472758726048;18002;18213.271469141615;18239.452816415527;18678.25286988505;14630.707385852436;14845.851541484446;15073.773893391388;15334.233288335761;15469.458568950033;14865.851341486446;15080.773788392964;15103.32035058403;15304.846951530788;15636.843631563539;15449.613759656098;15520.22398880074;15622.609434763766;15625.609359766097;15690.372385104925;9902.059304366074;9917.752056198653;9920.900790992286;9942.751431214278;10095.44475053879;10674.306170098918;13839.584812455643;14981.550553483414;15080.321385537458;15490.148041857807 14:28:06 INFO - 2016-02-17 14:28:06,977 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1756.5169578365949;1807.1870438590395;1840.7146892231701;1846.363004763357;1871.3824437935482;7731;7956.323712484445;7970.681172753083;7972.521648701082;7975.7208497702595;6779.76270830521;6884.414824739891;6890.483213758963;6896.413804826583;7740.961295193516;2230.4646884747667;2249.527599204161;2275.590393729128;2277.8974946127464;2278.1912421090524;2274.9545009099893;2291.175176936302;2314.9421264468438;2316.9420764480856;2318.547883162784;1947.552063025505;1977.4561995451222;2243.1251811793413;2267.7732226777357;2281.3384118605586;2237.2169740590825;2237.8097861681777;2238.149503188786;2242.697235873161;2243.1588154442084;2259.062489067035;2358.1157066100213;2363.2201373546686;2363.8818059096975;2364.9527009459716;7737.30364267213;7797.337226335769;7818.648160832776;7821.374290056782;7823.687052517893;4697.953020469786;4784.808607655689;4788.545088216649;4791.185498465269;4792.952070479286;3050.588170596963;3068.6164229471315;3092.3196896682693;3101.0231776990177;3107.829069401182;1002.9548670309854;1005.5625802775816;1014.279861298479;1033.8862725100307;1057.693268952003;10423.530941107669;10433.113185379176;10433.739156521147;10465.581376744996;10487.423191724452;4313.525512193656;4321.049369138785;4330.935035974463;4363.934540981888;4409.140217657526;9103.590338434786;9114.772130696785;9118.452892826452;9118.954405228063;9123;4825.95174048262;4827.686200396963;4862.732549709729;4864.391951006124;4864.562189402971;4425.933610995838;4428.601425871687;4432.113577284556;4438.223310920641;4438.5561443856;3593.1915319053005;3679.7240206984584;3703.370427027358;3718.0704823794053;3724.348239058154;1177.5054477119631;1177.6584790410857;1179.6343133628602;1185.1763024697752;1193.2065176657425;1029.9433531155707;1075.0109898893038;1097.05653138301;1310.88202061813;1380.6134282400944;7406.740764073232;7464.440166987498;7472.850542989218;7479.700811967569;7481.326680598772;1143.0741099709262;1170.8887655672697;1172.7068232941765;1192.9164958452825;1208.2508844516456;5852.882942341129;5943.58394912352;5945.464908158287;5947.494462970696;5953.79161729346;8780.648774049094;8896.154865287788;9012.8648070279;9143.177114059767;9143.222826059859;9057.682981095762;9162.770930726652;9197.08029197075;9199.218066464426;9209.539523023823;7173.820654483679;7230.638468076575;7317.195108538163;7364.189939106694;7409.777706668809;7508.962455187689;7548.622568871644;7559.5464272143845;7562.130355009244;7565.243475652391;8227.629756660845;8472.322214222724;8532.131941412286;8595.484270943763;8638.870416943626;8597.957010214908;8720.476771393736;8774.517401542977;8775.166359195866;8780.385373023826;6984.3364880336285;7204.819879503054;7207.063081799332;7209.639518023973;7210.351068403869;7624.8475030500185;7680.69277228927;7685.884711729441;7685.923140768745;7686.46194766361;7056.435485161277;7237.963810180915;7238.927610723826;7313.707451701873;7367.189609143097;6306.906948742742;6425.58233714807;6818.897716534157;7091.11361079865;7201.963990180016;7017.228104908558;7100.218975912749;7103.183133939457;7108.040414544177;7108.644567771694;6692.665366731547;6715.899261511179;6722.932770672231;6724;6725.361090696376;6694.430973367123;6722.226943901514;6747;6759.594424334556;6766.5263431559315 14:28:06 INFO - 2016-02-17 14:28:06,978 DEBUG : BROWSER_OUTPUT: |4;prototype.html;329.76421858371265;347.2222222222223;348.4129242226847;351.27111244168356;357.1482015393286;6133.539984501163;6163.137160797492;6186.443220110189;6194.28765691945;6252.655679029008;6109.266887973449;6109.847253818657;6112.266528016633;6113.908291375633;6121.204243448346;4639.072185562884;4777.737224452654;4777.808887644499;4780.354652121954;4780.521947805227;4736.265878788793;4743;4744.07490539345;4755.453122890877;4757.64317676174;4687.250039993602;4699.295105734133;4712.19892618254;4714;4746.335513028179;4540.727556346614;4644.303354496818;4670.9065818683775;4753.691010084333;4763.166445871976;4176.0603864130635;4182.351735481006;4190.769507677077;4195.181939521794;4196.3705444183315;5620.634658747209;5790.536757059425;5830.3586605473365;5831.970840145815;5838.678872662002;5407.783688652449;5409.864753381157;5552.694601796899;5557.5553955683445;5564.415736347666;6493.7727179548965;6514.93485065148;6527.738890444377;6528.477721782246;6529.902051469232;4637.721736695809;4659.347691323217;4659.627229821606;4660.464046634613;4665.766711664403;6567.146270984789;6654.900176497356;6757.864842703168;6774.967509747105;6782.287859774751;4584.243599805995;4602.815887364535;4613.446386433649;4617.607503362185;4618.076384723069;4668.719876807402;4669.743164125938;4671.972166123447;4675;4677.64917631179;5315.202719592092;5413.7834486620095;5416.9729151353995;5427.782888684408;5428.45715428454;6532.836679082966;6539.313372095957;6544.083828264047;6547.574407663486;6551.868962620721;1680.8739344549208;1708.3337498375643;1711.187186086599;1711.2299465240692;1712.5290545100197;1151.804193287143;1153.5501154549731;1154.261272785418;1170.2276497511602;1181.0197536045061;6276.560640755103;6287.654179020198;6295.055741638791;6299.874002519924;6305;6767.323267673193;6767.627780472023;6772;6787.830304242335;6790.456763458911;6216.532343636578;6258.9374106259265;6285.402886725754;6299.937000629935;6308.526860485482;3631.6913062389463;3664.029032306441;3664.13892735207;3666.2850744105176;3667.614900435416;6966.756163534252;6971.8605627887155;6983.89524157138;6987.056747339146;6997.055397521372;6719;6805.3875151236625;6834.965825170942;6883.139607549057;6927.272636373209;7047.471439642048;7060.258672839381;7060.399866011344;7063.399611033017;7066.293370662996;6828.522003459909;6842.486813488908;6846.418054465426;6852.760153394607;6861.55399899005;6538.803835884741;6738.7304507819135;6766.8646627068165;6831.214410342675;6837.042814006142;7079.327463890922;7080;7080.752173673896;7082.964585177041;7089;4650.488446270975;4691.835785747619;4692.577668009895;4697.5302469754115;4706.576408123286;6662.800115996528;6799.762008329685;6803.217629972616;6811.523193376416;6812.318768123148;6627.37039981201;6648.867022659617;6649.60102393858;6649.966750166218;6654.334566543307;6491.902621460777;6610.669466526559;6610.702518386588;6613.735450581923;6624;6505.772297969548;6519.934800651933;6527.15147030883;6527.412532872065;6531.3468653134305;6653.135092437952;6655.567388119756;6656.301088385843;6662.900056499059;6665.300143484864;6384.329645387354;6490.48076153916;6493.545451818327;6508.8047358579315;6511.088447617242 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: |5;yui.html;839.3788596438635;855.6919508976767;933.8085692433052;935.3919952031179;943.400940402844;12660.177088489254;13127.540536081215;13128.01539884509;13133.802992955114;13134.474621015172;12850.48598056079;12906.354682265872;12925.935370323135;12941;12948.352582370891;729.3253740290211;735.1068451831025;874.4490970688473;878.9165029322204;892.3173772064376;885.3846576629245;887.4409122252972;899.0335389456328;899.8515244984602;900.5767289373983;917.5091326140516;919.7332773495679;931.0084759730865;931.8043210925714;931.8276118917988;771.463832636318;772.7913463364888;923.307519360483;923.3213588012806;925.3753716241511;687.0793137196181;687.295522089857;689.2280645676824;690.082190686391;690.5235387582552;12847.29339886306;13140.60578182656;13175.67060823487;13187.142835715647;13187.604371868763;11555.76888462226;12495;12498.06264530164;12533.185342952771;12768.80846787299;10049.899501005042;10436.32163909351;10578.52396642153;10605.628802991936;10658.200634952333;3702.38910579754;3774.7735135891935;3780.489633899416;3784.4701741756176;3785.3943369060817;46074;46173.53826461759;51565.226521602206;52458;52551.21173182367;10019;10073.746605078994;10089.75209662692;10091.899081009096;10178.287519873686;19083.80916190848;19098.618027639648;19134.904325478285;19144.329948452018;19171.80828191728;7659.425543084291;7846.960765196138;8052.718154864551;8130.390220733469;8168.7957801055445;4381.145676593033;4496.775161241925;4524.47968483622;4624.583787459145;4658.720476771405;3180.7614428917923;3198.2164369729567;3199.4720871056015;3204.83975801209;3211.6627754085953;564.5004171308422;598.7126179433897;604.2265899648457;611.9785807496717;620.0265583034683;598.0789584040616;657.9440747536513;666.5733930284622;681.3186813186813;692.9826754331182;8372;9645.08371704687;9753.414795112316;10157.847632285522;10347.689569312932;689.7482418917159;715.2882881532908;729.5622626424104;734.6620554544862;758.317514237191;4084.65280451159;4185.100203456272;4288.313869780883;4289.528151903288;4330.718503297276;63721.044184337276;64047.359526405075;64484.35515644877;64505.67747161234;64601.353986460475;55862.16206756902;57628.135577966365;58693.41306586965;58757.70621146867;58859.41140588625;49647.751761241685;50418.74790626097;50503.24245136326;51140;51421;8779.824403512022;8816.294696424398;8826.470411775314;9003.324750643858;9095.04502027304;37334;37355.6264437363;37854.81072594619;38283.425748613234;38381.80809095937;42527.14945701131;46028.7698561505;47947.28079078747;48126.5187348136;48416.51583484261;12052.698682532657;12312.507499699912;12548;12882.742345153232;12907.354632268536;12599;12687.80968285458;12953.611391658265;13057.477700891857;13194.274314912773;11248.718782030515;11434.199606027498;11479.770404591694;11510.13673974472;11574;11850.170488065753;11870.287782733063;12013.279203247834;12120.818187727367;12150.817737734118;10318.638847640297;10717.571297148026;10754.623588174376;10845.13238940867;10936.289141205796;9839.409635421898;9843.950780246052;10301.433421161908;10329.328593641389;10504.684859454228;9532.714018579454;10018.549165287724;10050.69847904564;10093.848592270975;10141.898581014095 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,954 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: __startTimestamp1455748086968__endTimestamp 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:28:06 INFO - 2016-02-17 14:28:06,979 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5561.24 Median:5779.74 stddev:623.01 (10.8%) stddev-sans-first:598.10 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: Values: 3256.1 3343.3 3368.6 3368.7 3402.8 5485.1 5548.4 5550.9 5551.4 5578.9 5518.8 5571.9 5585.6 5590.5 5591.3 4987.0 5048.1 5061.6 5146.9 5150.3 5025.1 5086.3 5086.7 5089.5 5090.5 4996.7 5103.4 5143.1 5165.8 5177.0 5070.7 5148.3 5254.9 5255.7 5262.2 5128.5 5178.4 5208.6 5214.8 5243.4 5246.5 5318.5 5353.5 5447.9 5451.0 5964.3 5966.7 5987.2 5996.3 6009.6 5996.2 5998.5 5998.6 6003.4 6007.0 5414.6 5479.8 5528.7 5600.6 5601.5 6088.9 6144.4 6206.5 6207.1 6213.5 5908.8 5913.2 5919.6 5926.1 5945.4 5787.1 5899.2 5998.3 6002.6 6019.4 5869.7 5960.7 5969.6 5970.3 6010.1 5938.4 5960.8 5970.5 5986.6 5991.3 4486.1 4511.0 4514.3 4556.5 4576.1 4091.1 4121.5 4130.8 4133.6 4141.5 5667.0 5711.3 5739.8 5740.5 5744.2 5809.1 5921.2 5954.9 6070.8 6085.8 5426.4 5687.8 5710.9 5716.2 5717.6 5027.8 5052.9 5054.1 5054.7 5084.9 6109.1 6124.5 6214.8 6218.5 6221.2 5989.0 6091.3 6093.1 6096.9 6100.5 6087.7 6287.9 6306.1 6309.6 6313.0 5831.6 5866.4 5906.7 5910.4 5957.6 6026.8 6039.1 6060.3 6060.5 6066.5 5664.7 5771.7 5916.8 5918.7 5923.2 5797.6 5872.3 5872.6 5875.1 5899.7 5791.2 5840.5 5842.0 5848.7 5849.4 5779.7 5784.5 5859.7 5931.3 5949.5 5600.5 5632.7 5669.3 5671.0 5699.4 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:63258.89 Median:35335.50 stddev:150515.75 (426.0%) stddev-sans-first:150864.34 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: Values: 545.0 600.2 602.5 619.7 619.8 30494.4 39813.6 40463.4 40497.4 40515.0 45280.5 45849.1 45891.1 45939.8 45975.0 7950.9 8042.2 8052.0 9019.1 9184.6 10889.0 11104.6 11131.9 11138.7 11154.6 10811.1 10949.7 10950.3 10953.8 10963.0 10696.3 10773.8 10824.0 10837.4 10844.5 10632.9 10708.9 10722.2 10732.7 10736.6 42729.8 42958.6 43079.0 43121.4 43162.4 3661.5 4003.7 4042.3 4299.6 4425.9 32094.8 32440.8 32456.5 32495.5 32788.7 4136.7 4231.6 4290.7 4300.1 4315.1 288212.0 288487.0 288893.0 289577.0 289598.0 55189.4 56162.0 61513.4 74589.0 76655.2 66424.7 66809.3 66926.7 66950.0 66953.0 14967.9 15036.6 15055.1 15126.5 15172.4 12464.7 12565.6 12599.6 12606.4 12629.1 22409.8 22795.9 22831.0 22903.8 22949.2 7689.8 7779.9 7922.5 7961.1 8003.4 987.5 1000.2 1012.1 1137.3 1187.8 29458.3 29938.3 30559.4 30731.4 30971.5 1071.7 1080.7 1115.9 1130.9 1154.3 13614.8 14011.4 14642.3 16264.3 16628.4 822825.0 892503.0 898190.0 898824.0 899069.6 62107.0 62277.0 62378.4 62395.7 62432.0 1387.4 1398.1 1522.6 1535.9 1573.5 31816.1 32191.7 32450.5 32738.8 33091.0 44975.8 45009.3 45852.3 46112.0 46207.3 225807.0 226862.0 245730.0 245963.0 246468.0 35003.8 35208.8 35732.5 35752.5 36038.3 45454.3 46000.1 46218.8 46473.3 46499.0 38431.0 38752.6 38789.2 44521.6 45134.5 39648.4 40572.2 45562.0 45886.0 47676.3 38431.0 39253.6 39699.6 39772.2 39803.6 34508.7 34902.7 35161.8 35329.0 35342.0 26576.6 26837.1 26892.9 27186.6 27198.9 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,980 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32440.43 Median:1969.01 stddev:105136.92 (5339.6%) stddev-sans-first:105404.27 14:28:06 INFO - 2016-02-17 14:28:06,981 DEBUG : BROWSER_OUTPUT: Values: 366.8 367.2 370.6 372.2 372.8 14596.0 15263.5 17153.7 17528.5 18228.6 16505.6 16548.3 16749.2 17514.9 17530.5 5011.7 5640.3 5642.0 5643.7 5643.8 4305.5 4421.7 5653.9 6097.7 6248.3 6021.4 6134.3 6156.3 6165.0 6183.8 6224.9 6247.3 6249.8 6252.7 6259.1 5142.4 5465.8 5466.0 5466.1 5487.7 13595.1 13728.6 13814.5 13853.9 13924.3 2121.4 2198.7 2215.4 2252.1 2302.3 10211.3 10460.1 10982.9 11486.3 14562.8 1907.5 1915.6 1968.2 1969.9 1992.3 63654.4 65180.7 65541.4 66561.7 67892.0 10864.4 10899.9 10926.1 10927.2 10936.2 14089.4 14216.0 14226.4 14234.4 14240.3 7415.0 7453.9 7929.2 7938.8 7988.0 7566.8 7569.3 7576.5 7619.2 7672.7 4463.0 4532.8 4534.7 4627.1 4639.9 1711.3 1754.8 1797.7 1826.4 1835.4 933.4 944.6 1035.3 1048.7 1076.9 14875.3 16248.4 16308.9 17001.5 17182.7 924.5 965.6 983.2 1012.7 1019.7 9600.4 9950.4 10013.1 10020.8 10106.3 633179.0 640885.0 641089.0 641860.0 659725.0 84771.0 85072.0 88047.0 88582.0 88882.6 1214.9 1219.0 1222.9 1224.5 1226.6 15949.9 16014.8 16152.3 16183.8 16271.9 37520.6 37933.0 38092.2 38915.6 39540.2 49696.3 51380.7 51412.9 53526.2 53565.7 9990.0 10099.1 10207.2 10207.6 10209.7 11716.5 18002.0 18213.3 18239.5 18678.3 14630.7 14845.9 15073.8 15334.2 15469.5 14865.9 15080.8 15103.3 15304.8 15636.8 15449.6 15520.2 15622.6 15625.6 15690.4 9902.1 9917.8 9920.9 9942.8 10095.4 10674.3 13839.6 14981.6 15080.3 15490.1 14:28:06 INFO - 2016-02-17 14:28:06,981 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,981 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5504.99 Median:6366.24 stddev:2798.27 (44.0%) stddev-sans-first:2791.94 14:28:06 INFO - 2016-02-17 14:28:06,982 DEBUG : BROWSER_OUTPUT: Values: 1756.5 1807.2 1840.7 1846.4 1871.4 7731.0 7956.3 7970.7 7972.5 7975.7 6779.8 6884.4 6890.5 6896.4 7741.0 2230.5 2249.5 2275.6 2277.9 2278.2 2275.0 2291.2 2314.9 2316.9 2318.5 1947.6 1977.5 2243.1 2267.8 2281.3 2237.2 2237.8 2238.1 2242.7 2243.2 2259.1 2358.1 2363.2 2363.9 2365.0 7737.3 7797.3 7818.6 7821.4 7823.7 4698.0 4784.8 4788.5 4791.2 4793.0 3050.6 3068.6 3092.3 3101.0 3107.8 1003.0 1005.6 1014.3 1033.9 1057.7 10423.5 10433.1 10433.7 10465.6 10487.4 4313.5 4321.0 4330.9 4363.9 4409.1 9103.6 9114.8 9118.5 9119.0 9123.0 4826.0 4827.7 4862.7 4864.4 4864.6 4425.9 4428.6 4432.1 4438.2 4438.6 3593.2 3679.7 3703.4 3718.1 3724.3 1177.5 1177.7 1179.6 1185.2 1193.2 1029.9 1075.0 1097.1 1310.9 1380.6 7406.7 7464.4 7472.9 7479.7 7481.3 1143.1 1170.9 1172.7 1192.9 1208.3 5852.9 5943.6 5945.5 5947.5 5953.8 8780.6 8896.2 9012.9 9143.2 9143.2 9057.7 9162.8 9197.1 9199.2 9209.5 7173.8 7230.6 7317.2 7364.2 7409.8 7509.0 7548.6 7559.5 7562.1 7565.2 8227.6 8472.3 8532.1 8595.5 8638.9 8598.0 8720.5 8774.5 8775.2 8780.4 6984.3 7204.8 7207.1 7209.6 7210.4 7624.8 7680.7 7685.9 7685.9 7686.5 7056.4 7238.0 7238.9 7313.7 7367.2 6306.9 6425.6 6818.9 7091.1 7202.0 7017.2 7100.2 7103.2 7108.0 7108.6 6692.7 6715.9 6722.9 6724.0 6725.4 6694.4 6722.2 6747.0 6759.6 6766.5 14:28:06 INFO - 2016-02-17 14:28:06,982 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,982 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5496.74 Median:6255.80 stddev:1649.06 (26.4%) stddev-sans-first:1607.43 14:28:06 INFO - 2016-02-17 14:28:06,982 DEBUG : BROWSER_OUTPUT: Values: 329.8 347.2 348.4 351.3 357.1 6133.5 6163.1 6186.4 6194.3 6252.7 6109.3 6109.8 6112.3 6113.9 6121.2 4639.1 4777.7 4777.8 4780.4 4780.5 4736.3 4743.0 4744.1 4755.5 4757.6 4687.3 4699.3 4712.2 4714.0 4746.3 4540.7 4644.3 4670.9 4753.7 4763.2 4176.1 4182.4 4190.8 4195.2 4196.4 5620.6 5790.5 5830.4 5832.0 5838.7 5407.8 5409.9 5552.7 5557.6 5564.4 6493.8 6514.9 6527.7 6528.5 6529.9 4637.7 4659.3 4659.6 4660.5 4665.8 6567.1 6654.9 6757.9 6775.0 6782.3 4584.2 4602.8 4613.4 4617.6 4618.1 4668.7 4669.7 4672.0 4675.0 4677.6 5315.2 5413.8 5417.0 5427.8 5428.5 6532.8 6539.3 6544.1 6547.6 6551.9 1680.9 1708.3 1711.2 1711.2 1712.5 1151.8 1153.6 1154.3 1170.2 1181.0 6276.6 6287.7 6295.1 6299.9 6305.0 6767.3 6767.6 6772.0 6787.8 6790.5 6216.5 6258.9 6285.4 6299.9 6308.5 3631.7 3664.0 3664.1 3666.3 3667.6 6966.8 6971.9 6983.9 6987.1 6997.1 6719.0 6805.4 6835.0 6883.1 6927.3 7047.5 7060.3 7060.4 7063.4 7066.3 6828.5 6842.5 6846.4 6852.8 6861.6 6538.8 6738.7 6766.9 6831.2 6837.0 7079.3 7080.0 7080.8 7083.0 7089.0 4650.5 4691.8 4692.6 4697.5 4706.6 6662.8 6799.8 6803.2 6811.5 6812.3 6627.4 6648.9 6649.6 6650.0 6654.3 6491.9 6610.7 6610.7 6613.7 6624.0 6505.8 6519.9 6527.2 6527.4 6531.3 6653.1 6655.6 6656.3 6662.9 6665.3 6384.3 6490.5 6493.5 6508.8 6511.1 14:28:06 INFO - 2016-02-17 14:28:06,982 DEBUG : BROWSER_OUTPUT: 14:28:06 INFO - 2016-02-17 14:28:06,982 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14646.41 Median:4438.96 stddev:17439.63 (392.9%) stddev-sans-first:17457.73 14:28:06 INFO - 2016-02-17 14:28:06,983 DEBUG : BROWSER_OUTPUT: Values: 839.4 855.7 933.8 935.4 943.4 12660.2 13127.5 13128.0 13133.8 13134.5 12850.5 12906.4 12925.9 12941.0 12948.4 729.3 735.1 874.4 878.9 892.3 885.4 887.4 899.0 899.9 900.6 917.5 919.7 931.0 931.8 931.8 771.5 772.8 923.3 923.3 925.4 687.1 687.3 689.2 690.1 690.5 12847.3 13140.6 13175.7 13187.1 13187.6 11555.8 12495.0 12498.1 12533.2 12768.8 10049.9 10436.3 10578.5 10605.6 10658.2 3702.4 3774.8 3780.5 3784.5 3785.4 46074.0 46173.5 51565.2 52458.0 52551.2 10019.0 10073.7 10089.8 10091.9 10178.3 19083.8 19098.6 19134.9 19144.3 19171.8 7659.4 7847.0 8052.7 8130.4 8168.8 4381.1 4496.8 4524.5 4624.6 4658.7 3180.8 3198.2 3199.5 3204.8 3211.7 564.5 598.7 604.2 612.0 620.0 598.1 657.9 666.6 681.3 693.0 8372.0 9645.1 9753.4 10157.8 10347.7 689.7 715.3 729.6 734.7 758.3 4084.7 4185.1 4288.3 4289.5 4330.7 63721.0 64047.4 64484.4 64505.7 64601.4 55862.2 57628.1 58693.4 58757.7 58859.4 49647.8 50418.7 50503.2 51140.0 51421.0 8779.8 8816.3 8826.5 9003.3 9095.0 37334.0 37355.6 37854.8 38283.4 38381.8 42527.1 46028.8 47947.3 48126.5 48416.5 12052.7 12312.5 12548.0 12882.7 12907.4 12599.0 12687.8 12953.6 13057.5 13194.3 11248.7 11434.2 11479.8 11510.1 11574.0 11850.2 11870.3 12013.3 12120.8 12150.8 10318.6 10717.6 10754.6 10845.1 10936.3 9839.4 9844.0 10301.4 10329.3 10504.7 9532.7 10018.5 10050.7 10093.8 10141.9 14:28:06 INFO - 2016-02-17 14:28:06,983 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:28:06 INFO - 2016-02-17 14:28:06,983 DEBUG : BROWSER_OUTPUT: 14:28:07 INFO - 2016-02-17 14:28:07,521 INFO : Browser exited with error code: 0 14:28:07 INFO - 2016-02-17 14:28:07,537 INFO : Completed test dromaeo_css (00:18:29) 14:28:07 INFO - 2016-02-17 14:28:07,537 INFO : Starting test kraken 14:28:07 INFO - 2016-02-17 14:28:07,537 DEBUG : operating with platform_type : linux_ 14:28:07 INFO - 2016-02-17 14:28:07,537 INFO : Initialising browser for kraken test... 14:28:07 INFO - 2016-02-17 14:28:07,544 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKyEMeR/profile http://localhost:45919/getInfo.html 14:28:11 INFO - 2016-02-17 14:28:11,008 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:28:11 INFO - 2016-02-17 14:28:11,009 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:28:11 INFO - 2016-02-17 14:28:11,009 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:28:11 INFO - 2016-02-17 14:28:11,009 DEBUG : BROWSER_OUTPUT: __metrics 14:28:11 INFO - 2016-02-17 14:28:11,130 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] 14:28:11 INFO - 2016-02-17 14:28:11,933 INFO : Browser initialized. 14:28:11 INFO - 2016-02-17 14:28:11,934 INFO : Running cycle 1/1 for kraken test... 14:28:11 INFO - 2016-02-17 14:28:11,934 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKyEMeR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:28:23 INFO - 2016-02-17 14:28:23,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 132939776 14:28:23 INFO - 2016-02-17 14:28:23,619 DEBUG : BROWSER_OUTPUT: 14:28:26 INFO - 2016-02-17 14:28:26,059 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45919/tests/kraken/driver.html?testName=audio-beat-detection) 14:28:26 INFO - 2016-02-17 14:28:26,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 166948864 14:28:26 INFO - 2016-02-17 14:28:26,641 DEBUG : BROWSER_OUTPUT: 14:28:29 INFO - 2016-02-17 14:28:29,170 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45919/tests/kraken/driver.html?testName=audio-dft) 14:28:29 INFO - 2016-02-17 14:28:29,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 163962880 14:28:29 INFO - 2016-02-17 14:28:29,743 DEBUG : BROWSER_OUTPUT: 14:28:32 INFO - 2016-02-17 14:28:32,550 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45919/tests/kraken/driver.html?testName=audio-fft) 14:28:33 INFO - 2016-02-17 14:28:33,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 160968704 14:28:33 INFO - 2016-02-17 14:28:33,117 DEBUG : BROWSER_OUTPUT: 14:28:35 INFO - 2016-02-17 14:28:35,089 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45919/tests/kraken/driver.html?testName=audio-oscillator) 14:28:35 INFO - 2016-02-17 14:28:35,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 160256000 14:28:35 INFO - 2016-02-17 14:28:35,650 DEBUG : BROWSER_OUTPUT: 14:28:37 INFO - 2016-02-17 14:28:37,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45919/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:28:38 INFO - 2016-02-17 14:28:38,393 DEBUG : BROWSER_OUTPUT: RSS: Main: 160477184 14:28:38 INFO - 2016-02-17 14:28:38,393 DEBUG : BROWSER_OUTPUT: 14:28:41 INFO - 2016-02-17 14:28:41,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45919/tests/kraken/driver.html?testName=imaging-darkroom) 14:28:41 INFO - 2016-02-17 14:28:41,817 DEBUG : BROWSER_OUTPUT: RSS: Main: 160772096 14:28:41 INFO - 2016-02-17 14:28:41,817 DEBUG : BROWSER_OUTPUT: 14:28:45 INFO - 2016-02-17 14:28:45,172 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45919/tests/kraken/driver.html?testName=imaging-desaturate) 14:28:45 INFO - 2016-02-17 14:28:45,748 DEBUG : BROWSER_OUTPUT: RSS: Main: 159756288 14:28:45 INFO - 2016-02-17 14:28:45,748 DEBUG : BROWSER_OUTPUT: 14:28:48 INFO - 2016-02-17 14:28:48,451 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45919/tests/kraken/driver.html?testName=json-parse-financial) 14:28:49 INFO - 2016-02-17 14:28:49,025 DEBUG : BROWSER_OUTPUT: RSS: Main: 160755712 14:28:49 INFO - 2016-02-17 14:28:49,025 DEBUG : BROWSER_OUTPUT: 14:28:50 INFO - 2016-02-17 14:28:50,613 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45919/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:28:51 INFO - 2016-02-17 14:28:51,178 DEBUG : BROWSER_OUTPUT: RSS: Main: 163790848 14:28:51 INFO - 2016-02-17 14:28:51,178 DEBUG : BROWSER_OUTPUT: 14:28:52 INFO - 2016-02-17 14:28:52,936 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:28:53 INFO - 2016-02-17 14:28:53,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 163753984 14:28:53 INFO - 2016-02-17 14:28:53,538 DEBUG : BROWSER_OUTPUT: 14:28:55 INFO - 2016-02-17 14:28:55,514 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:28:56 INFO - 2016-02-17 14:28:56,083 DEBUG : BROWSER_OUTPUT: RSS: Main: 166477824 14:28:56 INFO - 2016-02-17 14:28:56,084 DEBUG : BROWSER_OUTPUT: 14:28:58 INFO - 2016-02-17 14:28:58,417 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:28:58 INFO - 2016-02-17 14:28:58,991 DEBUG : BROWSER_OUTPUT: RSS: Main: 172396544 14:28:58 INFO - 2016-02-17 14:28:58,991 DEBUG : BROWSER_OUTPUT: 14:29:01 INFO - 2016-02-17 14:29:01,472 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:29:02 INFO - 2016-02-17 14:29:02,040 DEBUG : BROWSER_OUTPUT: RSS: Main: 171552768 14:29:02 INFO - 2016-02-17 14:29:02,040 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,676 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45919/tests/kraken/driver.html?testName=ai-astar) 14:29:03 INFO - 2016-02-17 14:29:03,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 230006784 14:29:03 INFO - 2016-02-17 14:29:03,928 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,929 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:29:03 INFO - 2016-02-17 14:29:03,929 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:29:03 INFO - 2016-02-17 14:29:03,929 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:29:03 INFO - 2016-02-17 14:29:03,931 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:29:03 INFO - 2016-02-17 14:29:03,931 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;108;104;109;109;109;100;110;108;108 14:29:03 INFO - 2016-02-17 14:29:03,931 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;120;154;163;122;146;152;122;164;121 14:29:03 INFO - 2016-02-17 14:29:03,932 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;159;161;162;159;159;162;162;160 14:29:03 INFO - 2016-02-17 14:29:03,932 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;71;145;71;72;73;72;161;72 14:29:03 INFO - 2016-02-17 14:29:03,932 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;94;139;93;93;94;136;93;93;143 14:29:03 INFO - 2016-02-17 14:29:03,932 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;109;109;109;109;109;109;110;109 14:29:03 INFO - 2016-02-17 14:29:03,932 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;146;147;146;147;145;146;145;145 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;95;95;95;95;95;96;95 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;56;58;58;56;57;55;55;53 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;66;64;65;66;65;65;65;65 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;75;76;77;77;77;78;78;79;86;78 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;119;111;110;108;117;114;122;112;121;112 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;139;140;140;140;141;138;143;138;139 14:29:03 INFO - 2016-02-17 14:29:03,933 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;63;66;63;65;62;63;62;66;64 14:29:03 INFO - 2016-02-17 14:29:03,934 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:29:03 INFO - 2016-02-17 14:29:03,934 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:29:03 INFO - 2016-02-17 14:29:03,934 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,907 14:29:03 INFO - 2016-02-17 14:29:03,934 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:29:03 INFO - 2016-02-17 14:29:03,934 DEBUG : BROWSER_OUTPUT: __startTimestamp1455748143929__endTimestamp 14:29:03 INFO - 2016-02-17 14:29:03,934 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,936 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:29:03 INFO - 2016-02-17 14:29:03,936 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:29:03 INFO - 2016-02-17 14:29:03,936 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,936 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.40 Median:109.00 stddev:3.06 (2.8%) stddev-sans-first:3.19 14:29:03 INFO - 2016-02-17 14:29:03,938 DEBUG : BROWSER_OUTPUT: Values: 109.0 108.0 104.0 109.0 109.0 109.0 100.0 110.0 108.0 108.0 14:29:03 INFO - 2016-02-17 14:29:03,938 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,938 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.80 Median:149.00 stddev:17.95 (12.0%) stddev-sans-first:19.00 14:29:03 INFO - 2016-02-17 14:29:03,938 DEBUG : BROWSER_OUTPUT: Values: 144.0 120.0 154.0 163.0 122.0 146.0 152.0 122.0 164.0 121.0 14:29:03 INFO - 2016-02-17 14:29:03,939 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,939 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.50 stddev:1.34 (0.8%) stddev-sans-first:1.33 14:29:03 INFO - 2016-02-17 14:29:03,939 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 159.0 161.0 162.0 159.0 159.0 162.0 162.0 160.0 14:29:03 INFO - 2016-02-17 14:29:03,939 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,940 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.70 Median:71.50 stddev:34.63 (48.4%) stddev-sans-first:36.14 14:29:03 INFO - 2016-02-17 14:29:03,940 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 71.0 145.0 71.0 72.0 73.0 72.0 161.0 72.0 14:29:03 INFO - 2016-02-17 14:29:03,940 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,940 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.00 Median:93.00 stddev:22.38 (24.1%) stddev-sans-first:23.07 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 139.0 93.0 93.0 94.0 136.0 93.0 93.0 143.0 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 146.0 147.0 146.0 147.0 145.0 146.0 145.0 145.0 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,941 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.52 (2.7%) stddev-sans-first:1.58 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 56.0 58.0 58.0 56.0 57.0 55.0 55.0 53.0 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 66.0 64.0 65.0 66.0 65.0 65.0 65.0 65.0 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,942 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:78.00 stddev:3.00 (3.8%) stddev-sans-first:2.96 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: Values: 75.0 76.0 77.0 77.0 77.0 78.0 78.0 79.0 86.0 78.0 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.60 Median:115.50 stddev:4.86 (4.2%) stddev-sans-first:4.88 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: Values: 119.0 111.0 110.0 108.0 117.0 114.0 122.0 112.0 121.0 112.0 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.70 Median:140.00 stddev:1.49 (1.1%) stddev-sans-first:1.56 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: Values: 139.0 139.0 140.0 140.0 140.0 141.0 138.0 143.0 138.0 139.0 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.99 (3.1%) stddev-sans-first:1.56 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: Values: 68.0 63.0 66.0 63.0 65.0 62.0 63.0 62.0 66.0 64.0 14:29:03 INFO - 2016-02-17 14:29:03,943 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:29:03 INFO - 2016-02-17 14:29:03,944 DEBUG : BROWSER_OUTPUT: 14:29:04 INFO - 2016-02-17 14:29:04,553 INFO : Browser exited with error code: 0 14:29:04 INFO - 2016-02-17 14:29:04,604 INFO : Completed test kraken (00:00:57) 14:29:04 INFO - 2016-02-17 14:29:04,604 INFO : Starting test v8_7 14:29:04 INFO - 2016-02-17 14:29:04,605 DEBUG : operating with platform_type : linux_ 14:29:04 INFO - 2016-02-17 14:29:04,605 INFO : Initialising browser for v8_7 test... 14:29:04 INFO - 2016-02-17 14:29:04,611 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdt1gXH/profile http://localhost:45919/getInfo.html 14:29:07 INFO - 2016-02-17 14:29:07,962 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:29:07 INFO - 2016-02-17 14:29:07,962 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:29:07 INFO - 2016-02-17 14:29:07,963 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:29:07 INFO - 2016-02-17 14:29:07,963 DEBUG : BROWSER_OUTPUT: __metrics 14:29:08 INFO - 2016-02-17 14:29:08,079 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] 14:29:08 INFO - 2016-02-17 14:29:08,833 INFO : Browser initialized. 14:29:08 INFO - 2016-02-17 14:29:08,834 INFO : Running cycle 1/1 for v8_7 test... 14:29:08 INFO - 2016-02-17 14:29:08,834 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdt1gXH/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:29:20 INFO - 2016-02-17 14:29:20,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 132689920 14:29:20 INFO - 2016-02-17 14:29:20,541 DEBUG : BROWSER_OUTPUT: 14:29:41 INFO - 2016-02-17 14:29:41,914 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45919/tests/v8_7/run.html (next: http://localhost:45919/tests/v8_7/run.html) 14:29:42 INFO - 2016-02-17 14:29:42,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 422424576 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: |0;Richards;167.25204883759827 14:29:42 INFO - 2016-02-17 14:29:42,165 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.23057973394646 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.2415136338342 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |4;RayTrace;981.3542688910696 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |5;Earley;547.3453749315818 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |6;Boyer;10572.916666666666 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |7;RegExp;24309.52380952381 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |8;Splay;526.0389268805892 14:29:42 INFO - 2016-02-17 14:29:42,166 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: __startTimestamp1455748182161__endTimestamp 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,167 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.25 Median:167.25 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: Values: 167.3 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.23 Median:164.23 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: Values: 164.2 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.24 Median:278.24 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: Values: 278.2 14:29:42 INFO - 2016-02-17 14:29:42,168 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: Values: 5191.7 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:981.35 Median:981.35 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: Values: 981.4 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,169 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:547.35 Median:547.35 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: Values: 547.3 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10572.92 Median:10572.92 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: Values: 10572.9 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24309.52 Median:24309.52 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: Values: 24309.5 14:29:42 INFO - 2016-02-17 14:29:42,170 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:526.04 Median:526.04 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: Values: 526.0 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: Values: 4822.1 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:29:42 INFO - 2016-02-17 14:29:42,171 DEBUG : BROWSER_OUTPUT: 14:29:42 INFO - 2016-02-17 14:29:42,873 INFO : Browser exited with error code: 0 14:29:42 INFO - 2016-02-17 14:29:42,887 INFO : Completed test v8_7 (00:00:38) 14:29:43 INFO - 2016-02-17 14:29:43,181 INFO : Completed test suite (00:20:04) 14:29:43 INFO - 2016-02-17 14:29:43,181 DEBUG : Working with test: dromaeo_css 14:29:43 INFO - 2016-02-17 14:29:43,181 DEBUG : Generating results file: dromaeo_css 14:29:43 INFO - 2016-02-17 14:29:43,183 DEBUG : Working with test: kraken 14:29:43 INFO - 2016-02-17 14:29:43,183 DEBUG : Generating results file: kraken 14:29:43 INFO - 2016-02-17 14:29:43,183 INFO : javascript benchmark 14:29:43 INFO - 2016-02-17 14:29:43,183 DEBUG : Working with test: v8_7 14:29:43 INFO - 2016-02-17 14:29:43,183 DEBUG : Generating results file: v8_7 14:29:43 INFO - 2016-02-17 14:29:43,184 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:29:43 INFO - 2016-02-17 14:29:43,242 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 14:29:43 INFO - 2016-02-17 14:29:43,243 DEBUG : process_Request line: dromaeo_css 7354.23 graph.html#tests=[[72,53,35]] 14:29:43 INFO - 2016-02-17 14:29:43,243 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:29:43 INFO - 2016-02-17 14:29:43,272 DEBUG : process_Request line: kraken 1471.60 graph.html#tests=[[232,53,35]] 14:29:43 INFO - 2016-02-17 14:29:43,272 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:29:43 INFO - 2016-02-17 14:29:43,316 DEBUG : process_Request line: v8_7 23179.92 graph.html#tests=[[230,53,35]] 14:29:43 INFO - 2016-02-17 14:29:43,317 INFO : javascript benchmark 14:29:43 INFO - 2016-02-17 14:29:43,321 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3256.088295277322, 3343.314620502797, 3368.6294507604175, 3368.6799754023373, 3402.7958322500644, 5485.122380419134, 5548.389677135512, 5550.944490555094, 5551.417101204371, 5578.9163162552495, 5518.834434966948, 5571.860703482415, 5585.609007369486, 5590.496855283024, 5591.2731344925205, 4986.950130498703, 5048.091343558167, 5061.645684802065, 5146.871328216787, 5150.27896094547, 5025.0954828130925, 5086.262491938666, 5086.6947983120945, 5089.491050894918, 5090.516400941923, 4996.675216110959, 5103.362079740033, 5143.074246635604, 5165.81919632813, 5177.016366890298, 5070.72111033893, 5148.253503242027, 5254.894902101936, 5255.710935898524, 5262.210668399733, 5128.461511541309, 5178.40448348441, 5208.583313334924, 5214.765335559909, 5243.4232234454175, 5246.475352464723, 5318.494742999409, 5353.518183363477, 5447.937652157831, 5451.0, 5964.284285885678, 5966.67183304918, 5987.191729116601, 5996.310424301218, 6009.609375390585, 5996.220491336098, 5998.4601385875485, 5998.550108741862, 6003.399660034005, 6006.96996515019, 5414.5668346532575, 5479.835604931858, 5528.668279903219, 5600.551955843523, 5601.467860553241, 6088.878222435526, 6144.41628045335, 6206.534509911775, 6207.068939658997, 6213.471854892385, 5908.763649453974, 5913.172155898178, 5919.644821310735, 5926.081457374114, 5945.3757355477055, 5787.102999035156, 5899.174115623816, 5998.310194327708, 6002.579819412686, 6019.428154325332, 5869.677167755729, 5960.731767070405, 5969.582129250997, 5970.34326224115, 6010.098485227163, 5938.435848594377, 5960.76156953717, 5970.49250813677, 5986.610870293504, 5991.340952495222, 4486.057927835126, 4511.0, 4514.300283456069, 4556.521565235669, 4576.107659006462, 4091.1204091120553, 4121.464209652786, 4130.793460327031, 4133.607307305801, 4141.503019637663, 5667.036603777367, 5711.286089238845, 5739.799107031234, 5740.454656807597, 5744.167095771089, 5809.070548712186, 5921.230240068763, 5954.880902382015, 6070.817875463743, 6085.847853803691, 5426.430224826416, 5687.772489100473, 5710.885782284414, 5716.171155182476, 5717.599768016282, 5027.773750181177, 5052.9494705052475, 5054.1407960646775, 5054.721990290569, 5084.898302033938, 6109.11417844419, 6124.5100391968535, 6214.751409943642, 6218.533609979269, 6221.191245138103, 5988.970055149696, 6091.330152681228, 6093.104306696539, 6096.918227110297, 6100.468305653104, 6087.66517841523, 6287.905681414874, 6306.148669929685, 6309.621422714652, 6313.0, 5831.620944638585, 5866.384029676822, 5906.734196961061, 5910.4089591042275, 5957.553183511341, 6026.819195424145, 6039.094135879477, 6060.272767267779, 6060.454559089703, 6066.545009124245, 5664.745086471036, 5771.68255745938, 5916.792912248051, 5918.674472904032, 5923.170756094064, 5797.565182611237, 5872.265966754156, 5872.647641141545, 5875.148103525051, 5899.67551784656, 5791.218185544897, 5840.474357307863, 5841.9707901461925, 5848.678322692293, 5849.356570777296, 5779.739911704068, 5784.5083167929515, 5859.677717725566, 5931.288245410578, 5949.524038076857, 5600.467955544217, 5632.7183640816975, 5669.291338582677, 5670.971645141748, 5699.401562835843], "unit": "ms", "name": "dojo.html", "value": 5520.579467653907}, {"lowerIsBetter": false, "replicates": [544.9782008719651, 600.1777564736312, 602.5179856115108, 619.7366119399254, 619.7644894939925, 30494.390112197743, 39813.60186398135, 40463.39304910425, 40497.39253911186, 40515.0, 45280.547194528124, 45849.08301833961, 45891.0821783565, 45939.77030114853, 45975.0, 7950.880736788953, 8042.155573664769, 8052.0, 9019.052999435064, 9184.632614695403, 10889.0, 11104.55581776732, 11131.888681113207, 11138.665840024812, 11154.553817847316, 10811.135109191286, 10949.726256843564, 10950.34297942126, 10953.780924381508, 10963.0, 10696.304740191901, 10773.83839242412, 10824.0, 10837.403942783143, 10844.511996960076, 10632.946835265851, 10708.87556806532, 10722.249442539025, 10732.73168170794, 10736.570537178504, 42729.78635106805, 42958.57041429577, 43079.0, 43121.35317970233, 43162.35256471155, 3661.4690869823994, 4003.73975691582, 4042.272390969624, 4299.634531064863, 4425.889352766175, 32094.839525802454, 32440.83779581087, 32456.513152302734, 32495.51256731151, 32788.6721132788, 4136.689748268892, 4231.576842315774, 4290.721103128287, 4300.053988122608, 4315.136972605492, 288212.0, 288487.0, 288893.0, 289577.0, 289598.0, 55189.44810551843, 56162.0, 61513.38486615166, 74589.0, 76655.23344766481, 66424.66787666127, 66809.33190668128, 66926.66536667285, 66950.0, 66953.0, 14967.850321496646, 15036.62408439776, 15055.096694198382, 15126.470573529874, 15172.393104275705, 12464.688382790322, 12565.560205392767, 12599.622011339674, 12606.369681515887, 12629.11596188258, 22409.775902241094, 22795.88602056979, 22831.0, 22903.7709622905, 22949.196778112684, 7689.76930692069, 7779.8833017504785, 7922.524648521107, 7961.138847658881, 8003.439759216915, 987.5013118375175, 1000.1798525209404, 1012.099231683802, 1137.2835113878205, 1187.7861984842639, 29458.263543411587, 29938.25154371158, 30559.388812224075, 30731.38537229243, 30971.535426968163, 1071.6731396924013, 1080.713610893114, 1115.9218854680094, 1130.9038731707733, 1154.2901115813713, 13614.795778063337, 14011.369488373048, 14642.341094650767, 16264.268107935171, 16628.418005369753, 822825.0, 892503.0, 898190.0, 898824.0, 899069.5511746916, 62107.0, 62277.0, 62378.37621623726, 62395.6880215596, 62432.0, 1387.431153227172, 1398.0772690024535, 1522.5660686704014, 1535.8848086393566, 1573.5279416175101, 31816.069438683946, 32191.678083219806, 32450.513242301855, 32738.83630581927, 33091.0, 44975.775121124185, 45009.32486012647, 45852.31221531613, 46112.0, 46207.30689039734, 225807.0, 226862.0, 245730.0, 245963.0, 246468.0, 35003.82498087493, 35208.823955880056, 35732.4640130393, 35752.46371304485, 36038.27923441569, 45454.318185226584, 46000.079998399175, 46218.76890615525, 46473.302900457194, 46499.0, 38431.03922401962, 38752.6124738749, 38789.22421551609, 44521.55478445174, 45134.54865451303, 39648.40527392149, 40572.1885562293, 45562.0, 45886.0, 47676.284855727885, 38431.0, 39253.60746392499, 39699.60300397075, 39772.2045559093, 39803.60196397999, 34508.65491345055, 34902.65097348994, 35161.824190878884, 35329.0, 35342.0, 26576.601350979363, 26837.14309803555, 26892.865535672197, 27186.592201117393, 27198.864005679847], "unit": "ms", "name": "ext.html", "value": 20655.882702932668}, {"lowerIsBetter": false, "replicates": [366.8495916674164, 367.18301778542747, 370.5812431951895, 372.1923426165221, 372.7632953074798, 14596.0, 15263.542093737206, 17153.742693859604, 17528.474145775614, 18228.63542729146, 16505.587360316, 16548.338066477358, 16749.246283917222, 17514.912425437855, 17530.47408577745, 5011.724355160474, 5640.266765320503, 5642.0, 5643.68959707216, 5643.8024669136585, 4305.461817272841, 4421.712588681741, 5653.886922261552, 6097.69511524422, 6248.343923887995, 6021.42796434338, 6134.263888333407, 6156.292026416974, 6165.0, 6183.81448556544, 6224.9417599008275, 6247.344028876948, 6249.750009999594, 6252.687365631723, 6259.061140828867, 5142.434332223453, 5465.836024919259, 5466.043442397565, 5466.070767969455, 5487.725613719298, 13595.116317439335, 13728.588142355646, 13814.516491922835, 13853.930730346385, 13924.30378481072, 2121.3741946125774, 2198.65920782279, 2215.4461384653837, 2252.1104163855184, 2302.251768175333, 10211.336263142946, 10460.058594726435, 10982.945085274523, 11486.253393529394, 14562.781558276634, 1907.542189874434, 1915.6073005034075, 1968.1536939116174, 1969.8719583227187, 1992.2728204205505, 63654.36345636577, 65180.674096629686, 65541.39522326319, 66561.66719166422, 67892.0, 10864.402457864893, 10899.891001090045, 10926.125909927187, 10927.235093543535, 10936.179786516042, 14089.436422542984, 14216.0, 14226.430942762381, 14234.359453824603, 14240.287985600678, 7415.036045314183, 7453.85092298151, 7929.246721561442, 7938.801529961797, 7988.0, 7566.810829729191, 7569.318761311509, 7576.507527010726, 7619.199984001711, 7672.693092276358, 4462.955370446319, 4532.776150439428, 4534.659900507475, 4627.07458508293, 4639.907201856012, 1711.3154738104608, 1754.8069712331633, 1797.6854050541365, 1826.4246762269843, 1835.3667984545311, 933.4399360383716, 944.5831253120319, 1035.254616675992, 1048.7483004078965, 1076.9192310576739, 14875.33061012257, 16248.431304904272, 16308.918455407884, 17001.48995530136, 17182.65634687299, 924.505389616552, 965.6282331302499, 983.1986930651497, 1012.6506355307407, 1019.6889948565611, 9600.423974561549, 9950.353227040365, 10013.148882345082, 10020.849687254697, 10106.29255952091, 633179.0, 640885.0, 641089.0, 641860.0, 659725.0, 84771.0, 85072.0, 88047.0, 88582.0, 88882.55558722295, 1214.8663646998823, 1218.9756204875853, 1222.8532576090927, 1224.5285565057518, 1226.625879106863, 15949.920250398674, 16014.759778603564, 16152.273147708147, 16183.838161618232, 16271.918640406722, 37520.62479375171, 37933.0, 38092.238155236184, 38915.6108438912, 39540.2091958165, 49696.25455618096, 51380.74309628428, 51412.88696904164, 53526.1971070442, 53565.732171338896, 9989.950050249992, 10099.091081802673, 10207.183425325808, 10207.59169633236, 10209.74475638115, 11716.472758726048, 18002.0, 18213.271469141615, 18239.452816415527, 18678.25286988505, 14630.707385852436, 14845.851541484446, 15073.773893391388, 15334.233288335761, 15469.458568950033, 14865.851341486446, 15080.773788392964, 15103.32035058403, 15304.846951530788, 15636.843631563539, 15449.613759656098, 15520.22398880074, 15622.609434763766, 15625.609359766097, 15690.372385104925, 9902.059304366074, 9917.752056198653, 9920.900790992286, 9942.751431214278, 10095.44475053879, 10674.306170098918, 13839.584812455643, 14981.550553483414, 15080.321385537458, 15490.148041857807], "unit": "ms", "name": "jquery.html", "value": 9268.073148668445}, {"lowerIsBetter": false, "replicates": [1756.5169578365949, 1807.1870438590395, 1840.7146892231701, 1846.363004763357, 1871.3824437935482, 7731.0, 7956.323712484445, 7970.681172753083, 7972.521648701082, 7975.7208497702595, 6779.76270830521, 6884.414824739891, 6890.483213758963, 6896.413804826583, 7740.961295193516, 2230.4646884747667, 2249.527599204161, 2275.590393729128, 2277.8974946127464, 2278.1912421090524, 2274.9545009099893, 2291.175176936302, 2314.9421264468438, 2316.9420764480856, 2318.547883162784, 1947.552063025505, 1977.4561995451222, 2243.1251811793413, 2267.7732226777357, 2281.3384118605586, 2237.2169740590825, 2237.8097861681777, 2238.149503188786, 2242.697235873161, 2243.1588154442084, 2259.062489067035, 2358.1157066100213, 2363.2201373546686, 2363.8818059096975, 2364.9527009459716, 7737.30364267213, 7797.337226335769, 7818.648160832776, 7821.374290056782, 7823.687052517893, 4697.953020469786, 4784.808607655689, 4788.545088216649, 4791.185498465269, 4792.952070479286, 3050.588170596963, 3068.6164229471315, 3092.3196896682693, 3101.0231776990177, 3107.829069401182, 1002.9548670309854, 1005.5625802775816, 1014.279861298479, 1033.8862725100307, 1057.693268952003, 10423.530941107669, 10433.113185379176, 10433.739156521147, 10465.581376744996, 10487.423191724452, 4313.525512193656, 4321.049369138785, 4330.935035974463, 4363.934540981888, 4409.140217657526, 9103.590338434786, 9114.772130696785, 9118.452892826452, 9118.954405228063, 9123.0, 4825.95174048262, 4827.686200396963, 4862.732549709729, 4864.391951006124, 4864.562189402971, 4425.933610995838, 4428.601425871687, 4432.113577284556, 4438.223310920641, 4438.5561443856, 3593.1915319053005, 3679.7240206984584, 3703.370427027358, 3718.0704823794053, 3724.348239058154, 1177.5054477119631, 1177.6584790410857, 1179.6343133628602, 1185.1763024697752, 1193.2065176657425, 1029.9433531155707, 1075.0109898893038, 1097.05653138301, 1310.88202061813, 1380.6134282400944, 7406.740764073232, 7464.440166987498, 7472.850542989218, 7479.700811967569, 7481.326680598772, 1143.0741099709262, 1170.8887655672697, 1172.7068232941765, 1192.9164958452825, 1208.2508844516456, 5852.882942341129, 5943.58394912352, 5945.464908158287, 5947.494462970696, 5953.79161729346, 8780.648774049094, 8896.154865287788, 9012.8648070279, 9143.177114059767, 9143.222826059859, 9057.682981095762, 9162.770930726652, 9197.08029197075, 9199.218066464426, 9209.539523023823, 7173.820654483679, 7230.638468076575, 7317.195108538163, 7364.189939106694, 7409.777706668809, 7508.962455187689, 7548.622568871644, 7559.5464272143845, 7562.130355009244, 7565.243475652391, 8227.629756660845, 8472.322214222724, 8532.131941412286, 8595.484270943763, 8638.870416943626, 8597.957010214908, 8720.476771393736, 8774.517401542977, 8775.166359195866, 8780.385373023826, 6984.3364880336285, 7204.819879503054, 7207.063081799332, 7209.639518023973, 7210.351068403869, 7624.8475030500185, 7680.69277228927, 7685.884711729441, 7685.923140768745, 7686.46194766361, 7056.435485161277, 7237.963810180915, 7238.927610723826, 7313.707451701873, 7367.189609143097, 6306.906948742742, 6425.58233714807, 6818.897716534157, 7091.11361079865, 7201.963990180016, 7017.228104908558, 7100.218975912749, 7103.183133939457, 7108.040414544177, 7108.644567771694, 6692.665366731547, 6715.899261511179, 6722.932770672231, 6724.0, 6725.361090696376, 6694.430973367123, 6722.226943901514, 6747.0, 6759.594424334556, 6766.5263431559315], "unit": "ms", "name": "mootools.html", "value": 4549.483247645005}, {"lowerIsBetter": false, "replicates": [329.76421858371265, 347.2222222222223, 348.4129242226847, 351.27111244168356, 357.1482015393286, 6133.539984501163, 6163.137160797492, 6186.443220110189, 6194.28765691945, 6252.655679029008, 6109.266887973449, 6109.847253818657, 6112.266528016633, 6113.908291375633, 6121.204243448346, 4639.072185562884, 4777.737224452654, 4777.808887644499, 4780.354652121954, 4780.521947805227, 4736.265878788793, 4743.0, 4744.07490539345, 4755.453122890877, 4757.64317676174, 4687.250039993602, 4699.295105734133, 4712.19892618254, 4714.0, 4746.335513028179, 4540.727556346614, 4644.303354496818, 4670.9065818683775, 4753.691010084333, 4763.166445871976, 4176.0603864130635, 4182.351735481006, 4190.769507677077, 4195.181939521794, 4196.3705444183315, 5620.634658747209, 5790.536757059425, 5830.3586605473365, 5831.970840145815, 5838.678872662002, 5407.783688652449, 5409.864753381157, 5552.694601796899, 5557.5553955683445, 5564.415736347666, 6493.7727179548965, 6514.93485065148, 6527.738890444377, 6528.477721782246, 6529.902051469232, 4637.721736695809, 4659.347691323217, 4659.627229821606, 4660.464046634613, 4665.766711664403, 6567.146270984789, 6654.900176497356, 6757.864842703168, 6774.967509747105, 6782.287859774751, 4584.243599805995, 4602.815887364535, 4613.446386433649, 4617.607503362185, 4618.076384723069, 4668.719876807402, 4669.743164125938, 4671.972166123447, 4675.0, 4677.64917631179, 5315.202719592092, 5413.7834486620095, 5416.9729151353995, 5427.782888684408, 5428.45715428454, 6532.836679082966, 6539.313372095957, 6544.083828264047, 6547.574407663486, 6551.868962620721, 1680.8739344549208, 1708.3337498375643, 1711.187186086599, 1711.2299465240692, 1712.5290545100197, 1151.804193287143, 1153.5501154549731, 1154.261272785418, 1170.2276497511602, 1181.0197536045061, 6276.560640755103, 6287.654179020198, 6295.055741638791, 6299.874002519924, 6305.0, 6767.323267673193, 6767.627780472023, 6772.0, 6787.830304242335, 6790.456763458911, 6216.532343636578, 6258.9374106259265, 6285.402886725754, 6299.937000629935, 6308.526860485482, 3631.6913062389463, 3664.029032306441, 3664.13892735207, 3666.2850744105176, 3667.614900435416, 6966.756163534252, 6971.8605627887155, 6983.89524157138, 6987.056747339146, 6997.055397521372, 6719.0, 6805.3875151236625, 6834.965825170942, 6883.139607549057, 6927.272636373209, 7047.471439642048, 7060.258672839381, 7060.399866011344, 7063.399611033017, 7066.293370662996, 6828.522003459909, 6842.486813488908, 6846.418054465426, 6852.760153394607, 6861.55399899005, 6538.803835884741, 6738.7304507819135, 6766.8646627068165, 6831.214410342675, 6837.042814006142, 7079.327463890922, 7080.0, 7080.752173673896, 7082.964585177041, 7089.0, 4650.488446270975, 4691.835785747619, 4692.577668009895, 4697.5302469754115, 4706.576408123286, 6662.800115996528, 6799.762008329685, 6803.217629972616, 6811.523193376416, 6812.318768123148, 6627.37039981201, 6648.867022659617, 6649.60102393858, 6649.966750166218, 6654.334566543307, 6491.902621460777, 6610.669466526559, 6610.702518386588, 6613.735450581923, 6624.0, 6505.772297969548, 6519.934800651933, 6527.15147030883, 6527.412532872065, 6531.3468653134305, 6653.135092437952, 6655.567388119756, 6656.301088385843, 6662.900056499059, 6665.300143484864, 6384.329645387354, 6490.48076153916, 6493.545451818327, 6508.8047358579315, 6511.088447617242], "unit": "ms", "name": "prototype.html", "value": 4964.592657144467}, {"lowerIsBetter": false, "replicates": [839.3788596438635, 855.6919508976767, 933.8085692433052, 935.3919952031179, 943.400940402844, 12660.177088489254, 13127.540536081215, 13128.01539884509, 13133.802992955114, 13134.474621015172, 12850.48598056079, 12906.354682265872, 12925.935370323135, 12941.0, 12948.352582370891, 729.3253740290211, 735.1068451831025, 874.4490970688473, 878.9165029322204, 892.3173772064376, 885.3846576629245, 887.4409122252972, 899.0335389456328, 899.8515244984602, 900.5767289373983, 917.5091326140516, 919.7332773495679, 931.0084759730865, 931.8043210925714, 931.8276118917988, 771.463832636318, 772.7913463364888, 923.307519360483, 923.3213588012806, 925.3753716241511, 687.0793137196181, 687.295522089857, 689.2280645676824, 690.082190686391, 690.5235387582552, 12847.29339886306, 13140.60578182656, 13175.67060823487, 13187.142835715647, 13187.604371868763, 11555.76888462226, 12495.0, 12498.06264530164, 12533.185342952771, 12768.80846787299, 10049.899501005042, 10436.32163909351, 10578.52396642153, 10605.628802991936, 10658.200634952333, 3702.38910579754, 3774.7735135891935, 3780.489633899416, 3784.4701741756176, 3785.3943369060817, 46074.0, 46173.53826461759, 51565.226521602206, 52458.0, 52551.21173182367, 10019.0, 10073.746605078994, 10089.75209662692, 10091.899081009096, 10178.287519873686, 19083.80916190848, 19098.618027639648, 19134.904325478285, 19144.329948452018, 19171.80828191728, 7659.425543084291, 7846.960765196138, 8052.718154864551, 8130.390220733469, 8168.7957801055445, 4381.145676593033, 4496.775161241925, 4524.47968483622, 4624.583787459145, 4658.720476771405, 3180.7614428917923, 3198.2164369729567, 3199.4720871056015, 3204.83975801209, 3211.6627754085953, 564.5004171308422, 598.7126179433897, 604.2265899648457, 611.9785807496717, 620.0265583034683, 598.0789584040616, 657.9440747536513, 666.5733930284622, 681.3186813186813, 692.9826754331182, 8372.0, 9645.08371704687, 9753.414795112316, 10157.847632285522, 10347.689569312932, 689.7482418917159, 715.2882881532908, 729.5622626424104, 734.6620554544862, 758.317514237191, 4084.65280451159, 4185.100203456272, 4288.313869780883, 4289.528151903288, 4330.718503297276, 63721.044184337276, 64047.359526405075, 64484.35515644877, 64505.67747161234, 64601.353986460475, 55862.16206756902, 57628.135577966365, 58693.41306586965, 58757.70621146867, 58859.41140588625, 49647.751761241685, 50418.74790626097, 50503.24245136326, 51140.0, 51421.0, 8779.824403512022, 8816.294696424398, 8826.470411775314, 9003.324750643858, 9095.04502027304, 37334.0, 37355.6264437363, 37854.81072594619, 38283.425748613234, 38381.80809095937, 42527.14945701131, 46028.7698561505, 47947.28079078747, 48126.5187348136, 48416.51583484261, 12052.698682532657, 12312.507499699912, 12548.0, 12882.742345153232, 12907.354632268536, 12599.0, 12687.80968285458, 12953.611391658265, 13057.477700891857, 13194.274314912773, 11248.718782030515, 11434.199606027498, 11479.770404591694, 11510.13673974472, 11574.0, 11850.170488065753, 11870.287782733063, 12013.279203247834, 12120.818187727367, 12150.817737734118, 10318.638847640297, 10717.571297148026, 10754.623588174376, 10845.13238940867, 10936.289141205796, 9839.409635421898, 9843.950780246052, 10301.433421161908, 10329.328593641389, 10504.684859454228, 9532.714018579454, 10018.549165287724, 10050.69847904564, 10093.848592270975, 10141.898581014095], "unit": "ms", "name": "yui.html", "value": 6627.028428087605}], "name": "dromaeo_css", "value": 7354.2295646472585}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 108.0, 104.0, 109.0, 109.0, 109.0, 100.0, 110.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.4}, {"lowerIsBetter": true, "replicates": [144.0, 120.0, 154.0, 163.0, 122.0, 146.0, 152.0, 122.0, 164.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.8}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 159.0, 161.0, 162.0, 159.0, 159.0, 162.0, 162.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 71.0, 145.0, 71.0, 72.0, 73.0, 72.0, 161.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.7}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 139.0, 93.0, 93.0, 94.0, 136.0, 93.0, 93.0, 143.0], "unit": "ms", "name": "audio-oscillator", "value": 107.0}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 145.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 56.0, 58.0, 58.0, 56.0, 57.0, 55.0, 55.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 66.0, 64.0, 65.0, 66.0, 65.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.9}, {"lowerIsBetter": true, "replicates": [75.0, 76.0, 77.0, 77.0, 77.0, 78.0, 78.0, 79.0, 86.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.1}, {"lowerIsBetter": true, "replicates": [119.0, 111.0, 110.0, 108.0, 117.0, 114.0, 122.0, 112.0, 121.0, 112.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.6}, {"lowerIsBetter": true, "replicates": [139.0, 139.0, 140.0, 140.0, 140.0, 141.0, 138.0, 143.0, 138.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.7}, {"lowerIsBetter": true, "replicates": [68.0, 63.0, 66.0, 63.0, 65.0, 62.0, 63.0, 62.0, 66.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.2}], "name": "kraken", "value": 1471.5999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.25204883759827], "unit": "score", "name": "Richards", "value": 211.07065799999992}, {"lowerIsBetter": false, "replicates": [164.23057973394646], "unit": "score", "name": "DeltaBlue", "value": 402.59250199999997}, {"lowerIsBetter": false, "replicates": [278.2415136338342], "unit": "score", "name": "Encrypt", "value": 956.654514}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [981.3542688910696], "unit": "score", "name": "RayTrace", "value": 754.0487909999998}, {"lowerIsBetter": false, "replicates": [547.3453749315818], "unit": "score", "name": "Earley", "value": 1217.6279009999998}, {"lowerIsBetter": false, "replicates": [10572.916666666666], "unit": "score", "name": "Boyer", "value": 63.03492413793106}, {"lowerIsBetter": false, "replicates": [24309.52380952381], "unit": "score", "name": "RegExp", "value": 37.47440744368264}, {"lowerIsBetter": false, "replicates": [526.0389268805892], "unit": "score", "name": "Splay", "value": 154.91439100000002}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23179.919171704136}]} 14:29:43 INFO - RETURN: dromaeo_css: 7354.23 14:29:43 INFO - RETURN: kraken: 1471.60 14:29:43 INFO - RETURN: v8_7: 23179.92 14:29:43 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23179.92"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1471.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7354.23"}}} 14:29:43 INFO - Return code: 0 14:29:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:29:43 INFO - # TBPL SUCCESS # 14:29:43 INFO - Running post-action listener: _resource_record_post_action 14:29:43 INFO - Running post-run listener: _resource_record_post_run 14:29:44 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 315392; Write bytes: 526045184; Read time: 48; Write time: 439020 14:29:44 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 259244032; Read time: 0; Write time: 299080 14:29:44 INFO - run-tests - Wall time: 1206s; CPU: 13.0%; Read bytes: 315392; Write bytes: 266801152; Read time: 48; Write time: 139940 14:29:44 INFO - Running post-run listener: _upload_blobber_files 14:29:44 INFO - Blob upload gear active. 14:29:44 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:29:44 INFO - Copying logs to upload dir... 14:29:44 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1252.035731 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-17 14:29:44.331416) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-17 14:29:44.335149) ========= 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010016 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455728934/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-17 14:29:44.385704) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:29:44.386062) ========= 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-1455746930.940943-1782087825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004598 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-17 14:29:44.435440) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 14:29:44.435736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-17 14:29:44.436036) ========= ========= Total master_lag: 0.26 =========