builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-041 starttime: 1456418836.87 results: success (0) buildid: 20160225062630 builduid: 6b828a0255c44b9daeb9f5cf0b87d55d revision: 20afdf1337fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.875095) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.875540) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.875977) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006689 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.896579) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.896905) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.910627) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.910890) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004766 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.959620) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.959891) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.960233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:16.960519) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 08:47:17-- 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% 78.3M=0s 2016-02-25 08:47:17 (78.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098872 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.072567) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.072899) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020071 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.122094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.122465) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 20afdf1337fc --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 20afdf1337fc --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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 08:47:17,320 Setting DEBUG logging. 2016-02-25 08:47:17,320 attempt 1/10 2016-02-25 08:47:17,320 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/20afdf1337fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 08:47:17,591 unpacking tar archive at: mozilla-beta-20afdf1337fc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.578393 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.713056) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.713417) ========= echo 20afdf1337fc in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'20afdf1337fc'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20afdf1337fc program finished with exit code 0 elapsedTime=0.004630 script_repo_revision: '20afdf1337fc' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.763977) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.764289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 08:47:17.773992) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-02-25 08:47:17.774381) ========= /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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:47:18 INFO - MultiFileLogger online at 20160225 08:47:18 in /builds/slave/test-pgo 08:47:18 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 08:47:18 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:47:18 INFO - {'append_to_log': False, 08:47:18 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:47:18 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:47:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:47:18 INFO - 'branch': 'Mozilla-Beta', 08:47:18 INFO - 'buildbot_json_path': 'buildprops.json', 08:47:18 INFO - 'config_files': ('talos/linux_config.py',), 08:47:18 INFO - 'default_actions': ('clobber', 08:47:18 INFO - 'read-buildbot-config', 08:47:18 INFO - 'download-and-extract', 08:47:18 INFO - 'populate-webroot', 08:47:18 INFO - 'create-virtualenv', 08:47:18 INFO - 'install', 08:47:18 INFO - 'run-tests'), 08:47:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:47:18 INFO - 'download_minidump_stackwalk': True, 08:47:18 INFO - 'download_symbols': 'ondemand', 08:47:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:47:18 INFO - 'tooltool.py': '/tools/tooltool.py', 08:47:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:47:18 INFO - '/tools/misc-python/virtualenv.py')}, 08:47:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:47:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:47:18 INFO - 'installer_path': 'installer.exe', 08:47:18 INFO - 'log_level': 'info', 08:47:18 INFO - 'log_name': 'talos', 08:47:18 INFO - 'log_to_console': True, 08:47:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:47:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:47:18 INFO - 'opt_config_files': (), 08:47:18 INFO - 'pip_index': False, 08:47:18 INFO - 'sps_profile': False, 08:47:18 INFO - 'sps_profile_interval': 0, 08:47:18 INFO - 'suite': 'dromaeojs', 08:47:18 INFO - 'system_bits': '32', 08:47:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:47:18 INFO - 'title': 'talos-linux64-ix-041', 08:47:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:47:18 INFO - 'use_talos_json': True, 08:47:18 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:47:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:47:18 INFO - 'work_dir': 'build'} 08:47:18 INFO - ##### 08:47:18 INFO - ##### Running clobber step. 08:47:18 INFO - ##### 08:47:18 INFO - Running pre-action listener: _resource_record_pre_action 08:47:18 INFO - Running main action method: clobber 08:47:18 INFO - rmtree: /builds/slave/test-pgo/build 08:47:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:47:19 INFO - Running post-action listener: _resource_record_post_action 08:47:19 INFO - ##### 08:47:19 INFO - ##### Running read-buildbot-config step. 08:47:19 INFO - ##### 08:47:19 INFO - Running pre-action listener: _resource_record_pre_action 08:47:19 INFO - Running main action method: read_buildbot_config 08:47:19 INFO - Using buildbot properties: 08:47:19 INFO - { 08:47:19 INFO - "properties": { 08:47:19 INFO - "buildnumber": 97, 08:47:19 INFO - "product": "firefox", 08:47:19 INFO - "script_repo_revision": "production", 08:47:19 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:47:19 INFO - "repository": "", 08:47:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:47:19 INFO - "buildid": "20160225062630", 08:47:19 INFO - "slavename": "talos-linux64-ix-041", 08:47:19 INFO - "pgo_build": "True", 08:47:19 INFO - "basedir": "/builds/slave/test-pgo", 08:47:19 INFO - "project": "", 08:47:19 INFO - "platform": "ubuntu64_hw", 08:47:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:47:19 INFO - "slavebuilddir": "test", 08:47:19 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:47:19 INFO - "branch": "mozilla-beta", 08:47:19 INFO - "repo_path": "releases/mozilla-beta", 08:47:19 INFO - "stage_platform": "linux64-pgo", 08:47:19 INFO - "builduid": "6b828a0255c44b9daeb9f5cf0b87d55d", 08:47:19 INFO - "revision": "20afdf1337fc" 08:47:19 INFO - }, 08:47:19 INFO - "sourcestamp": { 08:47:19 INFO - "repository": "", 08:47:19 INFO - "hasPatch": false, 08:47:19 INFO - "project": "", 08:47:19 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:47:19 INFO - "changes": [ 08:47:19 INFO - { 08:47:19 INFO - "category": null, 08:47:19 INFO - "files": [ 08:47:19 INFO - { 08:47:19 INFO - "url": null, 08:47:19 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:47:19 INFO - } 08:47:19 INFO - ], 08:47:19 INFO - "repository": "", 08:47:19 INFO - "rev": "20afdf1337fc", 08:47:19 INFO - "who": "sendchange", 08:47:19 INFO - "when": 1456418807, 08:47:19 INFO - "number": 7653662, 08:47:19 INFO - "comments": "Bug 1247236 - r=jesup. a=sylvestre", 08:47:19 INFO - "project": "", 08:47:19 INFO - "at": "Thu 25 Feb 2016 08:46:47", 08:47:19 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:47:19 INFO - "revlink": "", 08:47:19 INFO - "properties": [ 08:47:19 INFO - [ 08:47:19 INFO - "buildid", 08:47:19 INFO - "20160225062630", 08:47:19 INFO - "Change" 08:47:19 INFO - ], 08:47:19 INFO - [ 08:47:19 INFO - "builduid", 08:47:19 INFO - "6b828a0255c44b9daeb9f5cf0b87d55d", 08:47:19 INFO - "Change" 08:47:19 INFO - ], 08:47:19 INFO - [ 08:47:19 INFO - "pgo_build", 08:47:19 INFO - "True", 08:47:19 INFO - "Change" 08:47:19 INFO - ] 08:47:19 INFO - ], 08:47:19 INFO - "revision": "20afdf1337fc" 08:47:19 INFO - } 08:47:19 INFO - ], 08:47:19 INFO - "revision": "20afdf1337fc" 08:47:19 INFO - } 08:47:19 INFO - } 08:47:19 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:47:19 INFO - Running post-action listener: _resource_record_post_action 08:47:19 INFO - ##### 08:47:19 INFO - ##### Running download-and-extract step. 08:47:19 INFO - ##### 08:47:19 INFO - Running pre-action listener: _resource_record_pre_action 08:47:19 INFO - Running main action method: download_and_extract 08:47:19 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:47: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')]} 08:47:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/test_packages.json 08:47:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:47:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:47:19 INFO - Downloaded 1270 bytes. 08:47:19 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:47:19 INFO - Using the following test package requirements: 08:47:19 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:47:19 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:47:19 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'jsshell-linux-x86_64.zip'], 08:47:19 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:47:19 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:47:19 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:47:19 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:47:19 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:47:19 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:47:19 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:47:19 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:47:19 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:47: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')]} 08:47:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:47:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:19 INFO - Downloaded 21586304 bytes. 08:47:19 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 08:47:19 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:47:19 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 08:47:20 INFO - Return code: 0 08:47:20 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 08:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:20 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:47:20 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:21 INFO - Downloaded 21586304 bytes. 08:47:21 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 08:47:21 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:47:21 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 08:47:22 INFO - Return code: 0 08:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:22 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:47:22 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:24 INFO - Downloaded 11132038 bytes. 08:47:24 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 08:47:24 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:47:24 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 08:47:24 INFO - Return code: 0 08:47:24 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')]} 08:47:24 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:47:24 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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 08:47:26 INFO - Downloaded 57937134 bytes. 08:47:26 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:47:27 INFO - mkdir: /builds/slave/test-pgo/properties 08:47:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:47:27 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:47:27 INFO - Contents: 08:47:27 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:47:27 INFO - Running post-action listener: _resource_record_post_action 08:47:27 INFO - Running post-action listener: set_extra_try_arguments 08:47:27 INFO - ##### 08:47:27 INFO - ##### Running populate-webroot step. 08:47:27 INFO - ##### 08:47:27 INFO - Running pre-action listener: _resource_record_pre_action 08:47:27 INFO - Running main action method: populate_webroot 08:47:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:47:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:47:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:47:27 INFO - '--tppagecycles', 08:47:27 INFO - '7'], 08:47:27 INFO - 'tests': ['tsvgm']}}, 08:47:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:47:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:47:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:47:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:47:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:47:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:47:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'tests': ['damp', 'tps']}, 08:47:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['damp', 'tps']}, 08:47:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:47:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:47:27 INFO - 'other': {'tests': ['a11yr', 08:47:27 INFO - 'ts_paint', 08:47:27 INFO - 'tpaint', 08:47:27 INFO - 'sessionrestore', 08:47:27 INFO - 'sessionrestore_no_auto_restore']}, 08:47:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['a11yr', 08:47:27 INFO - 'ts_paint', 08:47:27 INFO - 'tpaint', 08:47:27 INFO - 'sessionrestore', 08:47:27 INFO - 'sessionrestore_no_auto_restore']}, 08:47:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['a11yr', 08:47:27 INFO - 'ts_paint', 08:47:27 INFO - 'tpaint', 08:47:27 INFO - 'sessionrestore', 08:47:27 INFO - 'sessionrestore_no_auto_restore']}, 08:47:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['a11yr', 08:47:27 INFO - 'ts_paint', 08:47:27 INFO - 'tpaint', 08:47:27 INFO - 'sessionrestore', 08:47:27 INFO - 'sessionrestore_no_auto_restore']}, 08:47:27 INFO - 'other_l64': {'tests': ['a11yr', 08:47:27 INFO - 'ts_paint', 08:47:27 INFO - 'tpaint', 08:47:27 INFO - 'sessionrestore', 08:47:27 INFO - 'sessionrestore_no_auto_restore']}, 08:47:27 INFO - 'other_nol64': {'tests': ['a11yr', 08:47:27 INFO - 'ts_paint', 08:47:27 INFO - 'tpaint', 08:47:27 INFO - 'sessionrestore', 08:47:27 INFO - 'sessionrestore_no_auto_restore']}, 08:47:27 INFO - 'svgr': {'tests': ['tsvgx', 08:47:27 INFO - 'tsvgr_opacity', 08:47:27 INFO - 'tart', 08:47:27 INFO - 'tscrollx', 08:47:27 INFO - 'cart']}, 08:47:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['tsvgx', 08:47:27 INFO - 'tsvgr_opacity', 08:47:27 INFO - 'tart', 08:47:27 INFO - 'tscrollx', 08:47:27 INFO - 'cart']}, 08:47:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'tests': ['tp5o']}, 08:47:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'talos_options': ['--e10s'], 08:47:27 INFO - 'tests': ['tp5o']}, 08:47:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'talos_options': ['--xperf_path', 08:47:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:47:27 INFO - 'tests': ['tp5n']}, 08:47:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:47:27 INFO - 'talos_options': ['--e10s', 08:47:27 INFO - '--xperf_path', 08:47:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:47:27 INFO - 'tests': ['tp5n']}}, 08:47:27 INFO - 'talos.zip': {'path': '', 08:47:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:47:27 INFO - Running post-action listener: _resource_record_post_action 08:47:27 INFO - ##### 08:47:27 INFO - ##### Running create-virtualenv step. 08:47:27 INFO - ##### 08:47:27 INFO - Running pre-action listener: _resource_record_pre_action 08:47:27 INFO - Running main action method: create_virtualenv 08:47:27 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:47:27 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 08:47:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:47:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:47:27 INFO - Using real prefix '/usr' 08:47:27 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:47:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:47:29 INFO - Installing pip.................done. 08:47:29 INFO - Return code: 0 08:47:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 08:47:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:47:29 INFO - Using env: {'DISPLAY': ':0', 08:47:29 INFO - 'HOME': '/home/cltbld', 08:47:29 INFO - 'LANG': 'en_US.UTF-8', 08:47:29 INFO - 'LANGUAGE': 'en_US:en', 08:47:29 INFO - 'LOGNAME': 'cltbld', 08:47:29 INFO - 'MAIL': '/var/mail/cltbld', 08:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:29 INFO - 'MOZ_NO_REMOTE': '1', 08:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:29 INFO - 'NO_EM_RESTART': '1', 08:47:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:29 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:29 INFO - 'SHELL': '/bin/bash', 08:47:29 INFO - 'SHLVL': '1', 08:47:29 INFO - 'TERM': 'linux', 08:47:29 INFO - 'TMOUT': '86400', 08:47:29 INFO - 'USER': 'cltbld', 08:47:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:29 INFO - '_': '/tools/buildbot/bin/python'} 08:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:29 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 08:47:29 INFO - Cleaning up... 08:47:29 INFO - Return code: 0 08:47:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:29 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 08:47:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:47:29 INFO - Using env: {'DISPLAY': ':0', 08:47:29 INFO - 'HOME': '/home/cltbld', 08:47:29 INFO - 'LANG': 'en_US.UTF-8', 08:47:29 INFO - 'LANGUAGE': 'en_US:en', 08:47:29 INFO - 'LOGNAME': 'cltbld', 08:47:29 INFO - 'MAIL': '/var/mail/cltbld', 08:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:29 INFO - 'MOZ_NO_REMOTE': '1', 08:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:29 INFO - 'NO_EM_RESTART': '1', 08:47:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:29 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:29 INFO - 'SHELL': '/bin/bash', 08:47:29 INFO - 'SHLVL': '1', 08:47:29 INFO - 'TERM': 'linux', 08:47:29 INFO - 'TMOUT': '86400', 08:47:29 INFO - 'USER': 'cltbld', 08:47:29 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:29 INFO - '_': '/tools/buildbot/bin/python'} 08:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:30 INFO - Downloading/unpacking psutil>=0.7.1 08:47:30 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 08:47:30 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 08:47:30 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 08:47:30 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 08:47:30 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 08:47:30 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 08:47:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:47:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:32 INFO - Installing collected packages: psutil 08:47:32 INFO - Running setup.py install for psutil 08:47:32 INFO - building 'psutil._psutil_linux' extension 08:47:32 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 08:47:32 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 08:47:32 INFO - building 'psutil._psutil_posix' extension 08:47:32 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 08:47:32 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 08:47:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:32 INFO - Successfully installed psutil 08:47:32 INFO - Cleaning up... 08:47:32 INFO - Return code: 0 08:47:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:47:32 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')]} 08:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:32 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')]} 08:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:32 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:32 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 08:47:32 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 08:47:32 INFO - Using env: {'DISPLAY': ':0', 08:47:32 INFO - 'HOME': '/home/cltbld', 08:47:32 INFO - 'LANG': 'en_US.UTF-8', 08:47:32 INFO - 'LANGUAGE': 'en_US:en', 08:47:32 INFO - 'LOGNAME': 'cltbld', 08:47:32 INFO - 'MAIL': '/var/mail/cltbld', 08:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:32 INFO - 'MOZ_NO_REMOTE': '1', 08:47:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:32 INFO - 'NO_EM_RESTART': '1', 08:47:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:32 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:32 INFO - 'SHELL': '/bin/bash', 08:47:32 INFO - 'SHLVL': '1', 08:47:32 INFO - 'TERM': 'linux', 08:47:32 INFO - 'TMOUT': '86400', 08:47:32 INFO - 'USER': 'cltbld', 08:47:32 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:32 INFO - '_': '/tools/buildbot/bin/python'} 08:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:47:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:47:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:47:34 INFO - Installing collected packages: mozsystemmonitor 08:47:34 INFO - Running setup.py install for mozsystemmonitor 08:47:34 INFO - Successfully installed mozsystemmonitor 08:47:34 INFO - Cleaning up... 08:47:34 INFO - Return code: 0 08:47:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:47:34 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')]} 08:47:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 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')]} 08:47:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:34 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:34 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 08:47:34 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 08:47:34 INFO - Using env: {'DISPLAY': ':0', 08:47:34 INFO - 'HOME': '/home/cltbld', 08:47:34 INFO - 'LANG': 'en_US.UTF-8', 08:47:34 INFO - 'LANGUAGE': 'en_US:en', 08:47:34 INFO - 'LOGNAME': 'cltbld', 08:47:34 INFO - 'MAIL': '/var/mail/cltbld', 08:47:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:34 INFO - 'MOZ_NO_REMOTE': '1', 08:47:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:34 INFO - 'NO_EM_RESTART': '1', 08:47:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:34 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:34 INFO - 'SHELL': '/bin/bash', 08:47:34 INFO - 'SHLVL': '1', 08:47:34 INFO - 'TERM': 'linux', 08:47:34 INFO - 'TMOUT': '86400', 08:47:34 INFO - 'USER': 'cltbld', 08:47:34 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:34 INFO - '_': '/tools/buildbot/bin/python'} 08:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:35 INFO - Downloading/unpacking blobuploader==1.2.4 08:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:36 INFO - Downloading blobuploader-1.2.4.tar.gz 08:47:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:47:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:47:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:37 INFO - Downloading docopt-0.6.1.tar.gz 08:47:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:47:37 INFO - Installing collected packages: blobuploader, requests, docopt 08:47:37 INFO - Running setup.py install for blobuploader 08:47:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:47:37 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:47:37 INFO - Running setup.py install for requests 08:47:38 INFO - Running setup.py install for docopt 08:47:38 INFO - Successfully installed blobuploader requests docopt 08:47:38 INFO - Cleaning up... 08:47:38 INFO - Return code: 0 08:47:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:38 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:38 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 08:47:38 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 08:47:38 INFO - Using env: {'DISPLAY': ':0', 08:47:38 INFO - 'HOME': '/home/cltbld', 08:47:38 INFO - 'LANG': 'en_US.UTF-8', 08:47:38 INFO - 'LANGUAGE': 'en_US:en', 08:47:38 INFO - 'LOGNAME': 'cltbld', 08:47:38 INFO - 'MAIL': '/var/mail/cltbld', 08:47:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:38 INFO - 'MOZ_NO_REMOTE': '1', 08:47:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:38 INFO - 'NO_EM_RESTART': '1', 08:47:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:38 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:38 INFO - 'SHELL': '/bin/bash', 08:47:38 INFO - 'SHLVL': '1', 08:47:38 INFO - 'TERM': 'linux', 08:47:38 INFO - 'TMOUT': '86400', 08:47:38 INFO - 'USER': 'cltbld', 08:47:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:38 INFO - '_': '/tools/buildbot/bin/python'} 08:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:47:38 INFO - Running setup.py (path:/tmp/pip-Ibq0bi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:47:38 INFO - Running setup.py (path:/tmp/pip-5xziyC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:47:38 INFO - Running setup.py (path:/tmp/pip-XRkfw9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:47:38 INFO - Running setup.py (path:/tmp/pip-DPTVQ6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:47:38 INFO - Running setup.py (path:/tmp/pip-JqERUZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:47:38 INFO - Running setup.py (path:/tmp/pip-HVd2dq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:47:38 INFO - Running setup.py (path:/tmp/pip-oe8K_h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:47:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:47:38 INFO - Running setup.py (path:/tmp/pip-8y5G_e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:47:39 INFO - Running setup.py (path:/tmp/pip-Ym7MK_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:47:39 INFO - Running setup.py (path:/tmp/pip-So06Eq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:47:39 INFO - Running setup.py (path:/tmp/pip-gW4OA3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:47:39 INFO - Running setup.py (path:/tmp/pip-NNVpsL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:47:39 INFO - Running setup.py (path:/tmp/pip-P71iIP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:47:39 INFO - Running setup.py (path:/tmp/pip-Km0Dq6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:47:39 INFO - Running setup.py (path:/tmp/pip-7lEAA6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:47:39 INFO - Running setup.py (path:/tmp/pip-_LOITo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:47:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:47:39 INFO - Running setup.py (path:/tmp/pip-jaFlCc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:47:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:47:39 INFO - Running setup.py install for manifestparser 08:47:39 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:47:39 INFO - Running setup.py install for mozcrash 08:47:39 INFO - Running setup.py install for mozdebug 08:47:39 INFO - Running setup.py install for mozdevice 08:47:40 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Running setup.py install for mozfile 08:47:40 INFO - Running setup.py install for mozhttpd 08:47:40 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Running setup.py install for mozinfo 08:47:40 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Running setup.py install for mozInstall 08:47:40 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Running setup.py install for mozleak 08:47:40 INFO - Running setup.py install for mozlog 08:47:40 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Running setup.py install for moznetwork 08:47:40 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:47:40 INFO - Running setup.py install for mozprocess 08:47:40 INFO - Running setup.py install for mozprofile 08:47:41 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:47:41 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:47:41 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:47:41 INFO - Running setup.py install for mozrunner 08:47:41 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:47:41 INFO - Running setup.py install for mozscreenshot 08:47:41 INFO - Running setup.py install for moztest 08:47:41 INFO - Running setup.py install for mozversion 08:47:41 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:47:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:47:41 INFO - Cleaning up... 08:47:41 INFO - Return code: 0 08:47:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:47:41 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')]} 08:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:41 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')]} 08:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:41 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:41 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 08:47:41 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 08:47:41 INFO - Using env: {'DISPLAY': ':0', 08:47:41 INFO - 'HOME': '/home/cltbld', 08:47:41 INFO - 'LANG': 'en_US.UTF-8', 08:47:41 INFO - 'LANGUAGE': 'en_US:en', 08:47:41 INFO - 'LOGNAME': 'cltbld', 08:47:41 INFO - 'MAIL': '/var/mail/cltbld', 08:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:41 INFO - 'MOZ_NO_REMOTE': '1', 08:47:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:41 INFO - 'NO_EM_RESTART': '1', 08:47:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:41 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:41 INFO - 'SHELL': '/bin/bash', 08:47:41 INFO - 'SHLVL': '1', 08:47:41 INFO - 'TERM': 'linux', 08:47:41 INFO - 'TMOUT': '86400', 08:47:41 INFO - 'USER': 'cltbld', 08:47:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:41 INFO - '_': '/tools/buildbot/bin/python'} 08:47:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:47:41 INFO - Running setup.py (path:/tmp/pip-dYGsWG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:47:41 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)) 08:47:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:47:41 INFO - Running setup.py (path:/tmp/pip-km_zGH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:47:41 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)) 08:47:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:47:41 INFO - Running setup.py (path:/tmp/pip-OlCdt3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:47:41 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)) 08:47:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:47:41 INFO - Running setup.py (path:/tmp/pip-hfT65H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:47:41 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)) 08:47:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:47:41 INFO - Running setup.py (path:/tmp/pip-lgr8CD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:47:42 INFO - Running setup.py (path:/tmp/pip-G_2xpY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:47:42 INFO - Running setup.py (path:/tmp/pip-QAo6zh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:47:42 INFO - Running setup.py (path:/tmp/pip-7ORMy2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:47:42 INFO - Running setup.py (path:/tmp/pip-_v35bq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:47:42 INFO - Running setup.py (path:/tmp/pip-T85eaT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:47:42 INFO - Running setup.py (path:/tmp/pip-spYxq1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:47:42 INFO - Running setup.py (path:/tmp/pip-X6sjoB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:47:42 INFO - Running setup.py (path:/tmp/pip-Azy0ix-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:47:42 INFO - Running setup.py (path:/tmp/pip-pR4M0F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:47:42 INFO - Running setup.py (path:/tmp/pip-t6wt67-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:47:42 INFO - Running setup.py (path:/tmp/pip-2Als2K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:47:42 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)) 08:47:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:47:42 INFO - Running setup.py (path:/tmp/pip-eh1Kjc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:47:42 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)) 08:47:42 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)) 08:47:42 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)) 08:47:42 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)) 08:47:42 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)) 08:47:42 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)) 08:47:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:44 INFO - Downloading blessings-1.6.tar.gz 08:47:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:47:44 INFO - Installing collected packages: blessings 08:47:44 INFO - Running setup.py install for blessings 08:47:44 INFO - Successfully installed blessings 08:47:44 INFO - Cleaning up... 08:47:44 INFO - Return code: 0 08:47:44 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:47:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:47:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:47:45 INFO - Reading from file tmpfile_stdout 08:47:45 INFO - Current package versions: 08:47:45 INFO - argparse == 1.2.1 08:47:45 INFO - blessings == 1.6 08:47:45 INFO - blobuploader == 1.2.4 08:47:45 INFO - docopt == 0.6.1 08:47:45 INFO - manifestparser == 1.1 08:47:45 INFO - mozInstall == 1.12 08:47:45 INFO - mozcrash == 0.16 08:47:45 INFO - mozdebug == 0.1 08:47:45 INFO - mozdevice == 0.47 08:47:45 INFO - mozfile == 1.2 08:47:45 INFO - mozhttpd == 0.7 08:47:45 INFO - mozinfo == 0.9 08:47:45 INFO - mozleak == 0.1 08:47:45 INFO - mozlog == 3.1 08:47:45 INFO - moznetwork == 0.27 08:47:45 INFO - mozprocess == 0.22 08:47:45 INFO - mozprofile == 0.27 08:47:45 INFO - mozrunner == 6.11 08:47:45 INFO - mozscreenshot == 0.1 08:47:45 INFO - mozsystemmonitor == 0.0 08:47:45 INFO - moztest == 0.7 08:47:45 INFO - mozversion == 1.4 08:47:45 INFO - psutil == 3.1.1 08:47:45 INFO - requests == 1.2.3 08:47:45 INFO - wsgiref == 0.1.2 08:47:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:47:45 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')]} 08:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:45 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')]} 08:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:45 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:45 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 08:47:45 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 08:47:45 INFO - Using env: {'DISPLAY': ':0', 08:47:45 INFO - 'HOME': '/home/cltbld', 08:47:45 INFO - 'LANG': 'en_US.UTF-8', 08:47:45 INFO - 'LANGUAGE': 'en_US:en', 08:47:45 INFO - 'LOGNAME': 'cltbld', 08:47:45 INFO - 'MAIL': '/var/mail/cltbld', 08:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:45 INFO - 'MOZ_NO_REMOTE': '1', 08:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:45 INFO - 'NO_EM_RESTART': '1', 08:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:45 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:45 INFO - 'SHELL': '/bin/bash', 08:47:45 INFO - 'SHLVL': '1', 08:47:45 INFO - 'TERM': 'linux', 08:47:45 INFO - 'TMOUT': '86400', 08:47:45 INFO - 'USER': 'cltbld', 08:47:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:45 INFO - '_': '/tools/buildbot/bin/python'} 08:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 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)) 08:47:45 INFO - Cleaning up... 08:47:45 INFO - Return code: 0 08:47:45 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:47:45 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')]} 08:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:45 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')]} 08:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:45 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:45 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 08:47:45 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 08:47:45 INFO - Using env: {'DISPLAY': ':0', 08:47:45 INFO - 'HOME': '/home/cltbld', 08:47:45 INFO - 'LANG': 'en_US.UTF-8', 08:47:45 INFO - 'LANGUAGE': 'en_US:en', 08:47:45 INFO - 'LOGNAME': 'cltbld', 08:47:45 INFO - 'MAIL': '/var/mail/cltbld', 08:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:45 INFO - 'MOZ_NO_REMOTE': '1', 08:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:45 INFO - 'NO_EM_RESTART': '1', 08:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:45 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:45 INFO - 'SHELL': '/bin/bash', 08:47:45 INFO - 'SHLVL': '1', 08:47:45 INFO - 'TERM': 'linux', 08:47:45 INFO - 'TMOUT': '86400', 08:47:45 INFO - 'USER': 'cltbld', 08:47:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:45 INFO - '_': '/tools/buildbot/bin/python'} 08:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:45 INFO - Downloading/unpacking jsonschema 08:47:45 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 08:47:45 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 08:47:45 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 08:47:45 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 08:47:45 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 08:47:45 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 08:47:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:47:47 INFO - Downloading/unpacking functools32 (from jsonschema) 08:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:47 INFO - Downloading functools32-3.2.3-2.tar.gz 08:47:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:47:47 INFO - warning: no files found matching '*.txt' 08:47:47 INFO - no previously-included directories found matching 'build' 08:47:47 INFO - no previously-included directories found matching 'dist' 08:47:47 INFO - no previously-included directories found matching '.git*' 08:47:47 INFO - Installing collected packages: jsonschema, functools32 08:47:47 INFO - Running setup.py install for functools32 08:47:47 INFO - warning: no files found matching '*.txt' 08:47:47 INFO - no previously-included directories found matching 'build' 08:47:47 INFO - no previously-included directories found matching 'dist' 08:47:47 INFO - no previously-included directories found matching '.git*' 08:47:47 INFO - Successfully installed jsonschema functools32 08:47:47 INFO - Cleaning up... 08:47:47 INFO - Return code: 0 08:47:47 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:47 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 0x7f735c273f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe998a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf66600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', '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 08:47:47 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 08:47:47 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 08:47:47 INFO - Using env: {'DISPLAY': ':0', 08:47:47 INFO - 'HOME': '/home/cltbld', 08:47:47 INFO - 'LANG': 'en_US.UTF-8', 08:47:47 INFO - 'LANGUAGE': 'en_US:en', 08:47:47 INFO - 'LOGNAME': 'cltbld', 08:47:47 INFO - 'MAIL': '/var/mail/cltbld', 08:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:47 INFO - 'MOZ_NO_REMOTE': '1', 08:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:47:47 INFO - 'NO_EM_RESTART': '1', 08:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:47:47 INFO - 'PWD': '/builds/slave/test-pgo', 08:47:47 INFO - 'SHELL': '/bin/bash', 08:47:47 INFO - 'SHLVL': '1', 08:47:47 INFO - 'TERM': 'linux', 08:47:47 INFO - 'TMOUT': '86400', 08:47:47 INFO - 'USER': 'cltbld', 08:47:47 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:47 INFO - '_': '/tools/buildbot/bin/python'} 08:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:48 INFO - Downloading/unpacking flake8 08:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:47:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:47:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 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 08:47:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:47:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:47:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:47:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:47:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:47:50 INFO - no previously-included directories found matching 'docs/_build' 08:47:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:47:50 INFO - Running setup.py install for pep8 08:47:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:47:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:47:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:47:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:47:50 INFO - no previously-included directories found matching 'docs/_build' 08:47:50 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:47:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:47:50 INFO - Cleaning up... 08:47:50 INFO - Return code: 0 08:47:50 INFO - Running post-action listener: _resource_record_post_action 08:47:50 INFO - Running post-action listener: _start_resource_monitoring 08:47:50 INFO - Starting resource monitoring. 08:47:50 INFO - ##### 08:47:50 INFO - ##### Running install step. 08:47:50 INFO - ##### 08:47:50 INFO - Running pre-action listener: _resource_record_pre_action 08:47:50 INFO - Running main action method: install 08:47:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:47:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:47:51 INFO - Reading from file tmpfile_stdout 08:47:51 INFO - Detecting whether we're running mozinstall >=1.0... 08:47:51 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:47:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:47:51 INFO - Reading from file tmpfile_stdout 08:47:51 INFO - Output received: 08:47:51 INFO - Usage: mozinstall [options] installer 08:47:51 INFO - Options: 08:47:51 INFO - -h, --help show this help message and exit 08:47:51 INFO - -d DEST, --destination=DEST 08:47:51 INFO - Directory to install application into. [default: 08:47:51 INFO - "/builds/slave/test-pgo"] 08:47:51 INFO - --app=APP Application being installed. [default: firefox] 08:47:51 INFO - mkdir: /builds/slave/test-pgo/build/application 08:47:51 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'] 08:47:51 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 08:48:05 INFO - Reading from file tmpfile_stdout 08:48:05 INFO - Output received: 08:48:05 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:48:05 INFO - Running post-action listener: _resource_record_post_action 08:48:05 INFO - ##### 08:48:05 INFO - ##### Running run-tests step. 08:48:05 INFO - ##### 08:48:05 INFO - Running pre-action listener: _resource_record_pre_action 08:48:05 INFO - Running main action method: run_tests 08:48:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:48:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:48:05 INFO - Python 2.7.3 08:48:05 INFO - Return code: 0 08:48:05 INFO - grabbing minidump binary from tooltool 08:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:48:05 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 0xff5350>, '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 0xff6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xff66e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:48:05 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 08:48:05 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 08:48:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:48:05 INFO - Return code: 0 08:48:05 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:48:05 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:48:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:48:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:48:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:48:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:48:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:48:06 INFO - Return code: 0 08:48:06 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/1456410390/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:48:06 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/1456410390/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost 08:48:06 INFO - Using env: {'DISPLAY': ':0', 08:48:06 INFO - 'HOME': '/home/cltbld', 08:48:06 INFO - 'LANG': 'en_US.UTF-8', 08:48:06 INFO - 'LANGUAGE': 'en_US:en', 08:48:06 INFO - 'LOGNAME': 'cltbld', 08:48:06 INFO - 'MAIL': '/var/mail/cltbld', 08:48:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:48:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:06 INFO - 'MOZ_NO_REMOTE': '1', 08:48:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:48:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:06 INFO - 'NO_EM_RESTART': '1', 08:48:06 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:48:06 INFO - 'PWD': '/builds/slave/test-pgo', 08:48:06 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:48:06 INFO - 'SHELL': '/bin/bash', 08:48:06 INFO - 'SHLVL': '1', 08:48:06 INFO - 'TERM': 'linux', 08:48:06 INFO - 'TMOUT': '86400', 08:48:06 INFO - 'USER': 'cltbld', 08:48:06 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944', 08:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:06 INFO - '_': '/tools/buildbot/bin/python'} 08:48:06 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/1456410390/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost'] with output_timeout 3600 08:48:07 INFO - mozversion INFO | application_buildid: 20160225062630 08:48:07 INFO - mozversion INFO | application_changeset: 20afdf1337fca347bc69d0bc0619785898093e2a 08:48:07 INFO - mozversion INFO | application_display_name: Nightly 08:48:07 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:48:07 INFO - mozversion INFO | application_name: Firefox 08:48:07 INFO - mozversion INFO | application_remotingname: firefox 08:48:07 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:48:07 INFO - mozversion INFO | application_vendor: Mozilla 08:48:07 INFO - mozversion INFO | application_version: 45.0 08:48:07 INFO - mozversion INFO | platform_buildid: 20160225062630 08:48:07 INFO - mozversion INFO | platform_changeset: 20afdf1337fca347bc69d0bc0619785898093e2a 08:48:07 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:48:07 INFO - mozversion INFO | platform_version: 45.0 08:48:07 INFO - 2016-02-25 08:48:07,078 DEBUG : using testdate: 1456418887 08:48:07 INFO - 2016-02-25 08:48:07,078 DEBUG : actual date: 1456418887 08:48:07 INFO - 2016-02-25 08:48:07,087 INFO : starting webserver on 'localhost:43824' 08:48:07 INFO - 2016-02-25 08:48:07,088 INFO : Starting test suite talos-linux64-ix-041 08:48:07 INFO - 2016-02-25 08:48:07,088 INFO : Starting test dromaeo_css 08:48:07 INFO - 2016-02-25 08:48:07,088 DEBUG : operating with platform_type : linux_ 08:48:07 INFO - 2016-02-25 08:48:07,089 INFO : Initialising browser for dromaeo_css test... 08:48:07 INFO - 2016-02-25 08:48:07,100 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOMMVKv/profile http://localhost:43824/getInfo.html 08:48:11 INFO - 2016-02-25 08:48:11,258 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:48:11 INFO - 2016-02-25 08:48:11,258 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:48:11 INFO - 2016-02-25 08:48:11,258 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:48:11 INFO - 2016-02-25 08:48:11,258 DEBUG : BROWSER_OUTPUT: __metrics 08:48:11 INFO - 2016-02-25 08:48:11,407 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] 08:48:12 INFO - 2016-02-25 08:48:12,237 INFO : Browser initialized. 08:48:12 INFO - 2016-02-25 08:48:12,238 INFO : Running cycle 1/1 for dromaeo_css test... 08:48:12 INFO - 2016-02-25 08:48:12,238 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOMMVKv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:48:24 INFO - 2016-02-25 08:48:24,010 DEBUG : BROWSER_OUTPUT: RSS: Main: 135049216 08:48:24 INFO - 2016-02-25 08:48:24,011 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-25 08:49:14,885 DEBUG : BROWSER_OUTPUT: 1456418954880 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] 08:51:23 INFO - 2016-02-25 08:51:23,584 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43824/tests/dromaeo/cssquery-ext.html) 08:51:24 INFO - 2016-02-25 08:51:24,569 DEBUG : BROWSER_OUTPUT: RSS: Main: 223903744 08:51:24 INFO - 2016-02-25 08:51:24,569 DEBUG : BROWSER_OUTPUT: 08:54:26 INFO - 2016-02-25 08:54:26,555 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/dromaeo/cssquery-ext.html (next: http://localhost:43824/tests/dromaeo/cssquery-jquery.html) 08:54:27 INFO - 2016-02-25 08:54:27,121 DEBUG : BROWSER_OUTPUT: RSS: Main: 217731072 08:54:27 INFO - 2016-02-25 08:54:27,121 DEBUG : BROWSER_OUTPUT: 08:57:28 INFO - 2016-02-25 08:57:28,906 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43824/tests/dromaeo/cssquery-mootools.html) 08:57:29 INFO - 2016-02-25 08:57:29,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 222453760 08:57:29 INFO - 2016-02-25 08:57:29,468 DEBUG : BROWSER_OUTPUT: 09:00:31 INFO - 2016-02-25 09:00:31,147 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43824/tests/dromaeo/cssquery-prototype.html) 09:00:31 INFO - 2016-02-25 09:00:31,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 207360000 09:00:31 INFO - 2016-02-25 09:00:31,717 DEBUG : BROWSER_OUTPUT: 09:03:34 INFO - 2016-02-25 09:03:34,115 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43824/tests/dromaeo/cssquery-yui.html) 09:03:34 INFO - 2016-02-25 09:03:34,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 184553472 09:03:34 INFO - 2016-02-25 09:03:34,681 DEBUG : BROWSER_OUTPUT: 09:06:36 INFO - 2016-02-25 09:06:36,864 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/dromaeo/cssquery-yui.html (next: http://localhost:43824/tests/dromaeo/cssquery-dojo.html) 09:06:37 INFO - 2016-02-25 09:06:37,122 DEBUG : BROWSER_OUTPUT: RSS: Main: 217161728 09:06:37 INFO - 2016-02-25 09:06:37,122 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,122 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:06:37 INFO - 2016-02-25 09:06:37,122 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:06:37 INFO - 2016-02-25 09:06:37,122 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3301.2572171261463;3393.643667414923;3434.2272988577574;3436.570428696413;3450.2926899985496;5617.241672374228;5654.038813401721;5657.9717101414535;5669.09294512878;5687.203791469187;5660.462256085676;5693.629914055584;5699.458551437617;5700.059490184125;5716.971415142929;5052.166392545226;5091.592672586203;5148.794048238066;5181.300524429211;5245.632805703603;5038.899222015558;5175.327207463043;5191.143461328877;5197.688138711689;5200.427952925175;5168.302279192299;5209.661372010826;5231.424543300234;5239.790408383679;5240.6593571417925;5177.559907407837;5185.740712964355;5219.739013049332;5242.449542797989;5243.475652434764;4913.385826771653;5044.949550504484;5104.438511763703;5107;5163.199704045879;5400.189971504266;5492.368377636543;5506.174073888908;5510.669359838423;5512.503874651261;6043.486303664194;6091.604045737013;6092.08618707193;6096.1770161028135;6105.114758360013;6004.159417681528;6006.8498287542725;6081.787137450168;6144.170536977495;6147.47746441553;5734.369219385864;5738.139279108167;5740.0815869460685;5740.827775166752;5754.884902301972;6181.670940747671;6191.102290167901;6191.721372538246;6196.597221180609;6213.782517611862;5756.366799652035;5860.3260625027815;5973.49225315853;5998.580099393088;6133.080037994337;6116.512591648093;6120.750240493622;6125.207623589072;6126.681223142771;6127.719830079223;6081.665508397081;6087.908681369783;6100.23747031621;6113.510919126459;6131.049687298476;5872.647641141545;5920.407959204132;5990.251218597676;6033.879322413615;6094.055421409688;4636.513166117509;4675;4691.108689349013;4695.95304046962;4711.199096153662;4187.832486700559;4199.958000419957;4210.2842516772225;4212.726172798758;4222.641075508616;5562.276904002454;5594.776208951596;5689.0328644130595;5689.68706721126;5734;6079.604825686316;6139.201903752573;6141.57009009374;6151.477124444473;6156.538259630545;5693.914591281134;5749.626274292241;5753.712314384264;5758.74085666146;5788.623739456922;5131.281620573122;5200.115980283361;5242.9213561796605;5305.44292849253;5323.1749078892835;6258.061290806416;6258.311585725566;6261.780837670569;6264.060390941304;6264.780732674334;6121.632702037892;6122.5408094392205;6125.081237814364;6127.662978536223;6128.264608247039;6025.6083354581815;6050.909236361546;6136.417040381157;6146.293176284785;6148.415900489446;5985.730642121028;6018.247719035121;6030.396960303934;6040.6375617461335;6043.456088952015;6007.459328660441;6045.969770151121;6101.786437474844;6105.908411373922;6109.2974307955155;5847.766089356378;5911.527077833849;5929.110633405024;5938.168656388023;5946.643201407929;5951.970240148771;5959.493443057216;5973.970130149321;5976.432238937294;5980.880382392241;5652.3217213934595;5715.228444159985;5826.184334193301;5828.883422331614;5833.79581714638;5839.5912286141265;5913.29040515141;5937.851553711019;5942.10868369748;5958.285005799332;5886.440788125121;5906.232189815297;5910.231669882888;5913.881722365614;5917.0828521577505 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |1;ext.html;589.0839744197771;626.2078470734521;636.971336289867;671.8488340123475;704.144464475662;29677.554836677442;43101.35347969779;43721.562784372145;43731.562684373144;43798.12403751923;46871.76564117175;47514.52485475144;47578.52421475793;47586.52413475864;47647;8129.146439623843;8243.05204901435;8246.793830154264;9231.769205769859;9418.952905235465;10930.125589952784;11277.492512836941;11281.548738050467;11291.49188286525;11297.604583839568;11012.944935275313;11101.611443599475;11117.055050320732;11118.6108486203;11123.110151187926;10990.99999999996;11062.336259824437;11073.169512286611;11076.11391088711;11087.057600104;10824.18818588609;10910.454477276104;10943.06983906361;10947.34315941046;10955.890441095567;43996.3400548992;44318;44391;44455.33317000248;44604.330935036;3800.258949504848;3823.330917089498;3826.043489127718;3841.2317536492815;4358.193734159191;32817.17957051069;33224;33354.83322583372;33454.83272583621;33521.329573408395;4350.108227813291;4355.73865568067;4436.645068394553;4440.267355886275;4476.925997342435;306693;309328;309650.96919669636;310100;310909;60471.99999999912;61795.38204617986;68633.65683171552;69118;69247.96128058083;72189.63905180545;72788;73471.63264183752;73573.26426735664;73684.26315736774;16897.91551042237;17088.658226835392;17099.316027358767;17110.82889171117;17120.657586848192;12399.13206075566;12515.749685006249;12636.620901372973;12640.367981600883;12667.556635517712;21139.682904756442;21625.35123946284;22258;22418.663720044213;22423.32730018102;8147.592620368958;8418.663253469915;8507.532085735344;8561.1866872647;8572.485650860968;1068.834330678746;1105.9824961035865;1157.866845312783;1177.0818761366147;1202.4228370382266;30700.84649576738;30989.0703278902;31486.527702084488;34338;35281.47077793835;1140.8402823604702;1143.1255089856268;1151.3494875395374;1192.6183621241119;1215.9297773122935;13763.243021633907;15856.286467109008;15919.681606367807;16225.891219197825;16770.80071377811;449002;453425;459661;459950;461237;60852.69573652192;61284.38715612876;61294;61423;61524.69237653783;1200.0459634590522;1301.264785396248;1496.7829664698559;1498.0113125337443;1511.0329389190927;30422.847885760428;30837.845810770803;31181.688183118786;31394.686053139176;32044;54548.45451545434;56129;56162.438375617356;56567;56653;338031;343468;345973;346173;346391;46230.30654540252;47099.29351059668;47183.16900985952;47187.292190616485;47727.52272477231;50626.493735063654;50682.23976640427;50709.23936141035;50905;51176.48823511717;40579.39130913098;41885.37171942362;42511.3623295657;43732.34401483917;46259.3061104077;40943.795281023406;40948;40955.1808963825;41123.97190070272;43858;40025;40138;40178.1964360717;40308.1938361237;40962.79518602388;30092.69907300899;31700.5244921331;31702.207444814063;31745.841270794423;32290.83854580806;27200.727992719818;27507.17478475649;27809.16572502828;27855.303617409725;27889.16332510028 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |2;jquery.html;395.05778717758136;399.08410198594225;401.12554630904623;401.63651895034997;402.1494539049908;15542.922285388586;16034.198290085478;17694.823051769494;18089.819101808993;18116.18477168529;11354.375509346983;11669.474873630686;17879;18166.3641772538;18318.908405457954;5090.490950904908;5232.16285394337;5233.267342572043;5260.763265653036;5261.552768014723;4000.599940006005;4103.035786590149;4103.363978583325;4104.261232978063;5216.139337009389;6458.967705161491;6582.934170658304;7042.436605071608;7050.471214658895;7056.435485161175;6966.059581956421;6974.721011159573;7035.859282814315;7040.155181378216;7040.507164498487;6316.778912738032;6488.5133614978595;6488.740450381979;6751.189857217166;6766.864662706767;22909.656355154686;24267.27198184057;24339;24840.130595429073;24922.50154996908;2585.2761226856323;2630.579107342816;2731.4673638640475;2744;2748.051922086877;14509.637259068502;14782.113073215534;15416.3833446662;15513.844861551353;15558.611034724223;1992.481954691776;1996.7104769808298;1997.8201961823506;2006.3278801601482;2105.978940210609;73493.26506734917;75097.24902751012;76866.61566692186;77295;78756.60621696913;16226.269817857989;16943.15284235808;16963.74554381685;16966.660666786596;16970.660586788443;27252.182434526996;27312.726872731415;27513.31216719558;27656.170314890587;27718;10045.397276163454;10116.342437741523;10122.342047766871;10126.898731012743;10196.745081373025;8558.443701159405;8598.355123365773;8634.22291993711;8678.6962456315;8698.869516957251;6786.9209608034425;8339.12439193888;8345.749627511184;8356.415050946376;8374.2881855043;1921.8846869187894;1957.1877670766473;1981.128303546435;1985.394454691333;1988.711636812654;983.9704808855746;1046.7435478307864;1143.742657901982;1161.616666500053;1162.4013632979022;15263.160526170945;17046.23291951865;17685.557861053578;17929.282828686708;18391.632167356845;976.8632391465201;978.1978777402462;1000.4497526360473;1047.8323468245044;1068.1668298727006;9700.75748106303;10032.849507257397;10037.247206459399;10069.144122749502;10084.142847858015;601643;624095;675176;679438;703511;78094;79267.60366198247;81758.59120704359;85077;91532.54233728789;1213.387239444075;1219.9268043917393;1222.7554489102215;1226.7730469863104;1247.95008199671;15832.92083539575;15999.120048396984;16649.50051498457;16804.24380902838;17104.315827367263;38702.2259554813;38868.222635547696;39033.8048309768;39205.80397097996;41004.1799164021;52628;54905;55472.72263638817;55519.722401387735;55740.16389754238;10017.849732254168;10604.310719803188;10617.309874858109;10623.406489026707;10625.468726563486;12458.937705311415;12478.126531142732;18210.544736381697;18262.08689565543;18879.716804247673;15383;15687.058776472992;15695.921520392325;15698.215089245721;15877.682446351238;15172.696546069237;15219.771703424236;15311.38754449854;15554.766678500058;16018.119003454922;16055.438059668322;16178.757318639995;16299.511014669579;16312.429064982669;16433.424830130887;10218.335808172445;10442.321249119086;10528.631497897537;10547.156227501628;10576.947115264375;15140.318685658951;15164.31760570799;15227.390904363701;15326.846731532987;15567.76648350253 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1526.7786171005203;1584.9128297943616;1596;1734.5403468080954;1841.9815801841978;8250.381221408388;8571.400001999855;8594.65621375145;8599.742007739762;8636.568171591427;7344.596047217401;7539.472236943416;7572.091349038109;8103.311218546431;8428.578571071437;2368.810495160392;2382;2397.8081753459683;2399.3521749127726;2407.807375409963;2412.746661600533;2454.607262837946;2465.593177125781;2474.9133780317693;2479.9752002479927;2029.3607513633158;2073.3676228750223;2371.7509661485556;2462.630605409185;2466.506698660266;2427.5023620157826;2438.987805060963;2460.7662272084212;2461.089396923141;2496.6130249811217;2450.767177118171;2454.312792418126;2479.3925488255495;2480.6527086207943;2496.10140349474;8162.551059691715;8323.250907418302;8325.084240733515;8330;8331.708585169308;4997.575206107485;5088.847334579931;5094.286799848024;5099.107656160194;5101.566366858821;3363.4786608075788;3376.9155771105675;3411.300683359905;3542.752007359486;3570.4822800694014;1151.424287856072;1206.318430086998;1229.6372570091844;1233.6114124050985;1288.6971561682997;11551.422428878606;11702.648920532398;11723.4138293085;11724.003459705935;11727.999999999915;4482.5069242383315;4513.593776560126;4560.17916774977;4595.195840727859;4611.377464042378;10039.799204015879;10055.748606284902;10060.39637621745;10060.597576097021;10081.546330415149;5073.797048118107;5081.060003899291;5082.18685010404;5083.949160508421;5120.103981803244;4628.837990670378;4633.027064316464;4638.744869032168;4638.930416043762;4642.2108241598335;3880.495535580413;3970.3845903884944;3983.980080099581;3994.8801535953967;4026.879193624196;1225.7916154253799;1247.4885297028356;1275.4196840437764;1276.6106337566946;1285.6978610026636;1166.7258194324327;1180.4982882274999;1213.065939226808;1401.7827236778112;1419.7302512522795;7799.688012479551;7872.448928574945;7994.160613135653;8222.671093156207;8255.091939886608;1111.3220935229504;1153.8730739618636;1185.0637995983077;1186.128195776104;1198.526582000105;6280.497560195174;6287.717052732638;6337.7781777637565;6346.27017892939;6355.459785918156;10008.849867251996;10156.746081348025;10321.690349289533;10376.325538839952;10456.738581535523;9917.851232231522;10203.183745300357;10247.385156890461;10311.84532232017;10318.638847640446;7968.083670377865;7968.243016913384;7972.123066462685;7987.400944929153;8074.111847696749;7623.9618801905635;7642.121156067124;7746.341560967381;7753.728619498291;7754.302112809874;8969.237614802814;9005.279577633904;9255.444673319622;9264.305177111879;9283.071692830934;9247.39891907024;9251.722448326562;9476.147146756824;9499.715008549754;9517.524123793644;7135.464840136907;7137.179224389261;7217.927820721726;7219.205887352282;7228.566286022856;8071.677132914852;8135.552544609866;8189.262966333059;8191.098979112174;8220.63007164691;6435.517336199898;7210.242924492853;7382.52013619113;7526.209747976383;7536.171021187774;6940.236573976757;7385.593792341473;7460.776176714708;7648.196939321291;7653.311201991847;7705.653245603849;7811.062672479339;7813.882791758242;7813.960930195312;7832.099308579586;6835.555688880207;7039.084918960502;7046.295370462913;7123.643817809192;7130.5365151265005;7134.857302854018;7231.602261875647;7241.637918104179;7244.963775181091;7246.927530724625 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |4;prototype.html;355.6781113092651;368.3553780883455;369.98705045323385;378.5797764481425;378.85224762342676;6085.726142323595;6118.204633397658;6119.173911521943;6127.908081378783;6163.075538669202;5788.247527821377;5889.116632505126;5942.494887934531;5959.284885813709;6007.519398448125;4462.1521910837;4618.49196588375;4685.976570117145;4737.005228901935;4737.881552961169;4731.266653668669;4740.051989602076;4741.359916411292;4750.619950403977;4761.166795810736;4518.096380723852;4676.181668208067;4691.390119284488;4701;4701.717896926179;4587.449506059261;4610.654200934927;4682.531746825324;4705.529447055302;4730.8344207952905;4217.683673724483;4222.408862759216;4222.704410691253;4239.448871646666;4249.893752656178;5755.856103597402;5761.53907687384;5765.423457654243;5777.88444231113;5784.1034639630925;5303.5226829585135;5472.097103977839;5527.2538207342295;5528.502434780889;5539.501444869981;6431.517636177258;6434.292227854932;6436.452901503378;6439;6443.097966284724;4575.702579332333;4583.72497650142;4590.908181836345;4591.862244132682;4600.516945720685;6526.118973938553;6582.17722784652;6622.6357550334715;6653.201615806086;6653.53425260232;4701.2242979908615;4719.480857105716;4724.173269677862;4732.02993386355;4736.384270044941;4776.259679749644;4785.090832741768;4785.114753770564;4789.784459699252;4790.281457781361;5433.8369848904595;5435.10320797064;5446.809361672402;5447.155690867922;5451.700156491352;6335.239771227482;6470.838229044218;6471.579347342502;6478.870422591522;6490.156279683611;1650.9009459432473;1686.207482483231;1693.7882764654419;1693.90683512408;1697.2786565709523;1129.7796929598649;1147.0708725932022;1148.9827652585218;1149.0290704354807;1170.4615876696644;6222.81331560054;6230.158928544589;6231.563790534619;6235.469985051231;6240.656763878031;6637.1703537057865;6643.335666433415;6643.667816609151;6658.600483970978;6727.9327206728285;5885.499732522783;6078.3617720138745;6236.469900058455;6245.750169993149;6253.46845518127;3551.1122219445137;3645.708343332527;3654.488371627974;3656.835442405098;3658.926821463556;6922.342377474132;6931.09895713554;6932.6533673316135;6935.133108361455;6942.791716248521;6815.556988795713;6932.44540436774;6939.30606939302;6942.097527321416;6945.47908906834;6898;6902.482313826483;6903.620300883399;6908.5509441886115;6917.169939607279;6729.52893297484;6736.494762892705;6755.898661519983;6758.763443279461;6774.525783195129;6505.577137486048;6654.068430419842;6654.800355989328;6660.800175994728;6668.799936001928;6903.206131294965;6908.412784913338;6913.2049814272;6919.481038922202;6925.445964322943;4696.788644510937;4730.0539892022425;4734.668573199843;4735.02931898953;4735.266033764772;6649.4680425564875;6755.628440435823;6761.560498567577;6761.898571521333;6773.729050838108;6551.0173473979285;6560.376764207393;6574.506911981525;6579;6581.5392922496885;6570.868582628225;6577.3422657733845;6577.736890524517;6579.276279609335;6582.539222254396;6261.5616906816085;6351.110844481684;6374.521910856797;6380.904286435614;6381.106645069601;6356.141920840627;6474.935250647433;6495.350464953467;6551.279359270572;6608.273089960005;6432.903506447501;6448.613083215022;6453;6465;6469.676516174079 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: |5;yui.html;826.723047778994;843.6962693430368;929.7164364868714;931.3108299858104;946.0161432110606;14691.926540367296;14955.775663365059;14960.476383326586;14982.32579533921;15007.699846003086;12596.307203103826;12683.61949141525;12685.3657317134;12690.873091269084;12741.681457963556;701.8736627407064;713.1192976673804;714.9642517874112;855.315747402079;868.7914900423884;865.2904618213067;887.8978917424482;891;891.8795962545038;892.281713220857;917.8806755121825;931.0503286647615;933.4119504712056;933.6125507914207;934.2852717670987;731.7548621211873;746.3767753925479;901.3465237702678;927.0775578299601;928.1321963963682;682.2154522299346;684.2712511175582;684.8869936460478;685.25649670108;685.4036987820627;12892.355382230944;12937.547185848543;13027.804582931263;13053.086283960127;13058.869411305861;13028.153170044012;13418.194908305533;13933.094348867291;14166.858331416657;14397.13617182962;11154.10767138627;11251.606193783178;11346.886531134665;11446;11619.593314234045;3900.3369427197445;3966.3852102924093;3968.4840970673918;3975.204959008181;3977.2840888640035;45524;46624.300635490494;46735.766321168514;49157.77105572353;49227.50772492265;10913.618023369145;10919.454027298603;11214.943925280322;11361.14791390649;11662.533498660128;19288.324908628132;19418.514537136685;19427.125779339964;19455.8054419454;19520.31678891232;7970.721024764105;8276.668933242603;8412.074671786098;8441.493510389397;8520.488770673779;4406.911861762746;4548.840790572381;4587.082583483317;4607.470140933769;4671.859844204611;3394.6096198936943;3417.6582341765625;3422.178677117524;3423.9828800856335;3433.073070271063;602.1509671363444;614.8344962876458;623.3050149083739;624.2290770897937;634.8285962790021;696.923338432772;698.448225901532;741.7626359564995;750.2909750285927;753.9019927409402;9330.113639204264;9439.575219115157;9469.526523673927;9561.952190239144;9612.615495380247;735.1398863329916;747.7868807389867;752.7139686918936;770.3413581387945;791.5488171742052;4061.669431066556;4463.107378524308;4477.8432754854075;4484.910301793946;4513.255312873404;57760.4223957755;58493;58513.41486585165;58749.706251468466;58896.41103588909;50993.74503127461;53933;53991.73004134954;54891.17663235054;54968.45031549713;46878;50169;50260.999999999265;50497;51297.743511282206;9280.118388753057;9342.346035777431;9413.388129771543;9485.478298693639;9539.570719317786;36260.81869590635;36380.27239455249;37363.439548406255;37664;37823.05442363963;43727;44764;47340;47492;47889.042219156116;12075;13400.262985535888;13471.259080750653;13673.384697688827;13790.103643263557;13507.257100859548;13661.385237664528;13765;14141.929290353482;14283.928580357031;11282.94358528202;11650.592229271935;11658.825117623412;11832.881671183179;11841.703957401134;12815.102942794289;12932.353382331034;12967.675808104495;13129.277889716157;13796.172229666252;12424.56514022005;12438.067144963983;12440.688982775502;12562.748745024866;13072.150310229805;10310.484475776031;10474.738131546772;10530.947345263225;10540.525676344736;10951.397673127736;10218.649710566462;10531.631392901212;10559;10598.841017384899;10612.84080738805 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,734 09:06:37 INFO - 2016-02-25 09:06:37,123 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:06:37 INFO - 2016-02-25 09:06:37,124 DEBUG : BROWSER_OUTPUT: __startTimestamp1456419997115__endTimestamp 09:06:37 INFO - 2016-02-25 09:06:37,124 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,124 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:06:37 INFO - 2016-02-25 09:06:37,124 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:06:37 INFO - 2016-02-25 09:06:37,124 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,124 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5637.12 Median:5847.77 stddev:618.38 (10.6%) stddev-sans-first:592.51 09:06:37 INFO - 2016-02-25 09:06:37,125 DEBUG : BROWSER_OUTPUT: Values: 3301.3 3393.6 3434.2 3436.6 3450.3 5617.2 5654.0 5658.0 5669.1 5687.2 5660.5 5693.6 5699.5 5700.1 5717.0 5052.2 5091.6 5148.8 5181.3 5245.6 5038.9 5175.3 5191.1 5197.7 5200.4 5168.3 5209.7 5231.4 5239.8 5240.7 5177.6 5185.7 5219.7 5242.4 5243.5 4913.4 5044.9 5104.4 5107.0 5163.2 5400.2 5492.4 5506.2 5510.7 5512.5 6043.5 6091.6 6092.1 6096.2 6105.1 6004.2 6006.8 6081.8 6144.2 6147.5 5734.4 5738.1 5740.1 5740.8 5754.9 6181.7 6191.1 6191.7 6196.6 6213.8 5756.4 5860.3 5973.5 5998.6 6133.1 6116.5 6120.8 6125.2 6126.7 6127.7 6081.7 6087.9 6100.2 6113.5 6131.0 5872.6 5920.4 5990.3 6033.9 6094.1 4636.5 4675.0 4691.1 4696.0 4711.2 4187.8 4200.0 4210.3 4212.7 4222.6 5562.3 5594.8 5689.0 5689.7 5734.0 6079.6 6139.2 6141.6 6151.5 6156.5 5693.9 5749.6 5753.7 5758.7 5788.6 5131.3 5200.1 5242.9 5305.4 5323.2 6258.1 6258.3 6261.8 6264.1 6264.8 6121.6 6122.5 6125.1 6127.7 6128.3 6025.6 6050.9 6136.4 6146.3 6148.4 5985.7 6018.2 6030.4 6040.6 6043.5 6007.5 6046.0 6101.8 6105.9 6109.3 5847.8 5911.5 5929.1 5938.2 5946.6 5952.0 5959.5 5974.0 5976.4 5980.9 5652.3 5715.2 5826.2 5828.9 5833.8 5839.6 5913.3 5937.9 5942.1 5958.3 5886.4 5906.2 5910.2 5913.9 5917.1 09:06:37 INFO - 2016-02-25 09:06:37,125 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,125 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56089.74 Median:19540.86 stddev:98680.27 (505.0%) stddev-sans-first:98869.11 09:06:37 INFO - 2016-02-25 09:06:37,125 DEBUG : BROWSER_OUTPUT: Values: 589.1 626.2 637.0 671.8 704.1 29677.6 43101.4 43721.6 43731.6 43798.1 46871.8 47514.5 47578.5 47586.5 47647.0 8129.1 8243.1 8246.8 9231.8 9419.0 10930.1 11277.5 11281.5 11291.5 11297.6 11012.9 11101.6 11117.1 11118.6 11123.1 10991.0 11062.3 11073.2 11076.1 11087.1 10824.2 10910.5 10943.1 10947.3 10955.9 43996.3 44318.0 44391.0 44455.3 44604.3 3800.3 3823.3 3826.0 3841.2 4358.2 32817.2 33224.0 33354.8 33454.8 33521.3 4350.1 4355.7 4436.6 4440.3 4476.9 306693.0 309328.0 309651.0 310100.0 310909.0 60472.0 61795.4 68633.7 69118.0 69248.0 72189.6 72788.0 73471.6 73573.3 73684.3 16897.9 17088.7 17099.3 17110.8 17120.7 12399.1 12515.7 12636.6 12640.4 12667.6 21139.7 21625.4 22258.0 22418.7 22423.3 8147.6 8418.7 8507.5 8561.2 8572.5 1068.8 1106.0 1157.9 1177.1 1202.4 30700.8 30989.1 31486.5 34338.0 35281.5 1140.8 1143.1 1151.3 1192.6 1215.9 13763.2 15856.3 15919.7 16225.9 16770.8 449002.0 453425.0 459661.0 459950.0 461237.0 60852.7 61284.4 61294.0 61423.0 61524.7 1200.0 1301.3 1496.8 1498.0 1511.0 30422.8 30837.8 31181.7 31394.7 32044.0 54548.5 56129.0 56162.4 56567.0 56653.0 338031.0 343468.0 345973.0 346173.0 346391.0 46230.3 47099.3 47183.2 47187.3 47727.5 50626.5 50682.2 50709.2 50905.0 51176.5 40579.4 41885.4 42511.4 43732.3 46259.3 40943.8 40948.0 40955.2 41124.0 43858.0 40025.0 40138.0 40178.2 40308.2 40962.8 30092.7 31700.5 31702.2 31745.8 32290.8 27200.7 27507.2 27809.2 27855.3 27889.2 09:06:37 INFO - 2016-02-25 09:06:37,125 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,126 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:34368.85 Median:1990.60 stddev:107350.48 (5392.9%) stddev-sans-first:107621.31 09:06:37 INFO - 2016-02-25 09:06:37,126 DEBUG : BROWSER_OUTPUT: Values: 395.1 399.1 401.1 401.6 402.1 15542.9 16034.2 17694.8 18089.8 18116.2 11354.4 11669.5 17879.0 18166.4 18318.9 5090.5 5232.2 5233.3 5260.8 5261.6 4000.6 4103.0 4103.4 4104.3 5216.1 6459.0 6582.9 7042.4 7050.5 7056.4 6966.1 6974.7 7035.9 7040.2 7040.5 6316.8 6488.5 6488.7 6751.2 6766.9 22909.7 24267.3 24339.0 24840.1 24922.5 2585.3 2630.6 2731.5 2744.0 2748.1 14509.6 14782.1 15416.4 15513.8 15558.6 1992.5 1996.7 1997.8 2006.3 2106.0 73493.3 75097.2 76866.6 77295.0 78756.6 16226.3 16943.2 16963.7 16966.7 16970.7 27252.2 27312.7 27513.3 27656.2 27718.0 10045.4 10116.3 10122.3 10126.9 10196.7 8558.4 8598.4 8634.2 8678.7 8698.9 6786.9 8339.1 8345.7 8356.4 8374.3 1921.9 1957.2 1981.1 1985.4 1988.7 984.0 1046.7 1143.7 1161.6 1162.4 15263.2 17046.2 17685.6 17929.3 18391.6 976.9 978.2 1000.4 1047.8 1068.2 9700.8 10032.8 10037.2 10069.1 10084.1 601643.0 624095.0 675176.0 679438.0 703511.0 78094.0 79267.6 81758.6 85077.0 91532.5 1213.4 1219.9 1222.8 1226.8 1248.0 15832.9 15999.1 16649.5 16804.2 17104.3 38702.2 38868.2 39033.8 39205.8 41004.2 52628.0 54905.0 55472.7 55519.7 55740.2 10017.8 10604.3 10617.3 10623.4 10625.5 12458.9 12478.1 18210.5 18262.1 18879.7 15383.0 15687.1 15695.9 15698.2 15877.7 15172.7 15219.8 15311.4 15554.8 16018.1 16055.4 16178.8 16299.5 16312.4 16433.4 10218.3 10442.3 10528.6 10547.2 10576.9 15140.3 15164.3 15227.4 15326.8 15567.8 09:06:37 INFO - 2016-02-25 09:06:37,126 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,126 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5913.63 Median:5077.43 stddev:3074.19 (60.5%) stddev-sans-first:3065.13 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: Values: 1526.8 1584.9 1596.0 1734.5 1842.0 8250.4 8571.4 8594.7 8599.7 8636.6 7344.6 7539.5 7572.1 8103.3 8428.6 2368.8 2382.0 2397.8 2399.4 2407.8 2412.7 2454.6 2465.6 2474.9 2480.0 2029.4 2073.4 2371.8 2462.6 2466.5 2427.5 2439.0 2460.8 2461.1 2496.6 2450.8 2454.3 2479.4 2480.7 2496.1 8162.6 8323.3 8325.1 8330.0 8331.7 4997.6 5088.8 5094.3 5099.1 5101.6 3363.5 3376.9 3411.3 3542.8 3570.5 1151.4 1206.3 1229.6 1233.6 1288.7 11551.4 11702.6 11723.4 11724.0 11728.0 4482.5 4513.6 4560.2 4595.2 4611.4 10039.8 10055.7 10060.4 10060.6 10081.5 5073.8 5081.1 5082.2 5083.9 5120.1 4628.8 4633.0 4638.7 4638.9 4642.2 3880.5 3970.4 3984.0 3994.9 4026.9 1225.8 1247.5 1275.4 1276.6 1285.7 1166.7 1180.5 1213.1 1401.8 1419.7 7799.7 7872.4 7994.2 8222.7 8255.1 1111.3 1153.9 1185.1 1186.1 1198.5 6280.5 6287.7 6337.8 6346.3 6355.5 10008.8 10156.7 10321.7 10376.3 10456.7 9917.9 10203.2 10247.4 10311.8 10318.6 7968.1 7968.2 7972.1 7987.4 8074.1 7624.0 7642.1 7746.3 7753.7 7754.3 8969.2 9005.3 9255.4 9264.3 9283.1 9247.4 9251.7 9476.1 9499.7 9517.5 7135.5 7137.2 7217.9 7219.2 7228.6 8071.7 8135.6 8189.3 8191.1 8220.6 6435.5 7210.2 7382.5 7526.2 7536.2 6940.2 7385.6 7460.8 7648.2 7653.3 7705.7 7811.1 7813.9 7814.0 7832.1 6835.6 7039.1 7046.3 7123.6 7130.5 7134.9 7231.6 7241.6 7245.0 7246.9 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5447.07 Median:6145.49 stddev:1615.30 (26.3%) stddev-sans-first:1573.98 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: Values: 355.7 368.4 370.0 378.6 378.9 6085.7 6118.2 6119.2 6127.9 6163.1 5788.2 5889.1 5942.5 5959.3 6007.5 4462.2 4618.5 4686.0 4737.0 4737.9 4731.3 4740.1 4741.4 4750.6 4761.2 4518.1 4676.2 4691.4 4701.0 4701.7 4587.4 4610.7 4682.5 4705.5 4730.8 4217.7 4222.4 4222.7 4239.4 4249.9 5755.9 5761.5 5765.4 5777.9 5784.1 5303.5 5472.1 5527.3 5528.5 5539.5 6431.5 6434.3 6436.5 6439.0 6443.1 4575.7 4583.7 4590.9 4591.9 4600.5 6526.1 6582.2 6622.6 6653.2 6653.5 4701.2 4719.5 4724.2 4732.0 4736.4 4776.3 4785.1 4785.1 4789.8 4790.3 5433.8 5435.1 5446.8 5447.2 5451.7 6335.2 6470.8 6471.6 6478.9 6490.2 1650.9 1686.2 1693.8 1693.9 1697.3 1129.8 1147.1 1149.0 1149.0 1170.5 6222.8 6230.2 6231.6 6235.5 6240.7 6637.2 6643.3 6643.7 6658.6 6727.9 5885.5 6078.4 6236.5 6245.8 6253.5 3551.1 3645.7 3654.5 3656.8 3658.9 6922.3 6931.1 6932.7 6935.1 6942.8 6815.6 6932.4 6939.3 6942.1 6945.5 6898.0 6902.5 6903.6 6908.6 6917.2 6729.5 6736.5 6755.9 6758.8 6774.5 6505.6 6654.1 6654.8 6660.8 6668.8 6903.2 6908.4 6913.2 6919.5 6925.4 4696.8 4730.1 4734.7 4735.0 4735.3 6649.5 6755.6 6761.6 6761.9 6773.7 6551.0 6560.4 6574.5 6579.0 6581.5 6570.9 6577.3 6577.7 6579.3 6582.5 6261.6 6351.1 6374.5 6380.9 6381.1 6356.1 6474.9 6495.4 6551.3 6608.3 6432.9 6448.6 6453.0 6465.0 6469.7 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14597.35 Median:4481.38 stddev:16419.42 (366.4%) stddev-sans-first:16432.92 09:06:37 INFO - 2016-02-25 09:06:37,127 DEBUG : BROWSER_OUTPUT: Values: 826.7 843.7 929.7 931.3 946.0 14691.9 14955.8 14960.5 14982.3 15007.7 12596.3 12683.6 12685.4 12690.9 12741.7 701.9 713.1 715.0 855.3 868.8 865.3 887.9 891.0 891.9 892.3 917.9 931.1 933.4 933.6 934.3 731.8 746.4 901.3 927.1 928.1 682.2 684.3 684.9 685.3 685.4 12892.4 12937.5 13027.8 13053.1 13058.9 13028.2 13418.2 13933.1 14166.9 14397.1 11154.1 11251.6 11346.9 11446.0 11619.6 3900.3 3966.4 3968.5 3975.2 3977.3 45524.0 46624.3 46735.8 49157.8 49227.5 10913.6 10919.5 11214.9 11361.1 11662.5 19288.3 19418.5 19427.1 19455.8 19520.3 7970.7 8276.7 8412.1 8441.5 8520.5 4406.9 4548.8 4587.1 4607.5 4671.9 3394.6 3417.7 3422.2 3424.0 3433.1 602.2 614.8 623.3 624.2 634.8 696.9 698.4 741.8 750.3 753.9 9330.1 9439.6 9469.5 9562.0 9612.6 735.1 747.8 752.7 770.3 791.5 4061.7 4463.1 4477.8 4484.9 4513.3 57760.4 58493.0 58513.4 58749.7 58896.4 50993.7 53933.0 53991.7 54891.2 54968.5 46878.0 50169.0 50261.0 50497.0 51297.7 9280.1 9342.3 9413.4 9485.5 9539.6 36260.8 36380.3 37363.4 37664.0 37823.1 43727.0 44764.0 47340.0 47492.0 47889.0 12075.0 13400.3 13471.3 13673.4 13790.1 13507.3 13661.4 13765.0 14141.9 14283.9 11282.9 11650.6 11658.8 11832.9 11841.7 12815.1 12932.4 12967.7 13129.3 13796.2 12424.6 12438.1 12440.7 12562.7 13072.2 10310.5 10474.7 10530.9 10540.5 10951.4 10218.6 10531.6 10559.0 10598.8 10612.8 09:06:37 INFO - 2016-02-25 09:06:37,128 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:06:37 INFO - 2016-02-25 09:06:37,128 DEBUG : BROWSER_OUTPUT: 09:06:37 INFO - 2016-02-25 09:06:37,709 INFO : Browser exited with error code: 0 09:06:37 INFO - 2016-02-25 09:06:37,728 INFO : Completed test dromaeo_css (00:18:30) 09:06:37 INFO - 2016-02-25 09:06:37,728 INFO : Starting test kraken 09:06:37 INFO - 2016-02-25 09:06:37,728 DEBUG : operating with platform_type : linux_ 09:06:37 INFO - 2016-02-25 09:06:37,728 INFO : Initialising browser for kraken test... 09:06:37 INFO - 2016-02-25 09:06:37,734 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCCsR8R/profile http://localhost:43824/getInfo.html 09:06:41 INFO - 2016-02-25 09:06:41,437 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:06:41 INFO - 2016-02-25 09:06:41,438 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:06:41 INFO - 2016-02-25 09:06:41,438 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:06:41 INFO - 2016-02-25 09:06:41,438 DEBUG : BROWSER_OUTPUT: __metrics 09:06:41 INFO - 2016-02-25 09:06:41,728 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:42 INFO - 2016-02-25 09:06:42,653 INFO : Browser initialized. 09:06:42 INFO - 2016-02-25 09:06:42,654 INFO : Running cycle 1/1 for kraken test... 09:06:42 INFO - 2016-02-25 09:06:42,654 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCCsR8R/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:06:54 INFO - 2016-02-25 09:06:54,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 132071424 09:06:54 INFO - 2016-02-25 09:06:54,444 DEBUG : BROWSER_OUTPUT: 09:06:56 INFO - 2016-02-25 09:06:56,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43824/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:57 INFO - 2016-02-25 09:06:57,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 162914304 09:06:57 INFO - 2016-02-25 09:06:57,447 DEBUG : BROWSER_OUTPUT: 09:06:59 INFO - 2016-02-25 09:06:59,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43824/tests/kraken/driver.html?testName=audio-dft) 09:07:00 INFO - 2016-02-25 09:07:00,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 162172928 09:07:00 INFO - 2016-02-25 09:07:00,551 DEBUG : BROWSER_OUTPUT: 09:07:03 INFO - 2016-02-25 09:07:03,330 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43824/tests/kraken/driver.html?testName=audio-fft) 09:07:03 INFO - 2016-02-25 09:07:03,891 DEBUG : BROWSER_OUTPUT: RSS: Main: 158859264 09:07:03 INFO - 2016-02-25 09:07:03,891 DEBUG : BROWSER_OUTPUT: 09:07:05 INFO - 2016-02-25 09:07:05,872 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43824/tests/kraken/driver.html?testName=audio-oscillator) 09:07:06 INFO - 2016-02-25 09:07:06,442 DEBUG : BROWSER_OUTPUT: RSS: Main: 159567872 09:07:06 INFO - 2016-02-25 09:07:06,442 DEBUG : BROWSER_OUTPUT: 09:07:08 INFO - 2016-02-25 09:07:08,637 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43824/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:07:09 INFO - 2016-02-25 09:07:09,203 DEBUG : BROWSER_OUTPUT: RSS: Main: 157769728 09:07:09 INFO - 2016-02-25 09:07:09,203 DEBUG : BROWSER_OUTPUT: 09:07:12 INFO - 2016-02-25 09:07:12,087 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43824/tests/kraken/driver.html?testName=imaging-darkroom) 09:07:12 INFO - 2016-02-25 09:07:12,653 DEBUG : BROWSER_OUTPUT: RSS: Main: 159162368 09:07:12 INFO - 2016-02-25 09:07:12,654 DEBUG : BROWSER_OUTPUT: 09:07:16 INFO - 2016-02-25 09:07:16,010 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43824/tests/kraken/driver.html?testName=imaging-desaturate) 09:07:16 INFO - 2016-02-25 09:07:16,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 159756288 09:07:16 INFO - 2016-02-25 09:07:16,584 DEBUG : BROWSER_OUTPUT: 09:07:19 INFO - 2016-02-25 09:07:19,294 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43824/tests/kraken/driver.html?testName=json-parse-financial) 09:07:19 INFO - 2016-02-25 09:07:19,862 DEBUG : BROWSER_OUTPUT: RSS: Main: 158879744 09:07:19 INFO - 2016-02-25 09:07:19,862 DEBUG : BROWSER_OUTPUT: 09:07:21 INFO - 2016-02-25 09:07:21,399 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43824/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:07:21 INFO - 2016-02-25 09:07:21,965 DEBUG : BROWSER_OUTPUT: RSS: Main: 166383616 09:07:21 INFO - 2016-02-25 09:07:21,966 DEBUG : BROWSER_OUTPUT: 09:07:23 INFO - 2016-02-25 09:07:23,744 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:07:24 INFO - 2016-02-25 09:07:24,338 DEBUG : BROWSER_OUTPUT: RSS: Main: 163201024 09:07:24 INFO - 2016-02-25 09:07:24,339 DEBUG : BROWSER_OUTPUT: 09:07:26 INFO - 2016-02-25 09:07:26,291 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:07:26 INFO - 2016-02-25 09:07:26,860 DEBUG : BROWSER_OUTPUT: RSS: Main: 168558592 09:07:26 INFO - 2016-02-25 09:07:26,861 DEBUG : BROWSER_OUTPUT: 09:07:29 INFO - 2016-02-25 09:07:29,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:07:29 INFO - 2016-02-25 09:07:29,829 DEBUG : BROWSER_OUTPUT: RSS: Main: 173584384 09:07:29 INFO - 2016-02-25 09:07:29,830 DEBUG : BROWSER_OUTPUT: 09:07:32 INFO - 2016-02-25 09:07:32,310 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:32 INFO - 2016-02-25 09:07:32,879 DEBUG : BROWSER_OUTPUT: RSS: Main: 171941888 09:07:32 INFO - 2016-02-25 09:07:32,880 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43824/tests/kraken/driver.html?testName=ai-astar) 09:07:34 INFO - 2016-02-25 09:07:34,763 DEBUG : BROWSER_OUTPUT: RSS: Main: 229810176 09:07:34 INFO - 2016-02-25 09:07:34,763 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,764 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;103;109;111;110;100;110;108;109 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;121;161;158;125;157;147;124;158;122 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;159;160;161;160;160;160;165;160;160 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;72;71;158;71;72;71;70;154;73 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;132;92;92;94;137;92;93;145 09:07:34 INFO - 2016-02-25 09:07:34,765 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;109;109;109;108;109;109;110 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;145;146;145;145;145;146 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;57;56;58;57;56;56;59;56;55 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;64;65;65;64;64;64;65 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;80;78;80;79;78;84;79;79;79 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;120;114;121;115;123;123;112;118;119 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;154;141;144;142;139;144;145;141;130 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;62;63;63;64;63;64;62;63;63 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,908 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: __startTimestamp1456420054763__endTimestamp 09:07:34 INFO - 2016-02-25 09:07:34,766 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.80 Median:109.50 stddev:3.52 (3.2%) stddev-sans-first:3.73 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 103.0 109.0 111.0 110.0 100.0 110.0 108.0 109.0 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.40 Median:154.00 stddev:17.18 (11.2%) stddev-sans-first:17.94 09:07:34 INFO - 2016-02-25 09:07:34,768 DEBUG : BROWSER_OUTPUT: Values: 151.0 121.0 161.0 158.0 125.0 157.0 147.0 124.0 158.0 122.0 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:160.00 stddev:1.65 (1.0%) stddev-sans-first:1.74 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: Values: 160.0 159.0 160.0 161.0 160.0 160.0 160.0 165.0 160.0 160.0 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.20 Median:71.00 stddev:35.76 (50.4%) stddev-sans-first:37.32 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 71.0 158.0 71.0 72.0 71.0 70.0 154.0 73.0 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:22.17 (24.1%) stddev-sans-first:22.91 09:07:34 INFO - 2016-02-25 09:07:34,769 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 132.0 92.0 92.0 94.0 137.0 92.0 93.0 145.0 09:07:34 INFO - 2016-02-25 09:07:34,770 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,770 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 09:07:34 INFO - 2016-02-25 09:07:34,770 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 109.0 109.0 109.0 108.0 109.0 109.0 110.0 09:07:34 INFO - 2016-02-25 09:07:34,770 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,770 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:07:34 INFO - 2016-02-25 09:07:34,770 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 146.0 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.60 Median:56.50 stddev:1.17 (2.1%) stddev-sans-first:1.22 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: Values: 56.0 57.0 56.0 58.0 57.0 56.0 56.0 59.0 56.0 55.0 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,771 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 65.0 65.0 64.0 64.0 64.0 65.0 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.00 stddev:1.89 (2.4%) stddev-sans-first:1.81 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: Values: 77.0 80.0 78.0 80.0 79.0 78.0 84.0 79.0 79.0 79.0 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.00 Median:120.50 stddev:4.27 (3.5%) stddev-sans-first:3.94 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: Values: 125.0 120.0 114.0 121.0 115.0 123.0 123.0 112.0 118.0 119.0 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,773 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.10 Median:143.00 stddev:5.93 (4.1%) stddev-sans-first:6.28 09:07:34 INFO - 2016-02-25 09:07:34,774 DEBUG : BROWSER_OUTPUT: Values: 141.0 154.0 141.0 144.0 142.0 139.0 144.0 145.0 141.0 130.0 09:07:34 INFO - 2016-02-25 09:07:34,774 DEBUG : BROWSER_OUTPUT: 09:07:34 INFO - 2016-02-25 09:07:34,774 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.00 stddev:1.16 (1.8%) stddev-sans-first:0.71 09:07:34 INFO - 2016-02-25 09:07:34,774 DEBUG : BROWSER_OUTPUT: Values: 66.0 62.0 63.0 63.0 64.0 63.0 64.0 62.0 63.0 63.0 09:07:34 INFO - 2016-02-25 09:07:34,774 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:07:34 INFO - 2016-02-25 09:07:34,774 DEBUG : BROWSER_OUTPUT: 09:07:35 INFO - 2016-02-25 09:07:35,469 INFO : Browser exited with error code: 0 09:07:35 INFO - 2016-02-25 09:07:35,525 INFO : Completed test kraken (00:00:57) 09:07:35 INFO - 2016-02-25 09:07:35,525 INFO : Starting test v8_7 09:07:35 INFO - 2016-02-25 09:07:35,525 DEBUG : operating with platform_type : linux_ 09:07:35 INFO - 2016-02-25 09:07:35,526 INFO : Initialising browser for v8_7 test... 09:07:35 INFO - 2016-02-25 09:07:35,532 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXPntLT/profile http://localhost:43824/getInfo.html 09:07:39 INFO - 2016-02-25 09:07:39,434 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:07:39 INFO - 2016-02-25 09:07:39,434 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:07:39 INFO - 2016-02-25 09:07:39,434 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:07:39 INFO - 2016-02-25 09:07:39,434 DEBUG : BROWSER_OUTPUT: __metrics 09:07:39 INFO - 2016-02-25 09:07:39,591 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:07:40 INFO - 2016-02-25 09:07:40,325 INFO : Browser initialized. 09:07:40 INFO - 2016-02-25 09:07:40,326 INFO : Running cycle 1/1 for v8_7 test... 09:07:40 INFO - 2016-02-25 09:07:40,326 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXPntLT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:07:52 INFO - 2016-02-25 09:07:52,039 DEBUG : BROWSER_OUTPUT: RSS: Main: 133943296 09:07:52 INFO - 2016-02-25 09:07:52,039 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,373 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43824/tests/v8_7/run.html (next: http://localhost:43824/tests/v8_7/run.html) 09:08:13 INFO - 2016-02-25 09:08:13,623 DEBUG : BROWSER_OUTPUT: RSS: Main: 428486656 09:08:13 INFO - 2016-02-25 09:08:13,623 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,623 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |0;Richards;165.0437365901964 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.7175094712568 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.31895352073474 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5143.589743589743 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |4;RayTrace;937.207122774133 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |5;Earley;542.5935973955508 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |6;Boyer;10500 09:08:13 INFO - 2016-02-25 09:08:13,624 DEBUG : BROWSER_OUTPUT: |7;RegExp;24487.80487804878 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: |8;Splay;507.87201625190454 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4878.048780487805 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: __startTimestamp1456420093622__endTimestamp 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.04 Median:165.04 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: Values: 165.0 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.72 Median:164.72 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,625 DEBUG : BROWSER_OUTPUT: Values: 164.7 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.32 Median:278.32 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: Values: 278.3 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5143.59 Median:5143.59 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: Values: 5143.6 09:08:13 INFO - 2016-02-25 09:08:13,626 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,627 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:937.21 Median:937.21 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: Values: 937.2 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:542.59 Median:542.59 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: Values: 542.6 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10500.00 Median:10500.00 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: Values: 10500.0 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24487.80 Median:24487.80 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: Values: 24487.8 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:507.87 Median:507.87 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: Values: 507.9 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4878.05 Median:4878.05 stddev:0.00 (0.0%) 09:08:13 INFO - 2016-02-25 09:08:13,628 DEBUG : BROWSER_OUTPUT: Values: 4878.0 09:08:13 INFO - 2016-02-25 09:08:13,629 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:08:13 INFO - 2016-02-25 09:08:13,629 DEBUG : BROWSER_OUTPUT: 09:08:14 INFO - 2016-02-25 09:08:14,333 INFO : Browser exited with error code: 0 09:08:14 INFO - 2016-02-25 09:08:14,348 INFO : Completed test v8_7 (00:00:38) 09:08:14 INFO - 2016-02-25 09:08:14,695 INFO : Completed test suite (00:20:07) 09:08:14 INFO - 2016-02-25 09:08:14,695 DEBUG : Working with test: dromaeo_css 09:08:14 INFO - 2016-02-25 09:08:14,695 DEBUG : Generating results file: dromaeo_css 09:08:14 INFO - 2016-02-25 09:08:14,697 DEBUG : Working with test: kraken 09:08:14 INFO - 2016-02-25 09:08:14,697 DEBUG : Generating results file: kraken 09:08:14 INFO - 2016-02-25 09:08:14,698 INFO : javascript benchmark 09:08:14 INFO - 2016-02-25 09:08:14,698 DEBUG : Working with test: v8_7 09:08:14 INFO - 2016-02-25 09:08:14,698 DEBUG : Generating results file: v8_7 09:08:14 INFO - 2016-02-25 09:08:14,699 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:08:14 INFO - 2016-02-25 09:08:14,759 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:08:14 INFO - 2016-02-25 09:08:14,759 DEBUG : process_Request line: dromaeo_css 7653.82 graph.html#tests=[[72,53,35]] 09:08:14 INFO - 2016-02-25 09:08:14,759 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:08:14 INFO - 2016-02-25 09:08:14,787 DEBUG : process_Request line: kraken 1479.40 graph.html#tests=[[232,53,35]] 09:08:14 INFO - 2016-02-25 09:08:14,788 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:08:14 INFO - 2016-02-25 09:08:14,815 DEBUG : process_Request line: v8_7 23406.69 graph.html#tests=[[230,53,35]] 09:08:14 INFO - 2016-02-25 09:08:14,816 INFO : javascript benchmark 09:08:14 INFO - 2016-02-25 09:08:14,820 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3301.2572171261463, 3393.643667414923, 3434.2272988577574, 3436.570428696413, 3450.2926899985496, 5617.241672374228, 5654.038813401721, 5657.9717101414535, 5669.09294512878, 5687.203791469187, 5660.462256085676, 5693.629914055584, 5699.458551437617, 5700.059490184125, 5716.971415142929, 5052.166392545226, 5091.592672586203, 5148.794048238066, 5181.300524429211, 5245.632805703603, 5038.899222015558, 5175.327207463043, 5191.143461328877, 5197.688138711689, 5200.427952925175, 5168.302279192299, 5209.661372010826, 5231.424543300234, 5239.790408383679, 5240.6593571417925, 5177.559907407837, 5185.740712964355, 5219.739013049332, 5242.449542797989, 5243.475652434764, 4913.385826771653, 5044.949550504484, 5104.438511763703, 5107.0, 5163.199704045879, 5400.189971504266, 5492.368377636543, 5506.174073888908, 5510.669359838423, 5512.503874651261, 6043.486303664194, 6091.604045737013, 6092.08618707193, 6096.1770161028135, 6105.114758360013, 6004.159417681528, 6006.8498287542725, 6081.787137450168, 6144.170536977495, 6147.47746441553, 5734.369219385864, 5738.139279108167, 5740.0815869460685, 5740.827775166752, 5754.884902301972, 6181.670940747671, 6191.102290167901, 6191.721372538246, 6196.597221180609, 6213.782517611862, 5756.366799652035, 5860.3260625027815, 5973.49225315853, 5998.580099393088, 6133.080037994337, 6116.512591648093, 6120.750240493622, 6125.207623589072, 6126.681223142771, 6127.719830079223, 6081.665508397081, 6087.908681369783, 6100.23747031621, 6113.510919126459, 6131.049687298476, 5872.647641141545, 5920.407959204132, 5990.251218597676, 6033.879322413615, 6094.055421409688, 4636.513166117509, 4675.0, 4691.108689349013, 4695.95304046962, 4711.199096153662, 4187.832486700559, 4199.958000419957, 4210.2842516772225, 4212.726172798758, 4222.641075508616, 5562.276904002454, 5594.776208951596, 5689.0328644130595, 5689.68706721126, 5734.0, 6079.604825686316, 6139.201903752573, 6141.57009009374, 6151.477124444473, 6156.538259630545, 5693.914591281134, 5749.626274292241, 5753.712314384264, 5758.74085666146, 5788.623739456922, 5131.281620573122, 5200.115980283361, 5242.9213561796605, 5305.44292849253, 5323.1749078892835, 6258.061290806416, 6258.311585725566, 6261.780837670569, 6264.060390941304, 6264.780732674334, 6121.632702037892, 6122.5408094392205, 6125.081237814364, 6127.662978536223, 6128.264608247039, 6025.6083354581815, 6050.909236361546, 6136.417040381157, 6146.293176284785, 6148.415900489446, 5985.730642121028, 6018.247719035121, 6030.396960303934, 6040.6375617461335, 6043.456088952015, 6007.459328660441, 6045.969770151121, 6101.786437474844, 6105.908411373922, 6109.2974307955155, 5847.766089356378, 5911.527077833849, 5929.110633405024, 5938.168656388023, 5946.643201407929, 5951.970240148771, 5959.493443057216, 5973.970130149321, 5976.432238937294, 5980.880382392241, 5652.3217213934595, 5715.228444159985, 5826.184334193301, 5828.883422331614, 5833.79581714638, 5839.5912286141265, 5913.29040515141, 5937.851553711019, 5942.10868369748, 5958.285005799332, 5886.440788125121, 5906.232189815297, 5910.231669882888, 5913.881722365614, 5917.0828521577505], "unit": "ms", "name": "dojo.html", "value": 5597.410024750668}, {"lowerIsBetter": false, "replicates": [589.0839744197771, 626.2078470734521, 636.971336289867, 671.8488340123475, 704.144464475662, 29677.554836677442, 43101.35347969779, 43721.562784372145, 43731.562684373144, 43798.12403751923, 46871.76564117175, 47514.52485475144, 47578.52421475793, 47586.52413475864, 47647.0, 8129.146439623843, 8243.05204901435, 8246.793830154264, 9231.769205769859, 9418.952905235465, 10930.125589952784, 11277.492512836941, 11281.548738050467, 11291.49188286525, 11297.604583839568, 11012.944935275313, 11101.611443599475, 11117.055050320732, 11118.6108486203, 11123.110151187926, 10990.99999999996, 11062.336259824437, 11073.169512286611, 11076.11391088711, 11087.057600104, 10824.18818588609, 10910.454477276104, 10943.06983906361, 10947.34315941046, 10955.890441095567, 43996.3400548992, 44318.0, 44391.0, 44455.33317000248, 44604.330935036, 3800.258949504848, 3823.330917089498, 3826.043489127718, 3841.2317536492815, 4358.193734159191, 32817.17957051069, 33224.0, 33354.83322583372, 33454.83272583621, 33521.329573408395, 4350.108227813291, 4355.73865568067, 4436.645068394553, 4440.267355886275, 4476.925997342435, 306693.0, 309328.0, 309650.96919669636, 310100.0, 310909.0, 60471.99999999912, 61795.38204617986, 68633.65683171552, 69118.0, 69247.96128058083, 72189.63905180545, 72788.0, 73471.63264183752, 73573.26426735664, 73684.26315736774, 16897.91551042237, 17088.658226835392, 17099.316027358767, 17110.82889171117, 17120.657586848192, 12399.13206075566, 12515.749685006249, 12636.620901372973, 12640.367981600883, 12667.556635517712, 21139.682904756442, 21625.35123946284, 22258.0, 22418.663720044213, 22423.32730018102, 8147.592620368958, 8418.663253469915, 8507.532085735344, 8561.1866872647, 8572.485650860968, 1068.834330678746, 1105.9824961035865, 1157.866845312783, 1177.0818761366147, 1202.4228370382266, 30700.84649576738, 30989.0703278902, 31486.527702084488, 34338.0, 35281.47077793835, 1140.8402823604702, 1143.1255089856268, 1151.3494875395374, 1192.6183621241119, 1215.9297773122935, 13763.243021633907, 15856.286467109008, 15919.681606367807, 16225.891219197825, 16770.80071377811, 449002.0, 453425.0, 459661.0, 459950.0, 461237.0, 60852.69573652192, 61284.38715612876, 61294.0, 61423.0, 61524.69237653783, 1200.0459634590522, 1301.264785396248, 1496.7829664698559, 1498.0113125337443, 1511.0329389190927, 30422.847885760428, 30837.845810770803, 31181.688183118786, 31394.686053139176, 32044.0, 54548.45451545434, 56129.0, 56162.438375617356, 56567.0, 56653.0, 338031.0, 343468.0, 345973.0, 346173.0, 346391.0, 46230.30654540252, 47099.29351059668, 47183.16900985952, 47187.292190616485, 47727.52272477231, 50626.493735063654, 50682.23976640427, 50709.23936141035, 50905.0, 51176.48823511717, 40579.39130913098, 41885.37171942362, 42511.3623295657, 43732.34401483917, 46259.3061104077, 40943.795281023406, 40948.0, 40955.1808963825, 41123.97190070272, 43858.0, 40025.0, 40138.0, 40178.1964360717, 40308.1938361237, 40962.79518602388, 30092.69907300899, 31700.5244921331, 31702.207444814063, 31745.841270794423, 32290.83854580806, 27200.727992719818, 27507.17478475649, 27809.16572502828, 27855.303617409725, 27889.16332510028], "unit": "ms", "name": "ext.html", "value": 21238.812332628262}, {"lowerIsBetter": false, "replicates": [395.05778717758136, 399.08410198594225, 401.12554630904623, 401.63651895034997, 402.1494539049908, 15542.922285388586, 16034.198290085478, 17694.823051769494, 18089.819101808993, 18116.18477168529, 11354.375509346983, 11669.474873630686, 17879.0, 18166.3641772538, 18318.908405457954, 5090.490950904908, 5232.16285394337, 5233.267342572043, 5260.763265653036, 5261.552768014723, 4000.599940006005, 4103.035786590149, 4103.363978583325, 4104.261232978063, 5216.139337009389, 6458.967705161491, 6582.934170658304, 7042.436605071608, 7050.471214658895, 7056.435485161175, 6966.059581956421, 6974.721011159573, 7035.859282814315, 7040.155181378216, 7040.507164498487, 6316.778912738032, 6488.5133614978595, 6488.740450381979, 6751.189857217166, 6766.864662706767, 22909.656355154686, 24267.27198184057, 24339.0, 24840.130595429073, 24922.50154996908, 2585.2761226856323, 2630.579107342816, 2731.4673638640475, 2744.0, 2748.051922086877, 14509.637259068502, 14782.113073215534, 15416.3833446662, 15513.844861551353, 15558.611034724223, 1992.481954691776, 1996.7104769808298, 1997.8201961823506, 2006.3278801601482, 2105.978940210609, 73493.26506734917, 75097.24902751012, 76866.61566692186, 77295.0, 78756.60621696913, 16226.269817857989, 16943.15284235808, 16963.74554381685, 16966.660666786596, 16970.660586788443, 27252.182434526996, 27312.726872731415, 27513.31216719558, 27656.170314890587, 27718.0, 10045.397276163454, 10116.342437741523, 10122.342047766871, 10126.898731012743, 10196.745081373025, 8558.443701159405, 8598.355123365773, 8634.22291993711, 8678.6962456315, 8698.869516957251, 6786.9209608034425, 8339.12439193888, 8345.749627511184, 8356.415050946376, 8374.2881855043, 1921.8846869187894, 1957.1877670766473, 1981.128303546435, 1985.394454691333, 1988.711636812654, 983.9704808855746, 1046.7435478307864, 1143.742657901982, 1161.616666500053, 1162.4013632979022, 15263.160526170945, 17046.23291951865, 17685.557861053578, 17929.282828686708, 18391.632167356845, 976.8632391465201, 978.1978777402462, 1000.4497526360473, 1047.8323468245044, 1068.1668298727006, 9700.75748106303, 10032.849507257397, 10037.247206459399, 10069.144122749502, 10084.142847858015, 601643.0, 624095.0, 675176.0, 679438.0, 703511.0, 78094.0, 79267.60366198247, 81758.59120704359, 85077.0, 91532.54233728789, 1213.387239444075, 1219.9268043917393, 1222.7554489102215, 1226.7730469863104, 1247.95008199671, 15832.92083539575, 15999.120048396984, 16649.50051498457, 16804.24380902838, 17104.315827367263, 38702.2259554813, 38868.222635547696, 39033.8048309768, 39205.80397097996, 41004.1799164021, 52628.0, 54905.0, 55472.72263638817, 55519.722401387735, 55740.16389754238, 10017.849732254168, 10604.310719803188, 10617.309874858109, 10623.406489026707, 10625.468726563486, 12458.937705311415, 12478.126531142732, 18210.544736381697, 18262.08689565543, 18879.716804247673, 15383.0, 15687.058776472992, 15695.921520392325, 15698.215089245721, 15877.682446351238, 15172.696546069237, 15219.771703424236, 15311.38754449854, 15554.766678500058, 16018.119003454922, 16055.438059668322, 16178.757318639995, 16299.511014669579, 16312.429064982669, 16433.424830130887, 10218.335808172445, 10442.321249119086, 10528.631497897537, 10547.156227501628, 10576.947115264375, 15140.318685658951, 15164.31760570799, 15227.390904363701, 15326.846731532987, 15567.76648350253], "unit": "ms", "name": "jquery.html", "value": 10358.49823867504}, {"lowerIsBetter": false, "replicates": [1526.7786171005203, 1584.9128297943616, 1596.0, 1734.5403468080954, 1841.9815801841978, 8250.381221408388, 8571.400001999855, 8594.65621375145, 8599.742007739762, 8636.568171591427, 7344.596047217401, 7539.472236943416, 7572.091349038109, 8103.311218546431, 8428.578571071437, 2368.810495160392, 2382.0, 2397.8081753459683, 2399.3521749127726, 2407.807375409963, 2412.746661600533, 2454.607262837946, 2465.593177125781, 2474.9133780317693, 2479.9752002479927, 2029.3607513633158, 2073.3676228750223, 2371.7509661485556, 2462.630605409185, 2466.506698660266, 2427.5023620157826, 2438.987805060963, 2460.7662272084212, 2461.089396923141, 2496.6130249811217, 2450.767177118171, 2454.312792418126, 2479.3925488255495, 2480.6527086207943, 2496.10140349474, 8162.551059691715, 8323.250907418302, 8325.084240733515, 8330.0, 8331.708585169308, 4997.575206107485, 5088.847334579931, 5094.286799848024, 5099.107656160194, 5101.566366858821, 3363.4786608075788, 3376.9155771105675, 3411.300683359905, 3542.752007359486, 3570.4822800694014, 1151.424287856072, 1206.318430086998, 1229.6372570091844, 1233.6114124050985, 1288.6971561682997, 11551.422428878606, 11702.648920532398, 11723.4138293085, 11724.003459705935, 11727.999999999915, 4482.5069242383315, 4513.593776560126, 4560.17916774977, 4595.195840727859, 4611.377464042378, 10039.799204015879, 10055.748606284902, 10060.39637621745, 10060.597576097021, 10081.546330415149, 5073.797048118107, 5081.060003899291, 5082.18685010404, 5083.949160508421, 5120.103981803244, 4628.837990670378, 4633.027064316464, 4638.744869032168, 4638.930416043762, 4642.2108241598335, 3880.495535580413, 3970.3845903884944, 3983.980080099581, 3994.8801535953967, 4026.879193624196, 1225.7916154253799, 1247.4885297028356, 1275.4196840437764, 1276.6106337566946, 1285.6978610026636, 1166.7258194324327, 1180.4982882274999, 1213.065939226808, 1401.7827236778112, 1419.7302512522795, 7799.688012479551, 7872.448928574945, 7994.160613135653, 8222.671093156207, 8255.091939886608, 1111.3220935229504, 1153.8730739618636, 1185.0637995983077, 1186.128195776104, 1198.526582000105, 6280.497560195174, 6287.717052732638, 6337.7781777637565, 6346.27017892939, 6355.459785918156, 10008.849867251996, 10156.746081348025, 10321.690349289533, 10376.325538839952, 10456.738581535523, 9917.851232231522, 10203.183745300357, 10247.385156890461, 10311.84532232017, 10318.638847640446, 7968.083670377865, 7968.243016913384, 7972.123066462685, 7987.400944929153, 8074.111847696749, 7623.9618801905635, 7642.121156067124, 7746.341560967381, 7753.728619498291, 7754.302112809874, 8969.237614802814, 9005.279577633904, 9255.444673319622, 9264.305177111879, 9283.071692830934, 9247.39891907024, 9251.722448326562, 9476.147146756824, 9499.715008549754, 9517.524123793644, 7135.464840136907, 7137.179224389261, 7217.927820721726, 7219.205887352282, 7228.566286022856, 8071.677132914852, 8135.552544609866, 8189.262966333059, 8191.098979112174, 8220.63007164691, 6435.517336199898, 7210.242924492853, 7382.52013619113, 7526.209747976383, 7536.171021187774, 6940.236573976757, 7385.593792341473, 7460.776176714708, 7648.196939321291, 7653.311201991847, 7705.653245603849, 7811.062672479339, 7813.882791758242, 7813.960930195312, 7832.099308579586, 6835.555688880207, 7039.084918960502, 7046.295370462913, 7123.643817809192, 7130.5365151265005, 7134.857302854018, 7231.602261875647, 7241.637918104179, 7244.963775181091, 7246.927530724625], "unit": "ms", "name": "mootools.html", "value": 4861.006051916402}, {"lowerIsBetter": false, "replicates": [355.6781113092651, 368.3553780883455, 369.98705045323385, 378.5797764481425, 378.85224762342676, 6085.726142323595, 6118.204633397658, 6119.173911521943, 6127.908081378783, 6163.075538669202, 5788.247527821377, 5889.116632505126, 5942.494887934531, 5959.284885813709, 6007.519398448125, 4462.1521910837, 4618.49196588375, 4685.976570117145, 4737.005228901935, 4737.881552961169, 4731.266653668669, 4740.051989602076, 4741.359916411292, 4750.619950403977, 4761.166795810736, 4518.096380723852, 4676.181668208067, 4691.390119284488, 4701.0, 4701.717896926179, 4587.449506059261, 4610.654200934927, 4682.531746825324, 4705.529447055302, 4730.8344207952905, 4217.683673724483, 4222.408862759216, 4222.704410691253, 4239.448871646666, 4249.893752656178, 5755.856103597402, 5761.53907687384, 5765.423457654243, 5777.88444231113, 5784.1034639630925, 5303.5226829585135, 5472.097103977839, 5527.2538207342295, 5528.502434780889, 5539.501444869981, 6431.517636177258, 6434.292227854932, 6436.452901503378, 6439.0, 6443.097966284724, 4575.702579332333, 4583.72497650142, 4590.908181836345, 4591.862244132682, 4600.516945720685, 6526.118973938553, 6582.17722784652, 6622.6357550334715, 6653.201615806086, 6653.53425260232, 4701.2242979908615, 4719.480857105716, 4724.173269677862, 4732.02993386355, 4736.384270044941, 4776.259679749644, 4785.090832741768, 4785.114753770564, 4789.784459699252, 4790.281457781361, 5433.8369848904595, 5435.10320797064, 5446.809361672402, 5447.155690867922, 5451.700156491352, 6335.239771227482, 6470.838229044218, 6471.579347342502, 6478.870422591522, 6490.156279683611, 1650.9009459432473, 1686.207482483231, 1693.7882764654419, 1693.90683512408, 1697.2786565709523, 1129.7796929598649, 1147.0708725932022, 1148.9827652585218, 1149.0290704354807, 1170.4615876696644, 6222.81331560054, 6230.158928544589, 6231.563790534619, 6235.469985051231, 6240.656763878031, 6637.1703537057865, 6643.335666433415, 6643.667816609151, 6658.600483970978, 6727.9327206728285, 5885.499732522783, 6078.3617720138745, 6236.469900058455, 6245.750169993149, 6253.46845518127, 3551.1122219445137, 3645.708343332527, 3654.488371627974, 3656.835442405098, 3658.926821463556, 6922.342377474132, 6931.09895713554, 6932.6533673316135, 6935.133108361455, 6942.791716248521, 6815.556988795713, 6932.44540436774, 6939.30606939302, 6942.097527321416, 6945.47908906834, 6898.0, 6902.482313826483, 6903.620300883399, 6908.5509441886115, 6917.169939607279, 6729.52893297484, 6736.494762892705, 6755.898661519983, 6758.763443279461, 6774.525783195129, 6505.577137486048, 6654.068430419842, 6654.800355989328, 6660.800175994728, 6668.799936001928, 6903.206131294965, 6908.412784913338, 6913.2049814272, 6919.481038922202, 6925.445964322943, 4696.788644510937, 4730.0539892022425, 4734.668573199843, 4735.02931898953, 4735.266033764772, 6649.4680425564875, 6755.628440435823, 6761.560498567577, 6761.898571521333, 6773.729050838108, 6551.0173473979285, 6560.376764207393, 6574.506911981525, 6579.0, 6581.5392922496885, 6570.868582628225, 6577.3422657733845, 6577.736890524517, 6579.276279609335, 6582.539222254396, 6261.5616906816085, 6351.110844481684, 6374.521910856797, 6380.904286435614, 6381.106645069601, 6356.141920840627, 6474.935250647433, 6495.350464953467, 6551.279359270572, 6608.273089960005, 6432.903506447501, 6448.613083215022, 6453.0, 6465.0, 6469.676516174079], "unit": "ms", "name": "prototype.html", "value": 4933.282360753248}, {"lowerIsBetter": false, "replicates": [826.723047778994, 843.6962693430368, 929.7164364868714, 931.3108299858104, 946.0161432110606, 14691.926540367296, 14955.775663365059, 14960.476383326586, 14982.32579533921, 15007.699846003086, 12596.307203103826, 12683.61949141525, 12685.3657317134, 12690.873091269084, 12741.681457963556, 701.8736627407064, 713.1192976673804, 714.9642517874112, 855.315747402079, 868.7914900423884, 865.2904618213067, 887.8978917424482, 891.0, 891.8795962545038, 892.281713220857, 917.8806755121825, 931.0503286647615, 933.4119504712056, 933.6125507914207, 934.2852717670987, 731.7548621211873, 746.3767753925479, 901.3465237702678, 927.0775578299601, 928.1321963963682, 682.2154522299346, 684.2712511175582, 684.8869936460478, 685.25649670108, 685.4036987820627, 12892.355382230944, 12937.547185848543, 13027.804582931263, 13053.086283960127, 13058.869411305861, 13028.153170044012, 13418.194908305533, 13933.094348867291, 14166.858331416657, 14397.13617182962, 11154.10767138627, 11251.606193783178, 11346.886531134665, 11446.0, 11619.593314234045, 3900.3369427197445, 3966.3852102924093, 3968.4840970673918, 3975.204959008181, 3977.2840888640035, 45524.0, 46624.300635490494, 46735.766321168514, 49157.77105572353, 49227.50772492265, 10913.618023369145, 10919.454027298603, 11214.943925280322, 11361.14791390649, 11662.533498660128, 19288.324908628132, 19418.514537136685, 19427.125779339964, 19455.8054419454, 19520.31678891232, 7970.721024764105, 8276.668933242603, 8412.074671786098, 8441.493510389397, 8520.488770673779, 4406.911861762746, 4548.840790572381, 4587.082583483317, 4607.470140933769, 4671.859844204611, 3394.6096198936943, 3417.6582341765625, 3422.178677117524, 3423.9828800856335, 3433.073070271063, 602.1509671363444, 614.8344962876458, 623.3050149083739, 624.2290770897937, 634.8285962790021, 696.923338432772, 698.448225901532, 741.7626359564995, 750.2909750285927, 753.9019927409402, 9330.113639204264, 9439.575219115157, 9469.526523673927, 9561.952190239144, 9612.615495380247, 735.1398863329916, 747.7868807389867, 752.7139686918936, 770.3413581387945, 791.5488171742052, 4061.669431066556, 4463.107378524308, 4477.8432754854075, 4484.910301793946, 4513.255312873404, 57760.4223957755, 58493.0, 58513.41486585165, 58749.706251468466, 58896.41103588909, 50993.74503127461, 53933.0, 53991.73004134954, 54891.17663235054, 54968.45031549713, 46878.0, 50169.0, 50260.999999999265, 50497.0, 51297.743511282206, 9280.118388753057, 9342.346035777431, 9413.388129771543, 9485.478298693639, 9539.570719317786, 36260.81869590635, 36380.27239455249, 37363.439548406255, 37664.0, 37823.05442363963, 43727.0, 44764.0, 47340.0, 47492.0, 47889.042219156116, 12075.0, 13400.262985535888, 13471.259080750653, 13673.384697688827, 13790.103643263557, 13507.257100859548, 13661.385237664528, 13765.0, 14141.929290353482, 14283.928580357031, 11282.94358528202, 11650.592229271935, 11658.825117623412, 11832.881671183179, 11841.703957401134, 12815.102942794289, 12932.353382331034, 12967.675808104495, 13129.277889716157, 13796.172229666252, 12424.56514022005, 12438.067144963983, 12440.688982775502, 12562.748745024866, 13072.150310229805, 10310.484475776031, 10474.738131546772, 10530.947345263225, 10540.525676344736, 10951.397673127736, 10218.649710566462, 10531.631392901212, 10559.0, 10598.841017384899, 10612.84080738805], "unit": "ms", "name": "yui.html", "value": 6806.951956621736}], "name": "dromaeo_css", "value": 7653.820519768785}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 103.0, 109.0, 111.0, 110.0, 100.0, 110.0, 108.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.8}, {"lowerIsBetter": true, "replicates": [151.0, 121.0, 161.0, 158.0, 125.0, 157.0, 147.0, 124.0, 158.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.4}, {"lowerIsBetter": true, "replicates": [160.0, 159.0, 160.0, 161.0, 160.0, 160.0, 160.0, 165.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 71.0, 158.0, 71.0, 72.0, 71.0, 70.0, 154.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 88.2}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 132.0, 92.0, 92.0, 94.0, 137.0, 92.0, 93.0, 145.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [56.0, 57.0, 56.0, 58.0, 57.0, 56.0, 56.0, 59.0, 56.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.6}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [77.0, 80.0, 78.0, 80.0, 79.0, 78.0, 84.0, 79.0, 79.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.3}, {"lowerIsBetter": true, "replicates": [125.0, 120.0, 114.0, 121.0, 115.0, 123.0, 123.0, 112.0, 118.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.0}, {"lowerIsBetter": true, "replicates": [141.0, 154.0, 141.0, 144.0, 142.0, 139.0, 144.0, 145.0, 141.0, 130.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.1}, {"lowerIsBetter": true, "replicates": [66.0, 62.0, 63.0, 63.0, 64.0, 63.0, 64.0, 62.0, 63.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.3}], "name": "kraken", "value": 1479.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.0437365901964], "unit": "score", "name": "Richards", "value": 213.894818}, {"lowerIsBetter": false, "replicates": [164.7175094712568], "unit": "score", "name": "DeltaBlue", "value": 401.40237799999994}, {"lowerIsBetter": false, "replicates": [278.31895352073474], "unit": "score", "name": "Encrypt", "value": 956.3883330000003}, {"lowerIsBetter": false, "replicates": [5143.589743589743], "unit": "score", "name": "Decrypt", "value": 51.75004486540379}, {"lowerIsBetter": false, "replicates": [937.207122774133], "unit": "score", "name": "RayTrace", "value": 789.568263}, {"lowerIsBetter": false, "replicates": [542.5935973955508], "unit": "score", "name": "Earley", "value": 1228.2913090000002}, {"lowerIsBetter": false, "replicates": [10500.0], "unit": "score", "name": "Boyer", "value": 63.47266666666663}, {"lowerIsBetter": false, "replicates": [24487.80487804878], "unit": "score", "name": "RegExp", "value": 37.201578685258944}, {"lowerIsBetter": false, "replicates": [507.87201625190454], "unit": "score", "name": "Splay", "value": 160.45577900000004}, {"lowerIsBetter": false, "replicates": [4878.048780487805], "unit": "score", "name": "NavierStokes", "value": 304.2200000000002}], "name": "v8_7", "value": 23406.691943569254}]} 09:08:14 INFO - RETURN: dromaeo_css: 7653.82 09:08:14 INFO - RETURN: kraken: 1479.40 09:08:14 INFO - RETURN: v8_7: 23406.69 09:08:14 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23406.69"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1479.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7653.82"}}} 09:08:14 INFO - Return code: 0 09:08:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:08:14 INFO - # TBPL SUCCESS # 09:08:14 INFO - Running post-action listener: _resource_record_post_action 09:08:14 INFO - Running post-run listener: _resource_record_post_run 09:08:16 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 327680; Write bytes: 516915200; Read time: 424; Write time: 561368 09:08:16 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 4096; Write bytes: 255639552; Read time: 132; Write time: 392628 09:08:16 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 323584; Write bytes: 261275648; Read time: 292; Write time: 168740 09:08:16 INFO - Running post-run listener: _upload_blobber_files 09:08:16 INFO - Blob upload gear active. 09:08:16 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:08:16 INFO - Copying logs to upload dir... 09:08:16 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.120125 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-02-25 09:08:15.908979) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 09:08:15.912643) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009953 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456410390/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-25 09:08:15.962962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:08:15.963296) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1456418382.138817-863788944 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 09:08:16.013051) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 09:08:16.013370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 09:08:16.013653) ========= ========= Total master_lag: 0.26 =========