builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1452135449.03 results: success (0) buildid: 20160106164307 builduid: 935f3fde7c544de49c3a488384a6111a revision: 9cc90fb04ea6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.032946) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.033449) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.033736) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006401 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.056805) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.057108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.079777) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.080163) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.051933 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.143052) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.143346) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.143708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.143976) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-06 18:57:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 63.6M=0s 2016-01-06 18:57:29 (63.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174093 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.330166) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.330472) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.182811 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.524884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:29.525244) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9cc90fb04ea6 --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 9cc90fb04ea6 --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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-06 18:57:29,771 Setting DEBUG logging. 2016-01-06 18:57:29,771 attempt 1/10 2016-01-06 18:57:29,771 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9cc90fb04ea6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-06 18:57:30,345 unpacking tar archive at: mozilla-beta-9cc90fb04ea6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.838568 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:30.376369) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:30.376685) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:30.386354) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:30.386604) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-06 18:57:30.386970) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-06 18:57:30.387261) ========= /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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:57:30 INFO - MultiFileLogger online at 20160106 18:57:30 in /builds/slave/test-pgo 18:57:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 18:57:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:57:30 INFO - {'append_to_log': False, 18:57:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:57:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:57:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:57:30 INFO - 'branch': 'Mozilla-Beta', 18:57:30 INFO - 'buildbot_json_path': 'buildprops.json', 18:57:30 INFO - 'config_files': ('talos/linux_config.py',), 18:57:30 INFO - 'default_actions': ('clobber', 18:57:30 INFO - 'read-buildbot-config', 18:57:30 INFO - 'download-and-extract', 18:57:30 INFO - 'populate-webroot', 18:57:30 INFO - 'create-virtualenv', 18:57:30 INFO - 'install', 18:57:30 INFO - 'run-tests'), 18:57:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:57:30 INFO - 'download_minidump_stackwalk': True, 18:57:30 INFO - 'download_symbols': 'ondemand', 18:57:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:57:30 INFO - 'tooltool.py': '/tools/tooltool.py', 18:57:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:57:30 INFO - '/tools/misc-python/virtualenv.py')}, 18:57:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:57:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:57:30 INFO - 'installer_path': 'installer.exe', 18:57:30 INFO - 'log_level': 'info', 18:57:30 INFO - 'log_name': 'talos', 18:57:30 INFO - 'log_to_console': True, 18:57:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:57:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:57:30 INFO - 'opt_config_files': (), 18:57:30 INFO - 'pip_index': False, 18:57:30 INFO - 'sps_profile': False, 18:57:30 INFO - 'sps_profile_interval': 0, 18:57:30 INFO - 'suite': 'dromaeojs', 18:57:30 INFO - 'system_bits': '32', 18:57:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:57:30 INFO - 'title': 'talos-linux64-ix-014', 18:57:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:57:30 INFO - 'use_talos_json': True, 18:57:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:57:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:57:30 INFO - 'work_dir': 'build'} 18:57:30 INFO - ##### 18:57:30 INFO - ##### Running clobber step. 18:57:30 INFO - ##### 18:57:30 INFO - Running pre-action listener: _resource_record_pre_action 18:57:30 INFO - Running main action method: clobber 18:57:30 INFO - rmtree: /builds/slave/test-pgo/build 18:57:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:57:35 INFO - Running post-action listener: _resource_record_post_action 18:57:35 INFO - ##### 18:57:35 INFO - ##### Running read-buildbot-config step. 18:57:35 INFO - ##### 18:57:35 INFO - Running pre-action listener: _resource_record_pre_action 18:57:35 INFO - Running main action method: read_buildbot_config 18:57:35 INFO - Using buildbot properties: 18:57:35 INFO - { 18:57:35 INFO - "properties": { 18:57:35 INFO - "buildnumber": 67, 18:57:35 INFO - "product": "firefox", 18:57:35 INFO - "script_repo_revision": "production", 18:57:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:57:35 INFO - "repository": "", 18:57:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 18:57:35 INFO - "buildid": "20160106164307", 18:57:35 INFO - "slavename": "talos-linux64-ix-014", 18:57:35 INFO - "pgo_build": "True", 18:57:35 INFO - "basedir": "/builds/slave/test-pgo", 18:57:35 INFO - "project": "", 18:57:35 INFO - "platform": "ubuntu64_hw", 18:57:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:57:35 INFO - "slavebuilddir": "test", 18:57:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 18:57:35 INFO - "branch": "mozilla-beta", 18:57:35 INFO - "repo_path": "releases/mozilla-beta", 18:57:35 INFO - "stage_platform": "linux64-pgo", 18:57:35 INFO - "builduid": "935f3fde7c544de49c3a488384a6111a", 18:57:35 INFO - "revision": "9cc90fb04ea6" 18:57:35 INFO - }, 18:57:35 INFO - "sourcestamp": { 18:57:35 INFO - "repository": "", 18:57:35 INFO - "hasPatch": false, 18:57:35 INFO - "project": "", 18:57:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:57:35 INFO - "changes": [ 18:57:35 INFO - { 18:57:35 INFO - "category": null, 18:57:35 INFO - "files": [ 18:57:35 INFO - { 18:57:35 INFO - "url": null, 18:57:35 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2" 18:57:35 INFO - } 18:57:35 INFO - ], 18:57:35 INFO - "repository": "", 18:57:35 INFO - "rev": "9cc90fb04ea6", 18:57:35 INFO - "who": "sendchange", 18:57:35 INFO - "when": 1452135430, 18:57:35 INFO - "number": 7426967, 18:57:35 INFO - "comments": "Bug 1236754 - Turn on interposition if e10s is enabled (r=mconley,a=ritu)", 18:57:35 INFO - "project": "", 18:57:35 INFO - "at": "Wed 06 Jan 2016 18:57:10", 18:57:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 18:57:35 INFO - "revlink": "", 18:57:35 INFO - "properties": [ 18:57:35 INFO - [ 18:57:35 INFO - "buildid", 18:57:35 INFO - "20160106164307", 18:57:35 INFO - "Change" 18:57:35 INFO - ], 18:57:35 INFO - [ 18:57:35 INFO - "builduid", 18:57:35 INFO - "935f3fde7c544de49c3a488384a6111a", 18:57:35 INFO - "Change" 18:57:35 INFO - ], 18:57:35 INFO - [ 18:57:35 INFO - "pgo_build", 18:57:35 INFO - "True", 18:57:35 INFO - "Change" 18:57:35 INFO - ] 18:57:35 INFO - ], 18:57:35 INFO - "revision": "9cc90fb04ea6" 18:57:35 INFO - } 18:57:35 INFO - ], 18:57:35 INFO - "revision": "9cc90fb04ea6" 18:57:35 INFO - } 18:57:35 INFO - } 18:57:35 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2. 18:57:35 INFO - Running post-action listener: _resource_record_post_action 18:57:35 INFO - ##### 18:57:35 INFO - ##### Running download-and-extract step. 18:57:35 INFO - ##### 18:57:35 INFO - Running pre-action listener: _resource_record_pre_action 18:57:35 INFO - Running main action method: download_and_extract 18:57:35 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/test_packages.json 18:57:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:57:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:57:35 INFO - Downloaded 1270 bytes. 18:57:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:57:35 INFO - Using the following test package requirements: 18:57:35 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:57:35 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 18:57:35 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'jsshell-linux-x86_64.zip'], 18:57:35 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 18:57:35 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:57:35 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 18:57:35 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 18:57:35 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 18:57:35 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 18:57:35 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 18:57:35 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 18:57:35 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:57:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:57:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:57:39 INFO - Downloaded 22571527 bytes. 18:57:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:57:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:57:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:57:40 INFO - Return code: 0 18:57:40 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:40 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:57:40 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:57:41 INFO - Downloaded 22571527 bytes. 18:57:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:57:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 18:57:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:57:42 INFO - Return code: 0 18:57:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:57:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:57:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:57:44 INFO - Downloaded 11131970 bytes. 18:57:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:57:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 18:57:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:57:44 INFO - Return code: 0 18:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:57:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:57:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:57:49 INFO - Downloaded 56633471 bytes. 18:57:49 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:57:49 INFO - mkdir: /builds/slave/test-pgo/properties 18:57:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:57:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:57:49 INFO - Contents: 18:57:49 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2 18:57:49 INFO - Running post-action listener: _resource_record_post_action 18:57:49 INFO - Running post-action listener: set_extra_try_arguments 18:57:49 INFO - ##### 18:57:49 INFO - ##### Running populate-webroot step. 18:57:49 INFO - ##### 18:57:49 INFO - Running pre-action listener: _resource_record_pre_action 18:57:49 INFO - Running main action method: populate_webroot 18:57:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:57:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:57:49 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:57:49 INFO - '../fennec_ids.txt'], 18:57:49 INFO - 'tests': ['tcheck2']}, 18:57:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:57:49 INFO - '--tppagecycles', 18:57:49 INFO - '7'], 18:57:49 INFO - 'tests': ['tsvgm']}}, 18:57:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:57:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:57:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:57:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:57:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:57:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:57:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'tests': ['damp', 'tps']}, 18:57:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['damp', 'tps']}, 18:57:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:57:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:57:49 INFO - 'other': {'tests': ['a11yr', 18:57:49 INFO - 'ts_paint', 18:57:49 INFO - 'tpaint', 18:57:49 INFO - 'sessionrestore', 18:57:49 INFO - 'sessionrestore_no_auto_restore']}, 18:57:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['a11yr', 18:57:49 INFO - 'ts_paint', 18:57:49 INFO - 'tpaint', 18:57:49 INFO - 'sessionrestore', 18:57:49 INFO - 'sessionrestore_no_auto_restore']}, 18:57:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['a11yr', 18:57:49 INFO - 'ts_paint', 18:57:49 INFO - 'tpaint', 18:57:49 INFO - 'sessionrestore', 18:57:49 INFO - 'sessionrestore_no_auto_restore']}, 18:57:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['a11yr', 18:57:49 INFO - 'ts_paint', 18:57:49 INFO - 'tpaint', 18:57:49 INFO - 'sessionrestore', 18:57:49 INFO - 'sessionrestore_no_auto_restore']}, 18:57:49 INFO - 'other_l64': {'tests': ['a11yr', 18:57:49 INFO - 'ts_paint', 18:57:49 INFO - 'tpaint', 18:57:49 INFO - 'sessionrestore', 18:57:49 INFO - 'sessionrestore_no_auto_restore']}, 18:57:49 INFO - 'other_nol64': {'tests': ['a11yr', 18:57:49 INFO - 'ts_paint', 18:57:49 INFO - 'tpaint', 18:57:49 INFO - 'sessionrestore', 18:57:49 INFO - 'sessionrestore_no_auto_restore']}, 18:57:49 INFO - 'svgr': {'tests': ['tsvgx', 18:57:49 INFO - 'tsvgr_opacity', 18:57:49 INFO - 'tart', 18:57:49 INFO - 'tscrollx', 18:57:49 INFO - 'cart']}, 18:57:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['tsvgx', 18:57:49 INFO - 'tsvgr_opacity', 18:57:49 INFO - 'tart', 18:57:49 INFO - 'tscrollx', 18:57:49 INFO - 'cart']}, 18:57:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'tests': ['tp5o']}, 18:57:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'talos_options': ['--e10s'], 18:57:49 INFO - 'tests': ['tp5o']}, 18:57:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'talos_options': ['--xperf_path', 18:57:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:57:49 INFO - 'tests': ['tp5n']}, 18:57:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:57:49 INFO - 'talos_options': ['--e10s', 18:57:49 INFO - '--xperf_path', 18:57:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:57:49 INFO - 'tests': ['tp5n']}}, 18:57:49 INFO - 'talos.zip': {'path': '', 18:57:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:57:49 INFO - Running post-action listener: _resource_record_post_action 18:57:49 INFO - ##### 18:57:49 INFO - ##### Running create-virtualenv step. 18:57:49 INFO - ##### 18:57:49 INFO - Running pre-action listener: _resource_record_pre_action 18:57:49 INFO - Running main action method: create_virtualenv 18:57:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:57:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 18:57:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:57:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:57:49 INFO - Using real prefix '/usr' 18:57:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:57:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:57:52 INFO - Installing pip.................done. 18:57:52 INFO - Return code: 0 18:57:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:57:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 18:57:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:57:52 INFO - Using env: {'DISPLAY': ':0', 18:57:52 INFO - 'HOME': '/home/cltbld', 18:57:52 INFO - 'LANG': 'en_US.UTF-8', 18:57:52 INFO - 'LANGUAGE': 'en_US:en', 18:57:52 INFO - 'LOGNAME': 'cltbld', 18:57:52 INFO - 'MAIL': '/var/mail/cltbld', 18:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:52 INFO - 'MOZ_NO_REMOTE': '1', 18:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:52 INFO - 'NO_EM_RESTART': '1', 18:57:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:57:52 INFO - 'PWD': '/builds/slave/test-pgo', 18:57:52 INFO - 'SHELL': '/bin/bash', 18:57:52 INFO - 'SHLVL': '1', 18:57:52 INFO - 'TERM': 'linux', 18:57:52 INFO - 'TMOUT': '86400', 18:57:52 INFO - 'USER': 'cltbld', 18:57:52 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:52 INFO - '_': '/tools/buildbot/bin/python'} 18:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:57:52 INFO - Cleaning up... 18:57:52 INFO - Return code: 0 18:57:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:57:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 18:57:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:57:52 INFO - Using env: {'DISPLAY': ':0', 18:57:52 INFO - 'HOME': '/home/cltbld', 18:57:52 INFO - 'LANG': 'en_US.UTF-8', 18:57:52 INFO - 'LANGUAGE': 'en_US:en', 18:57:52 INFO - 'LOGNAME': 'cltbld', 18:57:52 INFO - 'MAIL': '/var/mail/cltbld', 18:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:52 INFO - 'MOZ_NO_REMOTE': '1', 18:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:52 INFO - 'NO_EM_RESTART': '1', 18:57:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:57:52 INFO - 'PWD': '/builds/slave/test-pgo', 18:57:52 INFO - 'SHELL': '/bin/bash', 18:57:52 INFO - 'SHLVL': '1', 18:57:52 INFO - 'TERM': 'linux', 18:57:52 INFO - 'TMOUT': '86400', 18:57:52 INFO - 'USER': 'cltbld', 18:57:52 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:52 INFO - '_': '/tools/buildbot/bin/python'} 18:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:52 INFO - Downloading/unpacking psutil>=0.7.1 18:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:54 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:57:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:57:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:57:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:57:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:57:54 INFO - Installing collected packages: psutil 18:57:54 INFO - Running setup.py install for psutil 18:57:54 INFO - building 'psutil._psutil_linux' extension 18:57:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:57:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:57:54 INFO - building 'psutil._psutil_posix' extension 18:57:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:57:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:57:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:57:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:57:54 INFO - Successfully installed psutil 18:57:54 INFO - Cleaning up... 18:57:54 INFO - Return code: 0 18:57:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:57:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:57:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:57:54 INFO - Using env: {'DISPLAY': ':0', 18:57:54 INFO - 'HOME': '/home/cltbld', 18:57:54 INFO - 'LANG': 'en_US.UTF-8', 18:57:54 INFO - 'LANGUAGE': 'en_US:en', 18:57:54 INFO - 'LOGNAME': 'cltbld', 18:57:54 INFO - 'MAIL': '/var/mail/cltbld', 18:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:54 INFO - 'MOZ_NO_REMOTE': '1', 18:57:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:54 INFO - 'NO_EM_RESTART': '1', 18:57:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:57:54 INFO - 'PWD': '/builds/slave/test-pgo', 18:57:54 INFO - 'SHELL': '/bin/bash', 18:57:54 INFO - 'SHLVL': '1', 18:57:54 INFO - 'TERM': 'linux', 18:57:54 INFO - 'TMOUT': '86400', 18:57:54 INFO - 'USER': 'cltbld', 18:57:54 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:54 INFO - '_': '/tools/buildbot/bin/python'} 18:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:57:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:57:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:57:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:57:57 INFO - Installing collected packages: mozsystemmonitor 18:57:57 INFO - Running setup.py install for mozsystemmonitor 18:57:57 INFO - Successfully installed mozsystemmonitor 18:57:57 INFO - Cleaning up... 18:57:57 INFO - Return code: 0 18:57:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:57:57 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:57:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 18:57:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:57:57 INFO - Using env: {'DISPLAY': ':0', 18:57:57 INFO - 'HOME': '/home/cltbld', 18:57:57 INFO - 'LANG': 'en_US.UTF-8', 18:57:57 INFO - 'LANGUAGE': 'en_US:en', 18:57:57 INFO - 'LOGNAME': 'cltbld', 18:57:57 INFO - 'MAIL': '/var/mail/cltbld', 18:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:57:57 INFO - 'MOZ_NO_REMOTE': '1', 18:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:57:57 INFO - 'NO_EM_RESTART': '1', 18:57:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:57:57 INFO - 'PWD': '/builds/slave/test-pgo', 18:57:57 INFO - 'SHELL': '/bin/bash', 18:57:57 INFO - 'SHLVL': '1', 18:57:57 INFO - 'TERM': 'linux', 18:57:57 INFO - 'TMOUT': '86400', 18:57:57 INFO - 'USER': 'cltbld', 18:57:57 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:57:57 INFO - '_': '/tools/buildbot/bin/python'} 18:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:57:58 INFO - Downloading/unpacking blobuploader==1.2.4 18:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:57:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:57:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:57:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:57:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:57:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:57:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:58:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:58:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:00 INFO - Downloading docopt-0.6.1.tar.gz 18:58:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:58:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:58:00 INFO - Installing collected packages: blobuploader, requests, docopt 18:58:00 INFO - Running setup.py install for blobuploader 18:58:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:58:00 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:58:00 INFO - Running setup.py install for requests 18:58:00 INFO - Running setup.py install for docopt 18:58:01 INFO - Successfully installed blobuploader requests docopt 18:58:01 INFO - Cleaning up... 18:58:01 INFO - Return code: 0 18:58:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:58:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:58:01 INFO - Using env: {'DISPLAY': ':0', 18:58:01 INFO - 'HOME': '/home/cltbld', 18:58:01 INFO - 'LANG': 'en_US.UTF-8', 18:58:01 INFO - 'LANGUAGE': 'en_US:en', 18:58:01 INFO - 'LOGNAME': 'cltbld', 18:58:01 INFO - 'MAIL': '/var/mail/cltbld', 18:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:01 INFO - 'MOZ_NO_REMOTE': '1', 18:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:01 INFO - 'NO_EM_RESTART': '1', 18:58:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:01 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:01 INFO - 'SHELL': '/bin/bash', 18:58:01 INFO - 'SHLVL': '1', 18:58:01 INFO - 'TERM': 'linux', 18:58:01 INFO - 'TMOUT': '86400', 18:58:01 INFO - 'USER': 'cltbld', 18:58:01 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:58:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:01 INFO - '_': '/tools/buildbot/bin/python'} 18:58:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:58:01 INFO - Running setup.py (path:/tmp/pip-TcZeCL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:58:01 INFO - Running setup.py (path:/tmp/pip-YVNroA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:58:01 INFO - Running setup.py (path:/tmp/pip-gDecwC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:58:01 INFO - Running setup.py (path:/tmp/pip-XuK4TY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:58:01 INFO - Running setup.py (path:/tmp/pip-p52xvD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:58:01 INFO - Running setup.py (path:/tmp/pip-CmebD5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:58:01 INFO - Running setup.py (path:/tmp/pip-J6iHWb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:58:01 INFO - Running setup.py (path:/tmp/pip-yddSif-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:58:01 INFO - Running setup.py (path:/tmp/pip-NFyDsZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:58:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:58:01 INFO - Running setup.py (path:/tmp/pip-xibZkI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:58:02 INFO - Running setup.py (path:/tmp/pip-px3b24-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:58:02 INFO - Running setup.py (path:/tmp/pip-586ivm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:58:02 INFO - Running setup.py (path:/tmp/pip-VBzCg5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:58:02 INFO - Running setup.py (path:/tmp/pip-h6rr6N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:58:02 INFO - Running setup.py (path:/tmp/pip-NS5rUX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:58:02 INFO - Running setup.py (path:/tmp/pip-P0cKQg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:58:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:58:02 INFO - Running setup.py (path:/tmp/pip-KReN4w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:58:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:58:02 INFO - Running setup.py install for manifestparser 18:58:02 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:58:02 INFO - Running setup.py install for mozcrash 18:58:02 INFO - Running setup.py install for mozdebug 18:58:02 INFO - Running setup.py install for mozdevice 18:58:02 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:58:02 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:58:02 INFO - Running setup.py install for mozfile 18:58:03 INFO - Running setup.py install for mozhttpd 18:58:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for mozinfo 18:58:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for mozInstall 18:58:03 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for mozleak 18:58:03 INFO - Running setup.py install for mozlog 18:58:03 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for moznetwork 18:58:03 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for mozprocess 18:58:03 INFO - Running setup.py install for mozprofile 18:58:03 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for mozrunner 18:58:03 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:58:03 INFO - Running setup.py install for mozscreenshot 18:58:04 INFO - Running setup.py install for moztest 18:58:04 INFO - Running setup.py install for mozversion 18:58:04 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:58:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:58:04 INFO - Cleaning up... 18:58:04 INFO - Return code: 0 18:58:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 18:58:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:58:04 INFO - Using env: {'DISPLAY': ':0', 18:58:04 INFO - 'HOME': '/home/cltbld', 18:58:04 INFO - 'LANG': 'en_US.UTF-8', 18:58:04 INFO - 'LANGUAGE': 'en_US:en', 18:58:04 INFO - 'LOGNAME': 'cltbld', 18:58:04 INFO - 'MAIL': '/var/mail/cltbld', 18:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:04 INFO - 'MOZ_NO_REMOTE': '1', 18:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:04 INFO - 'NO_EM_RESTART': '1', 18:58:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:04 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:04 INFO - 'SHELL': '/bin/bash', 18:58:04 INFO - 'SHLVL': '1', 18:58:04 INFO - 'TERM': 'linux', 18:58:04 INFO - 'TMOUT': '86400', 18:58:04 INFO - 'USER': 'cltbld', 18:58:04 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:04 INFO - '_': '/tools/buildbot/bin/python'} 18:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:58:04 INFO - Running setup.py (path:/tmp/pip-eATa4t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:58:04 INFO - Running setup.py (path:/tmp/pip-yIQz_u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:58:04 INFO - Running setup.py (path:/tmp/pip-N84CkX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:58:04 INFO - Running setup.py (path:/tmp/pip-P5ptii-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:58:04 INFO - Running setup.py (path:/tmp/pip-LfQ41H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:58:04 INFO - Running setup.py (path:/tmp/pip-UTrMYH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 18:58:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:58:04 INFO - Running setup.py (path:/tmp/pip-ZLpZnr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:58:05 INFO - Running setup.py (path:/tmp/pip-FqhnA5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:58:05 INFO - Running setup.py (path:/tmp/pip-0kXBdB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:58:05 INFO - Running setup.py (path:/tmp/pip-OuKcDx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:58:05 INFO - Running setup.py (path:/tmp/pip-lUFdzi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:58:05 INFO - Running setup.py (path:/tmp/pip-JAYnOV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:58:05 INFO - Running setup.py (path:/tmp/pip-2ohTpu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:58:05 INFO - Running setup.py (path:/tmp/pip-pbpaIl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:58:05 INFO - Running setup.py (path:/tmp/pip-Q6xqG_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:58:05 INFO - Running setup.py (path:/tmp/pip-bLiPPs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 18:58:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:58:05 INFO - Running setup.py (path:/tmp/pip-aivbeK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 18:58:05 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:58:05 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.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:58:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:07 INFO - Downloading blessings-1.5.1.tar.gz 18:58:07 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 18:58:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:58:07 INFO - Installing collected packages: blessings 18:58:07 INFO - Running setup.py install for blessings 18:58:07 INFO - Successfully installed blessings 18:58:07 INFO - Cleaning up... 18:58:07 INFO - Return code: 0 18:58:07 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:58:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:58:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:58:07 INFO - Reading from file tmpfile_stdout 18:58:07 INFO - Current package versions: 18:58:07 INFO - argparse == 1.2.1 18:58:07 INFO - blessings == 1.5.1 18:58:07 INFO - blobuploader == 1.2.4 18:58:07 INFO - docopt == 0.6.1 18:58:07 INFO - manifestparser == 1.1 18:58:07 INFO - mozInstall == 1.12 18:58:07 INFO - mozcrash == 0.16 18:58:07 INFO - mozdebug == 0.1 18:58:07 INFO - mozdevice == 0.46 18:58:07 INFO - mozfile == 1.2 18:58:07 INFO - mozhttpd == 0.7 18:58:07 INFO - mozinfo == 0.8 18:58:07 INFO - mozleak == 0.1 18:58:07 INFO - mozlog == 3.0 18:58:07 INFO - moznetwork == 0.27 18:58:07 INFO - mozprocess == 0.22 18:58:07 INFO - mozprofile == 0.27 18:58:07 INFO - mozrunner == 6.11 18:58:07 INFO - mozscreenshot == 0.1 18:58:07 INFO - mozsystemmonitor == 0.0 18:58:07 INFO - moztest == 0.7 18:58:07 INFO - mozversion == 1.4 18:58:07 INFO - psutil == 3.1.1 18:58:07 INFO - requests == 1.2.3 18:58:07 INFO - wsgiref == 0.1.2 18:58:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 18:58:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:58:07 INFO - Using env: {'DISPLAY': ':0', 18:58:07 INFO - 'HOME': '/home/cltbld', 18:58:07 INFO - 'LANG': 'en_US.UTF-8', 18:58:07 INFO - 'LANGUAGE': 'en_US:en', 18:58:07 INFO - 'LOGNAME': 'cltbld', 18:58:07 INFO - 'MAIL': '/var/mail/cltbld', 18:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:07 INFO - 'MOZ_NO_REMOTE': '1', 18:58:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:07 INFO - 'NO_EM_RESTART': '1', 18:58:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:07 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:07 INFO - 'SHELL': '/bin/bash', 18:58:07 INFO - 'SHLVL': '1', 18:58:07 INFO - 'TERM': 'linux', 18:58:07 INFO - 'TMOUT': '86400', 18:58:07 INFO - 'USER': 'cltbld', 18:58:07 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:07 INFO - '_': '/tools/buildbot/bin/python'} 18:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:58:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:58:08 INFO - Cleaning up... 18:58:08 INFO - Return code: 0 18:58:08 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 18:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 18:58:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:58:08 INFO - Using env: {'DISPLAY': ':0', 18:58:08 INFO - 'HOME': '/home/cltbld', 18:58:08 INFO - 'LANG': 'en_US.UTF-8', 18:58:08 INFO - 'LANGUAGE': 'en_US:en', 18:58:08 INFO - 'LOGNAME': 'cltbld', 18:58:08 INFO - 'MAIL': '/var/mail/cltbld', 18:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:08 INFO - 'MOZ_NO_REMOTE': '1', 18:58:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:08 INFO - 'NO_EM_RESTART': '1', 18:58:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:08 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:08 INFO - 'SHELL': '/bin/bash', 18:58:08 INFO - 'SHLVL': '1', 18:58:08 INFO - 'TERM': 'linux', 18:58:08 INFO - 'TMOUT': '86400', 18:58:08 INFO - 'USER': 'cltbld', 18:58:08 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:08 INFO - '_': '/tools/buildbot/bin/python'} 18:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:08 INFO - Downloading/unpacking jsonschema 18:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:58:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 18:58:10 INFO - Downloading/unpacking functools32 (from jsonschema) 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:10 INFO - Downloading functools32-3.2.3-2.tar.gz 18:58:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:58:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 18:58:10 INFO - warning: no files found matching '*.txt' 18:58:10 INFO - no previously-included directories found matching 'build' 18:58:10 INFO - no previously-included directories found matching 'dist' 18:58:10 INFO - no previously-included directories found matching '.git*' 18:58:10 INFO - Installing collected packages: jsonschema, functools32 18:58:10 INFO - Running setup.py install for functools32 18:58:10 INFO - warning: no files found matching '*.txt' 18:58:10 INFO - no previously-included directories found matching 'build' 18:58:10 INFO - no previously-included directories found matching 'dist' 18:58:10 INFO - no previously-included directories found matching '.git*' 18:58:10 INFO - Successfully installed jsonschema functools32 18:58:10 INFO - Cleaning up... 18:58:10 INFO - Return code: 0 18:58:10 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 18:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:58:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:58:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:58:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f0a58944f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd78a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ca4600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, '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': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:58:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 18:58:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:58:10 INFO - Using env: {'DISPLAY': ':0', 18:58:10 INFO - 'HOME': '/home/cltbld', 18:58:10 INFO - 'LANG': 'en_US.UTF-8', 18:58:10 INFO - 'LANGUAGE': 'en_US:en', 18:58:10 INFO - 'LOGNAME': 'cltbld', 18:58:10 INFO - 'MAIL': '/var/mail/cltbld', 18:58:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:10 INFO - 'MOZ_NO_REMOTE': '1', 18:58:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:10 INFO - 'NO_EM_RESTART': '1', 18:58:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:10 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:10 INFO - 'SHELL': '/bin/bash', 18:58:10 INFO - 'SHLVL': '1', 18:58:10 INFO - 'TERM': 'linux', 18:58:10 INFO - 'TMOUT': '86400', 18:58:10 INFO - 'USER': 'cltbld', 18:58:10 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:58:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:10 INFO - '_': '/tools/buildbot/bin/python'} 18:58:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:58:10 INFO - Downloading/unpacking flake8 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:58:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 18:58:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:58:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 18:58:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 18:58:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:58:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:58:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:58:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 18:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:58:13 INFO - no previously-included directories found matching 'docs/_build' 18:58:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:58:13 INFO - Running setup.py install for pep8 18:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:58:13 INFO - no previously-included directories found matching 'docs/_build' 18:58:13 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 18:58:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:58:13 INFO - Cleaning up... 18:58:13 INFO - Return code: 0 18:58:13 INFO - Running post-action listener: _resource_record_post_action 18:58:13 INFO - Running post-action listener: _start_resource_monitoring 18:58:13 INFO - Starting resource monitoring. 18:58:13 INFO - ##### 18:58:13 INFO - ##### Running install step. 18:58:13 INFO - ##### 18:58:13 INFO - Running pre-action listener: _resource_record_pre_action 18:58:13 INFO - Running main action method: install 18:58:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:58:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:58:13 INFO - Reading from file tmpfile_stdout 18:58:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:58:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:58:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:58:13 INFO - Reading from file tmpfile_stdout 18:58:13 INFO - Output received: 18:58:13 INFO - Usage: mozinstall [options] installer 18:58:13 INFO - Options: 18:58:13 INFO - -h, --help show this help message and exit 18:58:13 INFO - -d DEST, --destination=DEST 18:58:13 INFO - Directory to install application into. [default: 18:58:13 INFO - "/builds/slave/test-pgo"] 18:58:13 INFO - --app=APP Application being installed. [default: firefox] 18:58:13 INFO - mkdir: /builds/slave/test-pgo/build/application 18:58:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:58:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:58:27 INFO - Reading from file tmpfile_stdout 18:58:27 INFO - Output received: 18:58:27 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:58:27 INFO - Running post-action listener: _resource_record_post_action 18:58:27 INFO - ##### 18:58:27 INFO - ##### Running run-tests step. 18:58:27 INFO - ##### 18:58:27 INFO - Running pre-action listener: _resource_record_pre_action 18:58:27 INFO - Running main action method: run_tests 18:58:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:58:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:58:27 INFO - Python 2.7.3 18:58:27 INFO - Return code: 0 18:58:27 INFO - grabbing minidump binary from tooltool 18:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:58:27 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 0x1d33350>, '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 0x1d34250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d346e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:58:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 18:58:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:58:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:58:27 INFO - Return code: 0 18:58:27 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:58:27 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:58:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:58:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:58:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:58:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 18:58:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 18:58:28 INFO - Return code: 0 18:58:28 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/1452127387/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:58:28 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/1452127387/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 18:58:28 INFO - Using env: {'DISPLAY': ':0', 18:58:28 INFO - 'HOME': '/home/cltbld', 18:58:28 INFO - 'LANG': 'en_US.UTF-8', 18:58:28 INFO - 'LANGUAGE': 'en_US:en', 18:58:28 INFO - 'LOGNAME': 'cltbld', 18:58:28 INFO - 'MAIL': '/var/mail/cltbld', 18:58:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:58:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:58:28 INFO - 'MOZ_NO_REMOTE': '1', 18:58:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:58:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:58:28 INFO - 'NO_EM_RESTART': '1', 18:58:28 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:58:28 INFO - 'PWD': '/builds/slave/test-pgo', 18:58:28 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:58:28 INFO - 'SHELL': '/bin/bash', 18:58:28 INFO - 'SHLVL': '1', 18:58:28 INFO - 'TERM': 'linux', 18:58:28 INFO - 'TMOUT': '86400', 18:58:28 INFO - 'USER': 'cltbld', 18:58:28 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991', 18:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:58:28 INFO - '_': '/tools/buildbot/bin/python'} 18:58:28 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/1452127387/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 18:58:29 INFO - mozversion INFO | application_buildid: 20160106164307 18:58:29 INFO - mozversion INFO | application_changeset: 9cc90fb04ea67efe590a0302ed880b1206ce14a7 18:58:29 INFO - mozversion INFO | application_display_name: Nightly 18:58:29 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:58:29 INFO - mozversion INFO | application_name: Firefox 18:58:29 INFO - mozversion INFO | application_remotingname: firefox 18:58:29 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:58:29 INFO - mozversion INFO | application_vendor: Mozilla 18:58:29 INFO - mozversion INFO | application_version: 44.0 18:58:29 INFO - mozversion INFO | platform_buildid: 20160106164307 18:58:29 INFO - mozversion INFO | platform_changeset: 9cc90fb04ea67efe590a0302ed880b1206ce14a7 18:58:29 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:58:29 INFO - mozversion INFO | platform_version: 44.0 18:58:29 INFO - 2016-01-06 18:58:29,077 DEBUG : using testdate: 1452135509 18:58:29 INFO - 2016-01-06 18:58:29,078 DEBUG : actual date: 1452135509 18:58:29 INFO - 2016-01-06 18:58:29,129 INFO : starting webserver on 'localhost:60455' 18:58:29 INFO - 2016-01-06 18:58:29,130 INFO : Starting test suite talos-linux64-ix-014 18:58:29 INFO - 2016-01-06 18:58:29,130 INFO : Starting test dromaeo_css 18:58:29 INFO - 2016-01-06 18:58:29,130 DEBUG : operating with platform_type : linux_ 18:58:29 INFO - 2016-01-06 18:58:29,131 INFO : Initialising browser for dromaeo_css test... 18:58:29 INFO - 2016-01-06 18:58:29,141 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNiLxEU/profile http://localhost:60455/getInfo.html 18:58:32 INFO - 2016-01-06 18:58:32,779 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:58:32 INFO - 2016-01-06 18:58:32,779 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:58:32 INFO - 2016-01-06 18:58:32,779 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:58:32 INFO - 2016-01-06 18:58:32,779 DEBUG : BROWSER_OUTPUT: __metrics 18:58:32 INFO - 2016-01-06 18:58:32,948 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:58:34 INFO - 2016-01-06 18:58:34,078 INFO : Browser initialized. 18:58:34 INFO - 2016-01-06 18:58:34,078 INFO : Running cycle 1/1 for dromaeo_css test... 18:58:34 INFO - 2016-01-06 18:58:34,078 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpNiLxEU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:58:45 INFO - 2016-01-06 18:58:45,643 DEBUG : BROWSER_OUTPUT: RSS: Main: 128634880 18:58:45 INFO - 2016-01-06 18:58:45,643 DEBUG : BROWSER_OUTPUT: 18:59:36 INFO - 2016-01-06 18:59:36,528 DEBUG : BROWSER_OUTPUT: 1452135576527 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:01:44 INFO - 2016-01-06 19:01:44,415 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60455/tests/dromaeo/cssquery-ext.html) 19:01:45 INFO - 2016-01-06 19:01:45,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 242049024 19:01:45 INFO - 2016-01-06 19:01:45,520 DEBUG : BROWSER_OUTPUT: 19:04:47 INFO - 2016-01-06 19:04:47,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/dromaeo/cssquery-ext.html (next: http://localhost:60455/tests/dromaeo/cssquery-jquery.html) 19:04:48 INFO - 2016-01-06 19:04:48,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 204918784 19:04:48 INFO - 2016-01-06 19:04:48,184 DEBUG : BROWSER_OUTPUT: 19:07:49 INFO - 2016-01-06 19:07:49,873 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60455/tests/dromaeo/cssquery-mootools.html) 19:07:50 INFO - 2016-01-06 19:07:50,438 DEBUG : BROWSER_OUTPUT: RSS: Main: 208650240 19:07:50 INFO - 2016-01-06 19:07:50,439 DEBUG : BROWSER_OUTPUT: 19:10:52 INFO - 2016-01-06 19:10:52,171 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60455/tests/dromaeo/cssquery-prototype.html) 19:10:52 INFO - 2016-01-06 19:10:52,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 190898176 19:10:52 INFO - 2016-01-06 19:10:52,741 DEBUG : BROWSER_OUTPUT: 19:13:55 INFO - 2016-01-06 19:13:55,129 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60455/tests/dromaeo/cssquery-yui.html) 19:13:55 INFO - 2016-01-06 19:13:55,696 DEBUG : BROWSER_OUTPUT: RSS: Main: 193785856 19:13:55 INFO - 2016-01-06 19:13:55,696 DEBUG : BROWSER_OUTPUT: 19:16:57 INFO - 2016-01-06 19:16:57,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/dromaeo/cssquery-yui.html (next: http://localhost:60455/tests/dromaeo/cssquery-dojo.html) 19:16:58 INFO - 2016-01-06 19:16:58,044 DEBUG : BROWSER_OUTPUT: RSS: Main: 218730496 19:16:58 INFO - 2016-01-06 19:16:58,044 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,045 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:16:58 INFO - 2016-01-06 19:16:58,045 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:16:58 INFO - 2016-01-06 19:16:58,046 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:16:58 INFO - 2016-01-06 19:16:58,046 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:16:58 INFO - 2016-01-06 19:16:58,046 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3314.2045908981845;3387.1024178592693;3426.1948442116113;3426.62307146214;3437.312537492501;5567.693776842272;5594.412586678396;5606.159076138581;5626.831195064145;5664.490195882369;5679.7160141992945;5728.79949201777;5742.310922689283;5750.654960702361;5756.3092428908585;5016.372953380827;5153.613478989072;5193.376794784613;5264.789408423678;5284.8150314739;5136.178211486163;5155.922661160085;5162.561182299491;5264.552513036378;5270.578353731711;5280.471952804727;5292.391374991887;5293.655912365684;5296.364436267634;5304.6286759926625;4951;5188.533032027136;5192.169252919514;5287.233351164079;5292.761825717833;5038.042771873333;5109.514596113363;5191.636585439021;5225.111731005738;5252.474752524755;5554.750036248379;5589.804356847531;5602.467765562265;5607.831765047055;5613.887722245532;5939.495142912858;6079.908801367938;6159.137720719103;6176.135341052256;6183.81448556544;6158.137860699506;6272.8118156455375;6298.716557754912;6302.842428939267;6305.432511073979;5815.621984570989;5831.9416805831825;5838.591298609099;5840.3575606683235;5854.619449735754;6115.755369785159;6122.510199184144;6225.750969961241;6270.404311590391;6309.242890853127;6056.242969628796;6084.300305464928;6094.664793436404;6103.877922441615;6117.388261173847;6175.682006199553;6184.938150618436;6193.287771906198;6193.535484838655;6221.222347206599;5812.82561523155;5923.052311630118;5996.970015149896;6125.836893610466;6127.988881834536;6114.973461912185;6143.477804386588;6146.385361463907;6147.323794382614;6149.7847575334645;4688.718676879398;4697.4832768395445;4699.600533954652;4701.670883038223;4709.481956984729;4080.469538959916;4090.2228576570355;4177.4778152730905;4189.413482112506;4196.181744559843;5701.771929122789;5718.942810571924;5720.11338242573;5720.628159169641;5743.0811070228565;5975.910361344583;6073.08903664445;6107.358727333655;6109.419605137505;6117;5675.460831221028;5737.3688894221605;5769.509591684669;5789.739461724233;5819.388964158787;5127.333446651912;5138.2806407103035;5143.17709166539;5146.356705411824;5149.150390185652;6115.755369785248;6196.783112591128;6299.118123462719;6303.086052522359;6320.494360451153;6019.548533859978;6129.908051379322;6136.662483563447;6140.4473597376455;6143.907841382472;6301;6303.2120984875055;6308.179936608211;6313.936860631335;6327.557071004985;5812.273465816773;5919.260092488439;5958.225430693982;6041.335453100243;6082;6194.969025154845;6197.938020619736;6210.7515699371515;6212.378762123933;6216.53375996793;6038.184845046038;6045.75816967334;6050.334463208956;6052.243469566304;6065.631087358344;5742.942570574241;5846;5899.705014749159;6036.63780173191;6060.606060606046;5945.227120474311;5959.433853783884;5968.970155149196;5970.313413957277;5974.342822289458;5985.850353741192;6072.271327440735;6078.3921607838865;6086.452219300284;6095.390460954047;5683.658980461186;5748.885022299447;5794.507466865195;5834.591578589458;5858.9121163181735 19:16:58 INFO - 2016-01-06 19:16:58,047 DEBUG : BROWSER_OUTPUT: |1;ext.html;599.9310079340877;619.6560908695673;633.1958412815721;635.1330433957647;639.865628218074;28681.426371472557;43114.784426077866;43513.7824310878;43531.56468435315;43609.56390436095;46355.536444635545;46533;46999.76500117503;47009;47049.764751176284;8294.170582941688;8399.832003359928;9160.267178625727;9332.766680832965;10736.409497477636;10773.892261077406;10857.23999320048;10870.61952831651;10875;10887.891121088767;10929.890701093007;10956.178286628496;11069.391183484906;11071.72320691981;11072.224944253905;10794.892051079507;10883.727906802314;10889.183311251687;10892.019718225398;10900.672979810579;10715.517801698943;10757.246992710514;10819.945900270448;10850.24048316618;10857.131429485702;42527.57472425267;42886.785566072285;42976.785116074534;42991.78504107491;42994;3561.982190089059;3650.963490365089;3658.5975542690285;3729.9813500932323;4362.411074504933;32583;32888.835555822305;32930;32993.175170620685;34156.14609634754;3198.6725508914005;3227.4190645683766;3280.1963518937773;3289.1448223461834;3327.5674162358737;327887;329859;462615;468091;536881;62395.6880215596;62494;62686;63172.36827631757;63231.367686323465;65443.345566544675;65653.67173164104;65682;65737;65899.67050164718;17721;17754.644907101785;17788.644227115386;17839.46481605528;17933.103344832707;14474.131552106906;14587.489437869624;14670.559883203521;14688.41246350134;14704.632384190267;21023.68464473034;21378.14487420517;21472.141114355563;21472.4631884201;21500.89249553742;7687.500312479671;7921;7929.1277959424415;8006.479578827358;8044.597770111471;1003.9146672532917;1078.1644225725126;1092.852464917226;1111.4165063341648;1219.036960800975;30341.848290758404;30782.846085769877;30903;30918.53622195669;31624.209394765316;1092.317301686446;1134.1493879590307;1186.7211205366732;1245.9252444853337;1302.3813688497887;13483;13682.794758078637;14781.778273325694;14942.178180200193;15445.613859653598;384929;389011;392108;393042;393043;57712.4228757707;57914.420855791745;57949.71025144847;58007.41992580105;58039;1520.9467668631544;1521.376235743362;1522.824875139351;1523.2612183091192;1542.2134711297094;30110.548341774454;30124.698753012188;30329.24176895595;30422.39155216839;30590.84704576463;51586.74206628943;52207;52224.21663674972;52245.216321755965;52252.21621675602;355142;358279;358287;358468;359774;30649;32833.671663283065;34848.30303393969;35477.29045419129;35814;45443.09113817771;47502.52497475119;48171.75914120407;48330.275045875045;48574.51425485841;39586.40620390754;40183.397249041875;40345;41073.1785364297;41096.79451602842;37012.259754805295;39495.802520987214;39561.60438395695;40124.3981340286;40886;40440.79779601083;40485;40577.391339129346;40660;40937;32551.67448325581;34739.305213896085;34812.477812833335;34827.129321766144;35926;25010;25591.488170236862;25750.484990299716;25808.225753227434;25941.74058259393 19:16:58 INFO - 2016-01-06 19:16:58,051 DEBUG : BROWSER_OUTPUT: |2;jquery.html;386.9226154769045;390.1397418691784;391.8119302734687;393.86805420184237;398.4760040546683;12917.160384574996;15649.92175039126;16759.664806703873;18660.16029278684;18715.62568748624;17513.56216094598;17518.824811751878;17800.910995445036;18967.14647840847;19321;5801.9129713054235;6063.878722425571;6064.72708728106;6094.1772860663905;6095.420935011167;4376.868693939187;4494.505604383531;4497;4498.707584007046;6058.666773327465;6384.968075159618;6406.93593064068;6613.537052406334;6613.735450581971;6621.56959797614;6572.7042283097135;6596.73613055482;6614.603123812587;6639.634820084893;6644.269130395653;5784.537237021028;5912.17229587858;5915.940840591625;5920.111983202511;5995.580309378345;23669.881650591808;24179.51640967171;24548.263552093467;25028.749712502824;25224.621630675556;2332.825038122148;2346.8591884486987;2366.7988221001237;2373.6320870265135;2426.0053378115063;14969.401223951028;15112.848871511364;15197;15363.846361536353;15619.921900390425;1778.4858970848193;1833.120102350879;1912.512309361118;1982.544014876572;2019.030865184705;62298.68850655718;69487;70440.64779676069;71576;72452;7607.9239207608325;7687.730929417444;7721.150673425918;7886.369090472748;7890;9518.428894266366;9540.809183816285;9541.045895410542;9544.904550954401;9566.282528810367;6447.709853056624;6588.670566471753;6613.371729685672;6630.303818099127;6642.43539299165;7071.434285257167;7123.536970096927;7151.356377926011;7172.82067948295;7209.639518024078;3700.481932529448;3740.8129593520216;3744.85020599173;3762.548494180716;3776.263628592398;1239.7892358299111;1269.9301538415475;1278.0606254403006;1280.6862318731971;1313.4483516922915;530.5013287509729;531.44994930247;533.9012282727708;540.3812634533438;555.919391688203;11679.708007299716;13074.346282686018;13643.658908527366;13699.45202191921;13890.236037018061;563.6674362126365;567.9119736440858;578.8813293274868;582.0628787651877;593.4214141212284;7190.712371505186;7234;7353.1911489736085;7451.993980812629;7486.438517111238;614746;616176;620038;621668;636690;87492;88397.1160288389;90055;90214.54892725495;91861.54069229745;594.4531031451075;600.447588218831;600.9098635204755;602.1359349333633;604.5707547641224;10928.508217130408;12214.57248996281;12430.378481076092;12459.75080498403;12507.812382814447;39444.40833387559;39956.60043399645;41334.586654133076;42042.369364458944;42272.36591451192;52571.474285256656;53650;55079.724601376736;55116.44883551113;56596.71701641465;16323.347066117698;16468.917655411646;16674.58313542171;17032.233549490054;17081.743773843154;14901.552953411416;20607.690884637042;20915.790842091385;21039;21456.463588410414;13707.520236791665;16182.433614823425;16358.754618680967;16395.508134755975;16589.17054147312;15756.92121539385;16323.836761632232;16413.67172656564;16603.584910377336;17188.570285742957;17356.305747769948;17488;17544.736828947833;17723.11384430799;17778.46664600064;16264.268107934935;16609.833901661314;16664.66670666604;16842.57893552671;16885;17271.827281727023;17365.305387784854;17437.389691360742;17697.557561061076;17765 19:16:58 INFO - 2016-01-06 19:16:58,052 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1554.5414102839654;1560.4616407339463;1777.6355847051357;1843.7142242952343;1862.9660538401185;7086.716531338753;7295.270472952702;8061.072976607685;8178.304844088252;8208.999999999993;7927.524348539092;8083.070446898594;8160.836783264331;8163.4693744906535;8194.959025204866;2337.474068334624;2362.3267368799898;2362.9527409451803;2364.9527009459803;2366;2123.3948324727457;2164.0802658869998;2352.3413444235644;2368.336865677613;2414.9758502415016;2336.0889253191267;2348.095983046531;2354.5290941811622;2355.5877721398774;2356.4108972756812;2330.9650355244685;2352.847064940773;2356.7525409832065;2361.622140457527;2361.9409514762183;2379.9048038078454;2421.987890060556;2424.854508729482;2429.246933450618;2432.8540287582623;8148.959255203686;8158.510489370657;8190.918090819075;8197.508149511048;8198.83602327956;4993.575546078588;5044.722540260284;5055.342805435306;5055.494450554915;5059.797608095671;3168.825714585697;3208.98395508021;3277.9836100819343;3291.7037466628117;3303.339332133583;1011.6004178349584;1049.4437947887632;1060.6075751971744;1062.6493257225097;1090.0462095666292;11367.545298188063;11402.201845870792;11409.543618255342;11410;11413;4571.1086338164405;4580.450345958439;4623.214053610895;4648.930266046012;4666.393368862026;9766.9023309766;9824.90175098254;9835.459049752333;9837.114659680663;9858.309918305793;5143.742812859342;5172.534471897472;5184.611154163453;5184.662996905265;5188.429272779991;4759.881002974953;4768.785404656729;4771.33201351811;4771.498992605796;4771.809127634856;3769.246150769802;3970.5433875104154;4062.857799976987;4088.509378874495;4121.5466298707115;1238.1271203801298;1306.7713150198676;1340.187167508437;1346.2326473909777;1355.9186448813102;1118.5805323003874;1118.5917140243753;1138.3397629374942;1262.1985039499984;1324.8675132486867;7891.092524359658;8041.155678653774;8093.1502192270145;8111.918880811117;8119.7158099467415;1189.2804853063772;1195.58154645875;1232.7411243638758;1239.3369547292154;1263.7030297879992;6465.3857883501;6520.054592084121;6575.178102737158;6575.868482630321;6585.078089067534;9748.707538773848;9896.406215627085;9915.702528924145;9928.205743540499;9955.452450115168;9169.40398874071;9894.455804930798;9931.950340248397;9935.552900119512;9938.602455901828;7839.05931288249;7894.368450523944;7915.168907264654;7919.6832126715435;7922.128565857636;7864.646090925808;7924.20757924203;7960.641771120429;7976.481528700616;8026.43814932949;8454.450460720034;8914.55427228649;9001.414908030956;9028.90971090307;9046.864297035681;8857.778555535906;9033.457992520469;9067.546622668708;9163.816723665355;9174.128457796498;7482.2517748224745;7572.962135189288;7579.583122928292;7581.12817026027;7581.166071732216;6859.245482996766;7041.894371584328;7887.092984306878;8051.718189863561;8053.315468185036;7373.77878663641;7493.063367079115;7623.16145224036;7693.422993275639;7741.303282704584;7431.4054875608745;7469.77590672259;7562.432817538596;7632.045994250719;7663.31030207284;7073.363397294268;7450.664720087718;7750.651220694969;7787.29914307715;7788.065432148179;7001.054857594363;7045;7048.964755176191;7060.293970602898;7061.6822243000215;7048.964755176191;7117.786466405809;7134.714611415693;7144.821379465347;7150.5352152109945 19:16:58 INFO - 2016-01-06 19:16:58,054 DEBUG : BROWSER_OUTPUT: |4;prototype.html;369.6802266039873;370.57939238963786;371.71563753728395;379.0240131660973;382.6498753640419;5863.853403664911;5880.823575292743;5927.614705044169;5934.910976335353;6003.759849606022;5758.42415758424;5834.241548598687;5851.3563508014195;5871.530277577795;5874.559408044403;4617.399738034051;4621.214393553088;4627.398438203046;4633.582977532022;4634.86095417138;4484.910301793962;4562.543745625444;4574.611158051553;4629.398178236841;4643.8839029024375;4578.702384345007;4588.357629931812;4594.379758732578;4599.724016559;4609.654275929302;4548.499665036843;4577.084583083397;4579.084183163364;4585.816567337302;4602.79287432063;4098.057446787226;4106.691998100155;4106.77412742299;4117.094239267386;4120.17596480705;5532.114861622121;5599.608027438081;5625.662460252398;5676.403977582336;5692.544596432275;5424.538914192299;5433.4566543345745;5437.32033495813;5447.128459446509;5450.5367043801325;6005.219321488223;6291.874162516724;6318.652474113922;6328.525360597973;6330.987042073246;4303.892402689927;4491.438570178728;4516.616087632555;4521.954780452186;4564.3838081859185;6372.426481616631;6390.169277993831;6399.328070552571;6400.167978162855;6407.583507072025;4648.86053418398;4673.228917228687;4673.439187297478;4674.041821426599;4677.976610116928;4607.792649330788;4691.413573303337;4692.694974826693;4738.123447162286;4740.19416699162;5327.627066105335;5327.840164795062;5328.3605967284175;5328.547073498796;5329.626926115135;6295.748170073238;6297.1813664223355;6300.684965751694;6303.432691057735;6305.432511074026;1677.9244933978;1697.5925777813236;1697.983020169807;1700.0904516083835;1700.1244359155046;1128.5260190719916;1129.7966366054188;1132.8300754886832;1157.3345326437332;1185.8636256830403;6063.514918806485;6071.210742603522;6077.088436734525;6077.574569780161;6084.209052823193;6425.678716064178;6428.903566446507;6430.485561155097;6431.710573024225;6433.131527243762;5957.344692083867;6058.091286307001;6058.818235452855;6062.514998800086;6064.545159112996;3544.486049522805;3584.6415358463946;3585.6235095315137;3589.10272431892;3589.9282014359565;6655.933440665532;6659.300773418819;6666.333366663392;6671.299513551105;6684;6561.212654481493;6626.403623673892;6633.2703402625675;6634.966825165843;6661.2006559213205;6554.770583029571;6578.671066446658;6608.173978252815;6608.306127856602;6608.43828274602;6305.779297724557;6407.967960160169;6408.102865598913;6494.318096599789;6495.870082598227;6429.035644653152;6432.614043157426;6432.903506447501;6445.097686323825;6453.483721302191;6656.5340426169705;6663.866722665616;6672.299408562031;6672.866542669217;6675.0654908313845;4479.865604031885;4566.840160594364;4570.565796249351;4572.039871626863;4580.88547786308;6518.837029074311;6598.8350291243105;6601.306862779531;6604.405603495708;6604.7027883746305;6443.130177425987;6453.483721302191;6455.322191169859;6458.257300410513;6469.967650161719;6328.367163283635;6413.486921046212;6437.871242575216;6440.935590644221;6460.967695161494;6340.1123842661145;6343.841403964753;6347.746090156526;6355.237371515448;6362.936370636235;6484.546081774419;6485.935140648722;6492.610443373413;6495.870082598416;6498.057781621544;6149.661768602591;6219.782307619393;6231.626102433868;6319.936800631935;6337.334579869047 19:16:58 INFO - 2016-01-06 19:16:58,057 DEBUG : BROWSER_OUTPUT: |5;yui.html;826.1036775099017;877.622622272423;879.4679219072461;937.8686983822266;942.5899733615878;13395.330233488323;13707.93146034271;13711;13715.65710857229;13740.450381984721;12865.292408917507;12888.93555532221;12891.419886105125;12893.290869002201;12901.354932253396;742.5581778841594;748.3751067858345;891.687909231767;893.3389291923994;893.4907102951321;883.5847151838625;890.7773056735816;896.4621227263655;898.0301274623424;903.4714691905243;787.1262898183012;938.4697645830119;939.1078475448352;939.5208443693731;947.2895328503622;796.191865256762;941.181172380027;944.1078181118811;944.5749412764284;960.2701946520694;698.0715648187897;698.4970821008866;700.5586480517293;702.9894551581731;703.2229386527915;13199.604011879659;13301.73396532064;13325.933370333183;13328.533501327503;13328.933355333258;11587.362695051768;11919.582814601534;12017;12208.32854193019;12251;9958.502074896227;10013;10095.495225238783;10113.190944724478;10154.390736555757;3402.625711171769;3423.058658868831;3424.1610805352598;3425.246445781924;3427.9828600857086;52298;52325;52378;54015.72992135053;54080;9898.851517227247;9918.15695665862;9929.453880036523;9937.254705897087;10002.749931251778;19533;19720.40838774839;19724.90137549303;19743.901280493792;19782;7201.639918004079;7322.231165727522;7329.120505539263;7479.326860582573;7585.127710313274;4036.455078564415;4224.493060832659;4297.097609501977;4311.827526898889;4327.545607711208;3130.358276553301;3161.351922855809;3163.557102005721;3163.9050828475183;3172.127664892173;488.5578551410979;491.7762418099756;495.1285737102725;496.81617801412983;497.5024975024975;645.612632420544;666.167290886392;689.7654797368918;691.8132104331802;742.5433358484493;8685.435446695945;8969.14793094655;9042.231410330196;9046.502442365732;9069.77325566853;613.7238242790762;697.8594692907282;726.2447055062781;741.8961345411647;781.7654703589014;4057.8376864925663;4154.356074808409;4290.52804191545;4421.977890110529;4478.708883922534;64381.67809161018;64678.353216467236;65364.673176634766;65464.67267663631;72138.27861721421;58795.70602146962;59452;59872;60152.39847601556;65842.34157658459;59180.40819591835;59325;59682;60074.699626501584;61271.6936415324;9259.074092590687;9473.052694730472;9478.857817132886;9565.856512152463;9634.9036509634;40953;41908;42632.147357053305;43013.35479967866;43343;53899.73050134881;54313.45686543084;54340.184897227366;55278.44721552733;55869.720651396485;12664.6833829155;12731.681707957005;12745.745085098431;12798.936005319914;12928.93535532354;12617;12858.807117893426;12942.611721648365;13084.214947103204;13457.932710336385;12070.818937716116;12413.317267550372;12636;12728.936355318165;12866.614001579968;12585.181963172365;12611.873881261437;12635.684107897376;12849;13317.467301307839;12259.38703064861;12379.69050773738;12536.74926501483;12849.100562960502;12994.610161695164;10779.461026948778;10882.129429645767;11128.554857805597;11286.492107855001;11507.194496385508;10035.648752293635;10185.236107291834;10331.793364132825;10455.738606534593;10473.581056757948 19:16:58 INFO - 2016-01-06 19:16:58,057 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:16:58 INFO - 2016-01-06 19:16:58,057 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:16:58 INFO - 2016-01-06 19:16:58,057 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,861 19:16:58 INFO - 2016-01-06 19:16:58,058 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:16:58 INFO - 2016-01-06 19:16:58,058 DEBUG : BROWSER_OUTPUT: __startTimestamp1452136618044__endTimestamp 19:16:58 INFO - 2016-01-06 19:16:58,058 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,058 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:16:58 INFO - 2016-01-06 19:16:58,058 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:16:58 INFO - 2016-01-06 19:16:58,058 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,059 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5677.61 Median:5854.62 stddev:642.13 (11.0%) stddev-sans-first:616.76 19:16:58 INFO - 2016-01-06 19:16:58,059 DEBUG : BROWSER_OUTPUT: Values: 3314.2 3387.1 3426.2 3426.6 3437.3 5567.7 5594.4 5606.2 5626.8 5664.5 5679.7 5728.8 5742.3 5750.7 5756.3 5016.4 5153.6 5193.4 5264.8 5284.8 5136.2 5155.9 5162.6 5264.6 5270.6 5280.5 5292.4 5293.7 5296.4 5304.6 4951.0 5188.5 5192.2 5287.2 5292.8 5038.0 5109.5 5191.6 5225.1 5252.5 5554.8 5589.8 5602.5 5607.8 5613.9 5939.5 6079.9 6159.1 6176.1 6183.8 6158.1 6272.8 6298.7 6302.8 6305.4 5815.6 5831.9 5838.6 5840.4 5854.6 6115.8 6122.5 6225.8 6270.4 6309.2 6056.2 6084.3 6094.7 6103.9 6117.4 6175.7 6184.9 6193.3 6193.5 6221.2 5812.8 5923.1 5997.0 6125.8 6128.0 6115.0 6143.5 6146.4 6147.3 6149.8 4688.7 4697.5 4699.6 4701.7 4709.5 4080.5 4090.2 4177.5 4189.4 4196.2 5701.8 5718.9 5720.1 5720.6 5743.1 5975.9 6073.1 6107.4 6109.4 6117.0 5675.5 5737.4 5769.5 5789.7 5819.4 5127.3 5138.3 5143.2 5146.4 5149.2 6115.8 6196.8 6299.1 6303.1 6320.5 6019.5 6129.9 6136.7 6140.4 6143.9 6301.0 6303.2 6308.2 6313.9 6327.6 5812.3 5919.3 5958.2 6041.3 6082.0 6195.0 6197.9 6210.8 6212.4 6216.5 6038.2 6045.8 6050.3 6052.2 6065.6 5742.9 5846.0 5899.7 6036.6 6060.6 5945.2 5959.4 5969.0 5970.3 5974.3 5985.9 6072.3 6078.4 6086.5 6095.4 5683.7 5748.9 5794.5 5834.6 5858.9 19:16:58 INFO - 2016-01-06 19:16:58,059 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,059 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56642.14 Median:35162.80 stddev:104052.87 (295.9%) stddev-sans-first:104259.69 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: Values: 599.9 619.7 633.2 635.1 639.9 28681.4 43114.8 43513.8 43531.6 43609.6 46355.5 46533.0 46999.8 47009.0 47049.8 8294.2 8399.8 9160.3 9332.8 10736.4 10773.9 10857.2 10870.6 10875.0 10887.9 10929.9 10956.2 11069.4 11071.7 11072.2 10794.9 10883.7 10889.2 10892.0 10900.7 10715.5 10757.2 10819.9 10850.2 10857.1 42527.6 42886.8 42976.8 42991.8 42994.0 3562.0 3651.0 3658.6 3730.0 4362.4 32583.0 32888.8 32930.0 32993.2 34156.1 3198.7 3227.4 3280.2 3289.1 3327.6 327887.0 329859.0 462615.0 468091.0 536881.0 62395.7 62494.0 62686.0 63172.4 63231.4 65443.3 65653.7 65682.0 65737.0 65899.7 17721.0 17754.6 17788.6 17839.5 17933.1 14474.1 14587.5 14670.6 14688.4 14704.6 21023.7 21378.1 21472.1 21472.5 21500.9 7687.5 7921.0 7929.1 8006.5 8044.6 1003.9 1078.2 1092.9 1111.4 1219.0 30341.8 30782.8 30903.0 30918.5 31624.2 1092.3 1134.1 1186.7 1245.9 1302.4 13483.0 13682.8 14781.8 14942.2 15445.6 384929.0 389011.0 392108.0 393042.0 393043.0 57712.4 57914.4 57949.7 58007.4 58039.0 1520.9 1521.4 1522.8 1523.3 1542.2 30110.5 30124.7 30329.2 30422.4 30590.8 51586.7 52207.0 52224.2 52245.2 52252.2 355142.0 358279.0 358287.0 358468.0 359774.0 30649.0 32833.7 34848.3 35477.3 35814.0 45443.1 47502.5 48171.8 48330.3 48574.5 39586.4 40183.4 40345.0 41073.2 41096.8 37012.3 39495.8 39561.6 40124.4 40886.0 40440.8 40485.0 40577.4 40660.0 40937.0 32551.7 34739.3 34812.5 34827.1 35926.0 25010.0 25591.5 25750.5 25808.2 25941.7 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32676.75 Median:20177.54 stddev:101690.95 (504.0%) stddev-sans-first:101947.31 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: Values: 386.9 390.1 391.8 393.9 398.5 12917.2 15649.9 16759.7 18660.2 18715.6 17513.6 17518.8 17800.9 18967.1 19321.0 5801.9 6063.9 6064.7 6094.2 6095.4 4376.9 4494.5 4497.0 4498.7 6058.7 6385.0 6406.9 6613.5 6613.7 6621.6 6572.7 6596.7 6614.6 6639.6 6644.3 5784.5 5912.2 5915.9 5920.1 5995.6 23669.9 24179.5 24548.3 25028.7 25224.6 2332.8 2346.9 2366.8 2373.6 2426.0 14969.4 15112.8 15197.0 15363.8 15619.9 1778.5 1833.1 1912.5 1982.5 2019.0 62298.7 69487.0 70440.6 71576.0 72452.0 7607.9 7687.7 7721.2 7886.4 7890.0 9518.4 9540.8 9541.0 9544.9 9566.3 6447.7 6588.7 6613.4 6630.3 6642.4 7071.4 7123.5 7151.4 7172.8 7209.6 3700.5 3740.8 3744.9 3762.5 3776.3 1239.8 1269.9 1278.1 1280.7 1313.4 530.5 531.4 533.9 540.4 555.9 11679.7 13074.3 13643.7 13699.5 13890.2 563.7 567.9 578.9 582.1 593.4 7190.7 7234.0 7353.2 7452.0 7486.4 614746.0 616176.0 620038.0 621668.0 636690.0 87492.0 88397.1 90055.0 90214.5 91861.5 594.5 600.4 600.9 602.1 604.6 10928.5 12214.6 12430.4 12459.8 12507.8 39444.4 39956.6 41334.6 42042.4 42272.4 52571.5 53650.0 55079.7 55116.4 56596.7 16323.3 16468.9 16674.6 17032.2 17081.7 14901.6 20607.7 20915.8 21039.0 21456.5 13707.5 16182.4 16358.8 16395.5 16589.2 15756.9 16323.8 16413.7 16603.6 17188.6 17356.3 17488.0 17544.7 17723.1 17778.5 16264.3 16609.8 16664.7 16842.6 16885.0 17271.8 17365.3 17437.4 17697.6 17765.0 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5846.34 Median:6930.15 stddev:3005.81 (43.4%) stddev-sans-first:2996.93 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: Values: 1554.5 1560.5 1777.6 1843.7 1863.0 7086.7 7295.3 8061.1 8178.3 8209.0 7927.5 8083.1 8160.8 8163.5 8195.0 2337.5 2362.3 2363.0 2365.0 2366.0 2123.4 2164.1 2352.3 2368.3 2415.0 2336.1 2348.1 2354.5 2355.6 2356.4 2331.0 2352.8 2356.8 2361.6 2361.9 2379.9 2422.0 2424.9 2429.2 2432.9 8149.0 8158.5 8190.9 8197.5 8198.8 4993.6 5044.7 5055.3 5055.5 5059.8 3168.8 3209.0 3278.0 3291.7 3303.3 1011.6 1049.4 1060.6 1062.6 1090.0 11367.5 11402.2 11409.5 11410.0 11413.0 4571.1 4580.5 4623.2 4648.9 4666.4 9766.9 9824.9 9835.5 9837.1 9858.3 5143.7 5172.5 5184.6 5184.7 5188.4 4759.9 4768.8 4771.3 4771.5 4771.8 3769.2 3970.5 4062.9 4088.5 4121.5 1238.1 1306.8 1340.2 1346.2 1355.9 1118.6 1118.6 1138.3 1262.2 1324.9 7891.1 8041.2 8093.2 8111.9 8119.7 1189.3 1195.6 1232.7 1239.3 1263.7 6465.4 6520.1 6575.2 6575.9 6585.1 9748.7 9896.4 9915.7 9928.2 9955.5 9169.4 9894.5 9932.0 9935.6 9938.6 7839.1 7894.4 7915.2 7919.7 7922.1 7864.6 7924.2 7960.6 7976.5 8026.4 8454.5 8914.6 9001.4 9028.9 9046.9 8857.8 9033.5 9067.5 9163.8 9174.1 7482.3 7573.0 7579.6 7581.1 7581.2 6859.2 7041.9 7887.1 8051.7 8053.3 7373.8 7493.1 7623.2 7693.4 7741.3 7431.4 7469.8 7562.4 7632.0 7663.3 7073.4 7450.7 7750.7 7787.3 7788.1 7001.1 7045.0 7049.0 7060.3 7061.7 7049.0 7117.8 7134.7 7144.8 7150.5 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5313.62 Median:6004.49 stddev:1555.65 (25.9%) stddev-sans-first:1515.11 19:16:58 INFO - 2016-01-06 19:16:58,061 DEBUG : BROWSER_OUTPUT: Values: 369.7 370.6 371.7 379.0 382.6 5863.9 5880.8 5927.6 5934.9 6003.8 5758.4 5834.2 5851.4 5871.5 5874.6 4617.4 4621.2 4627.4 4633.6 4634.9 4484.9 4562.5 4574.6 4629.4 4643.9 4578.7 4588.4 4594.4 4599.7 4609.7 4548.5 4577.1 4579.1 4585.8 4602.8 4098.1 4106.7 4106.8 4117.1 4120.2 5532.1 5599.6 5625.7 5676.4 5692.5 5424.5 5433.5 5437.3 5447.1 5450.5 6005.2 6291.9 6318.7 6328.5 6331.0 4303.9 4491.4 4516.6 4522.0 4564.4 6372.4 6390.2 6399.3 6400.2 6407.6 4648.9 4673.2 4673.4 4674.0 4678.0 4607.8 4691.4 4692.7 4738.1 4740.2 5327.6 5327.8 5328.4 5328.5 5329.6 6295.7 6297.2 6300.7 6303.4 6305.4 1677.9 1697.6 1698.0 1700.1 1700.1 1128.5 1129.8 1132.8 1157.3 1185.9 6063.5 6071.2 6077.1 6077.6 6084.2 6425.7 6428.9 6430.5 6431.7 6433.1 5957.3 6058.1 6058.8 6062.5 6064.5 3544.5 3584.6 3585.6 3589.1 3589.9 6655.9 6659.3 6666.3 6671.3 6684.0 6561.2 6626.4 6633.3 6635.0 6661.2 6554.8 6578.7 6608.2 6608.3 6608.4 6305.8 6408.0 6408.1 6494.3 6495.9 6429.0 6432.6 6432.9 6445.1 6453.5 6656.5 6663.9 6672.3 6672.9 6675.1 4479.9 4566.8 4570.6 4572.0 4580.9 6518.8 6598.8 6601.3 6604.4 6604.7 6443.1 6453.5 6455.3 6458.3 6470.0 6328.4 6413.5 6437.9 6440.9 6461.0 6340.1 6343.8 6347.7 6355.2 6362.9 6484.5 6485.9 6492.6 6495.9 6498.1 6149.7 6219.8 6231.6 6319.9 6337.3 19:16:58 INFO - 2016-01-06 19:16:58,062 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,063 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15608.39 Median:490.17 stddev:19158.14 (3908.5%) stddev-sans-first:19179.72 19:16:58 INFO - 2016-01-06 19:16:58,063 DEBUG : BROWSER_OUTPUT: Values: 826.1 877.6 879.5 937.9 942.6 13395.3 13707.9 13711.0 13715.7 13740.5 12865.3 12888.9 12891.4 12893.3 12901.4 742.6 748.4 891.7 893.3 893.5 883.6 890.8 896.5 898.0 903.5 787.1 938.5 939.1 939.5 947.3 796.2 941.2 944.1 944.6 960.3 698.1 698.5 700.6 703.0 703.2 13199.6 13301.7 13325.9 13328.5 13328.9 11587.4 11919.6 12017.0 12208.3 12251.0 9958.5 10013.0 10095.5 10113.2 10154.4 3402.6 3423.1 3424.2 3425.2 3428.0 52298.0 52325.0 52378.0 54015.7 54080.0 9898.9 9918.2 9929.5 9937.3 10002.7 19533.0 19720.4 19724.9 19743.9 19782.0 7201.6 7322.2 7329.1 7479.3 7585.1 4036.5 4224.5 4297.1 4311.8 4327.5 3130.4 3161.4 3163.6 3163.9 3172.1 488.6 491.8 495.1 496.8 497.5 645.6 666.2 689.8 691.8 742.5 8685.4 8969.1 9042.2 9046.5 9069.8 613.7 697.9 726.2 741.9 781.8 4057.8 4154.4 4290.5 4422.0 4478.7 64381.7 64678.4 65364.7 65464.7 72138.3 58795.7 59452.0 59872.0 60152.4 65842.3 59180.4 59325.0 59682.0 60074.7 61271.7 9259.1 9473.1 9478.9 9565.9 9634.9 40953.0 41908.0 42632.1 43013.4 43343.0 53899.7 54313.5 54340.2 55278.4 55869.7 12664.7 12731.7 12745.7 12798.9 12928.9 12617.0 12858.8 12942.6 13084.2 13457.9 12070.8 12413.3 12636.0 12728.9 12866.6 12585.2 12611.9 12635.7 12849.0 13317.5 12259.4 12379.7 12536.7 12849.1 12994.6 10779.5 10882.1 11128.6 11286.5 11507.2 10035.6 10185.2 10331.8 10455.7 10473.6 19:16:58 INFO - 2016-01-06 19:16:58,063 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:16:58 INFO - 2016-01-06 19:16:58,063 DEBUG : BROWSER_OUTPUT: 19:16:58 INFO - 2016-01-06 19:16:58,630 INFO : Browser exited with error code: 0 19:16:58 INFO - 2016-01-06 19:16:58,650 INFO : Completed test dromaeo_css (00:18:29) 19:16:58 INFO - 2016-01-06 19:16:58,651 INFO : Starting test kraken 19:16:58 INFO - 2016-01-06 19:16:58,651 DEBUG : operating with platform_type : linux_ 19:16:58 INFO - 2016-01-06 19:16:58,651 INFO : Initialising browser for kraken test... 19:16:58 INFO - 2016-01-06 19:16:58,657 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfdqZ07/profile http://localhost:60455/getInfo.html 19:17:01 INFO - 2016-01-06 19:17:01,958 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:17:01 INFO - 2016-01-06 19:17:01,958 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:17:01 INFO - 2016-01-06 19:17:01,958 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:17:01 INFO - 2016-01-06 19:17:01,958 DEBUG : BROWSER_OUTPUT: __metrics 19:17:02 INFO - 2016-01-06 19:17:02,128 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:17:03 INFO - 2016-01-06 19:17:03,262 INFO : Browser initialized. 19:17:03 INFO - 2016-01-06 19:17:03,262 INFO : Running cycle 1/1 for kraken test... 19:17:03 INFO - 2016-01-06 19:17:03,263 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpfdqZ07/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:17:14 INFO - 2016-01-06 19:17:14,787 DEBUG : BROWSER_OUTPUT: RSS: Main: 128831488 19:17:14 INFO - 2016-01-06 19:17:14,787 DEBUG : BROWSER_OUTPUT: 19:17:17 INFO - 2016-01-06 19:17:17,203 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60455/tests/kraken/driver.html?testName=audio-beat-detection) 19:17:17 INFO - 2016-01-06 19:17:17,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 164630528 19:17:17 INFO - 2016-01-06 19:17:17,772 DEBUG : BROWSER_OUTPUT: 19:17:20 INFO - 2016-01-06 19:17:20,279 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60455/tests/kraken/driver.html?testName=audio-dft) 19:17:20 INFO - 2016-01-06 19:17:20,845 DEBUG : BROWSER_OUTPUT: RSS: Main: 161845248 19:17:20 INFO - 2016-01-06 19:17:20,846 DEBUG : BROWSER_OUTPUT: 19:17:23 INFO - 2016-01-06 19:17:23,613 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60455/tests/kraken/driver.html?testName=audio-fft) 19:17:24 INFO - 2016-01-06 19:17:24,175 DEBUG : BROWSER_OUTPUT: RSS: Main: 158953472 19:17:24 INFO - 2016-01-06 19:17:24,175 DEBUG : BROWSER_OUTPUT: 19:17:26 INFO - 2016-01-06 19:17:26,120 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60455/tests/kraken/driver.html?testName=audio-oscillator) 19:17:26 INFO - 2016-01-06 19:17:26,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 158748672 19:17:26 INFO - 2016-01-06 19:17:26,681 DEBUG : BROWSER_OUTPUT: 19:17:28 INFO - 2016-01-06 19:17:28,799 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60455/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:17:29 INFO - 2016-01-06 19:17:29,351 DEBUG : BROWSER_OUTPUT: RSS: Main: 156090368 19:17:29 INFO - 2016-01-06 19:17:29,352 DEBUG : BROWSER_OUTPUT: 19:17:32 INFO - 2016-01-06 19:17:32,219 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60455/tests/kraken/driver.html?testName=imaging-darkroom) 19:17:32 INFO - 2016-01-06 19:17:32,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 156012544 19:17:32 INFO - 2016-01-06 19:17:32,789 DEBUG : BROWSER_OUTPUT: 19:17:36 INFO - 2016-01-06 19:17:36,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60455/tests/kraken/driver.html?testName=imaging-desaturate) 19:17:36 INFO - 2016-01-06 19:17:36,729 DEBUG : BROWSER_OUTPUT: RSS: Main: 157220864 19:17:36 INFO - 2016-01-06 19:17:36,729 DEBUG : BROWSER_OUTPUT: 19:17:39 INFO - 2016-01-06 19:17:39,433 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60455/tests/kraken/driver.html?testName=json-parse-financial) 19:17:39 INFO - 2016-01-06 19:17:39,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 156839936 19:17:39 INFO - 2016-01-06 19:17:39,995 DEBUG : BROWSER_OUTPUT: 19:17:41 INFO - 2016-01-06 19:17:41,504 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60455/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:17:42 INFO - 2016-01-06 19:17:42,058 DEBUG : BROWSER_OUTPUT: RSS: Main: 162521088 19:17:42 INFO - 2016-01-06 19:17:42,058 DEBUG : BROWSER_OUTPUT: 19:17:43 INFO - 2016-01-06 19:17:43,789 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:17:44 INFO - 2016-01-06 19:17:44,376 DEBUG : BROWSER_OUTPUT: RSS: Main: 160829440 19:17:44 INFO - 2016-01-06 19:17:44,377 DEBUG : BROWSER_OUTPUT: 19:17:46 INFO - 2016-01-06 19:17:46,355 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:17:46 INFO - 2016-01-06 19:17:46,927 DEBUG : BROWSER_OUTPUT: RSS: Main: 165412864 19:17:46 INFO - 2016-01-06 19:17:46,928 DEBUG : BROWSER_OUTPUT: 19:17:49 INFO - 2016-01-06 19:17:49,221 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:17:49 INFO - 2016-01-06 19:17:49,791 DEBUG : BROWSER_OUTPUT: RSS: Main: 168865792 19:17:49 INFO - 2016-01-06 19:17:49,791 DEBUG : BROWSER_OUTPUT: 19:17:52 INFO - 2016-01-06 19:17:52,226 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:17:52 INFO - 2016-01-06 19:17:52,792 DEBUG : BROWSER_OUTPUT: RSS: Main: 166727680 19:17:52 INFO - 2016-01-06 19:17:52,792 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,381 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60455/tests/kraken/driver.html?testName=ai-astar) 19:17:54 INFO - 2016-01-06 19:17:54,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 225431552 19:17:54 INFO - 2016-01-06 19:17:54,626 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,629 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:17:54 INFO - 2016-01-06 19:17:54,629 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:17:54 INFO - 2016-01-06 19:17:54,629 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;106;109;111;109;100;108;107;109 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;128;144;156;123;144;145;124;161;121 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;161;160;162;163;158;163;162;159 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;71;145;71;71;70;71;153;72 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;88;88;131;88;88;90;142;88;90;133 19:17:54 INFO - 2016-01-06 19:17:54,630 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;109;110;110;110;109;110;110 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;145;146;145;146;145;146;145;144;145 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;96;96;96;96;95 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;55;55;58;57;56;57;54;53 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;64;63;64;63;63;63;68;63 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;78;79;78;86;79;79;78;79;78 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;108;120;107;113;108;116;125;108;118 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;137;136;135;146;135;130;134;145;138 19:17:54 INFO - 2016-01-06 19:17:54,631 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;61;63;60;61;62;62;59;60;63;61 19:17:54 INFO - 2016-01-06 19:17:54,632 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:17:54 INFO - 2016-01-06 19:17:54,632 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:17:54 INFO - 2016-01-06 19:17:54,632 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,852 19:17:54 INFO - 2016-01-06 19:17:54,632 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:17:54 INFO - 2016-01-06 19:17:54,632 DEBUG : BROWSER_OUTPUT: __startTimestamp1452136674627__endTimestamp 19:17:54 INFO - 2016-01-06 19:17:54,632 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,636 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:17:54 INFO - 2016-01-06 19:17:54,636 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:17:54 INFO - 2016-01-06 19:17:54,636 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,636 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.90 Median:109.00 stddev:3.14 (2.9%) stddev-sans-first:3.24 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 106.0 109.0 111.0 109.0 100.0 108.0 107.0 109.0 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.80 Median:144.50 stddev:14.71 (10.2%) stddev-sans-first:14.93 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: Values: 152.0 128.0 144.0 156.0 123.0 144.0 145.0 124.0 161.0 121.0 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.80 Median:161.50 stddev:1.69 (1.0%) stddev-sans-first:1.76 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 161.0 160.0 162.0 163.0 158.0 163.0 162.0 159.0 19:17:54 INFO - 2016-01-06 19:17:54,637 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.70 Median:71.00 stddev:32.89 (46.3%) stddev-sans-first:34.40 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 71.0 145.0 71.0 71.0 70.0 71.0 153.0 72.0 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.60 Median:88.00 stddev:22.77 (25.9%) stddev-sans-first:23.53 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: Values: 88.0 88.0 131.0 88.0 88.0 90.0 142.0 88.0 90.0 133.0 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 109.0 110.0 110.0 110.0 109.0 110.0 110.0 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.67 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: Values: 147.0 145.0 146.0 145.0 146.0 145.0 146.0 145.0 144.0 145.0 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,638 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 95.0 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:1.51 (2.7%) stddev-sans-first:1.59 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 55.0 55.0 58.0 57.0 56.0 57.0 54.0 53.0 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:63.00 stddev:1.57 (2.5%) stddev-sans-first:1.64 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 64.0 63.0 64.0 63.0 63.0 63.0 68.0 63.0 19:17:54 INFO - 2016-01-06 19:17:54,639 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:2.62 (3.3%) stddev-sans-first:2.55 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: Values: 76.0 78.0 79.0 78.0 86.0 79.0 79.0 78.0 79.0 78.0 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.70 Median:115.00 stddev:6.09 (5.3%) stddev-sans-first:6.46 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: Values: 114.0 108.0 120.0 107.0 113.0 108.0 116.0 125.0 108.0 118.0 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.10 Median:136.50 stddev:4.91 (3.6%) stddev-sans-first:5.15 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: Values: 135.0 137.0 136.0 135.0 146.0 135.0 130.0 134.0 145.0 138.0 19:17:54 INFO - 2016-01-06 19:17:54,640 DEBUG : BROWSER_OUTPUT: 19:17:54 INFO - 2016-01-06 19:17:54,641 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:1.32 (2.1%) stddev-sans-first:1.39 19:17:54 INFO - 2016-01-06 19:17:54,641 DEBUG : BROWSER_OUTPUT: Values: 61.0 63.0 60.0 61.0 62.0 62.0 59.0 60.0 63.0 61.0 19:17:54 INFO - 2016-01-06 19:17:54,641 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:17:54 INFO - 2016-01-06 19:17:54,641 DEBUG : BROWSER_OUTPUT: 19:17:55 INFO - 2016-01-06 19:17:55,302 INFO : Browser exited with error code: 0 19:17:55 INFO - 2016-01-06 19:17:55,349 INFO : Completed test kraken (00:00:56) 19:17:55 INFO - 2016-01-06 19:17:55,349 INFO : Starting test v8_7 19:17:55 INFO - 2016-01-06 19:17:55,349 DEBUG : operating with platform_type : linux_ 19:17:55 INFO - 2016-01-06 19:17:55,350 INFO : Initialising browser for v8_7 test... 19:17:55 INFO - 2016-01-06 19:17:55,356 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc9DL2J/profile http://localhost:60455/getInfo.html 19:17:58 INFO - 2016-01-06 19:17:58,714 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:17:58 INFO - 2016-01-06 19:17:58,715 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:17:58 INFO - 2016-01-06 19:17:58,715 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:17:58 INFO - 2016-01-06 19:17:58,715 DEBUG : BROWSER_OUTPUT: __metrics 19:17:58 INFO - 2016-01-06 19:17:58,852 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:17:59 INFO - 2016-01-06 19:17:59,961 INFO : Browser initialized. 19:17:59 INFO - 2016-01-06 19:17:59,962 INFO : Running cycle 1/1 for v8_7 test... 19:17:59 INFO - 2016-01-06 19:17:59,962 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc9DL2J/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:18:11 INFO - 2016-01-06 19:18:11,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 129040384 19:18:11 INFO - 2016-01-06 19:18:11,495 DEBUG : BROWSER_OUTPUT: 19:18:32 INFO - 2016-01-06 19:18:32,976 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:60455/tests/v8_7/run.html (next: http://localhost:60455/tests/v8_7/run.html) 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: RSS: Main: 428318720 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:18:33 INFO - 2016-01-06 19:18:33,227 DEBUG : BROWSER_OUTPUT: |0;Richards;166.5001665001665 19:18:33 INFO - 2016-01-06 19:18:33,228 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.2707304614337 19:18:33 INFO - 2016-01-06 19:18:33,228 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4857462269424 19:18:33 INFO - 2016-01-06 19:18:33,228 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5030.150753768844 19:18:33 INFO - 2016-01-06 19:18:33,228 DEBUG : BROWSER_OUTPUT: |4;RayTrace;936.3295880149813 19:18:33 INFO - 2016-01-06 19:18:33,228 DEBUG : BROWSER_OUTPUT: |5;Earley;553.0973451327434 19:18:33 INFO - 2016-01-06 19:18:33,228 DEBUG : BROWSER_OUTPUT: |6;Boyer;10427.083333333334 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: |7;RegExp;23720.93023255814 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: |8;Splay;549.7526113249038 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: __startTimestamp1452136713226__endTimestamp 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,229 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.50 Median:166.50 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: Values: 166.5 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.27 Median:170.27 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: Values: 170.3 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: Values: 279.5 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5030.15 Median:5030.15 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: Values: 5030.2 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,231 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:936.33 Median:936.33 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,232 DEBUG : BROWSER_OUTPUT: Values: 936.3 19:18:33 INFO - 2016-01-06 19:18:33,232 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,232 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:553.10 Median:553.10 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,232 DEBUG : BROWSER_OUTPUT: Values: 553.1 19:18:33 INFO - 2016-01-06 19:18:33,232 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,232 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10427.08 Median:10427.08 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: Values: 10427.1 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23720.93 Median:23720.93 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: Values: 23720.9 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:549.75 Median:549.75 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: Values: 549.8 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: Values: 4822.1 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:18:33 INFO - 2016-01-06 19:18:33,233 DEBUG : BROWSER_OUTPUT: 19:18:33 INFO - 2016-01-06 19:18:33,886 INFO : Browser exited with error code: 0 19:18:33 INFO - 2016-01-06 19:18:33,902 INFO : Completed test v8_7 (00:00:38) 19:18:34 INFO - 2016-01-06 19:18:34,133 INFO : Completed test suite (00:20:05) 19:18:34 INFO - 2016-01-06 19:18:34,133 DEBUG : Working with test: dromaeo_css 19:18:34 INFO - 2016-01-06 19:18:34,133 DEBUG : Generating results file: dromaeo_css 19:18:34 INFO - 2016-01-06 19:18:34,134 DEBUG : Working with test: kraken 19:18:34 INFO - 2016-01-06 19:18:34,134 DEBUG : Generating results file: kraken 19:18:34 INFO - 2016-01-06 19:18:34,135 INFO : javascript benchmark 19:18:34 INFO - 2016-01-06 19:18:34,135 DEBUG : Working with test: v8_7 19:18:34 INFO - 2016-01-06 19:18:34,135 DEBUG : Generating results file: v8_7 19:18:34 INFO - 2016-01-06 19:18:34,135 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:18:34 INFO - 2016-01-06 19:18:34,219 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 19:18:34 INFO - 2016-01-06 19:18:34,219 DEBUG : process_Request line: dromaeo_css 7396.64 graph.html#tests=[[72,53,35]] 19:18:34 INFO - 2016-01-06 19:18:34,220 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:18:34 INFO - 2016-01-06 19:18:34,355 DEBUG : process_Request line: kraken 1458.50 graph.html#tests=[[232,53,35]] 19:18:34 INFO - 2016-01-06 19:18:34,355 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:18:34 INFO - 2016-01-06 19:18:34,384 DEBUG : process_Request line: v8_7 23238.72 graph.html#tests=[[230,53,35]] 19:18:34 INFO - 2016-01-06 19:18:34,385 INFO : javascript benchmark 19:18:34 INFO - 2016-01-06 19:18:34,389 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3314.2045908981845, 3387.1024178592693, 3426.1948442116113, 3426.62307146214, 3437.312537492501, 5567.693776842272, 5594.412586678396, 5606.159076138581, 5626.831195064145, 5664.490195882369, 5679.7160141992945, 5728.79949201777, 5742.310922689283, 5750.654960702361, 5756.3092428908585, 5016.372953380827, 5153.613478989072, 5193.376794784613, 5264.789408423678, 5284.8150314739, 5136.178211486163, 5155.922661160085, 5162.561182299491, 5264.552513036378, 5270.578353731711, 5280.471952804727, 5292.391374991887, 5293.655912365684, 5296.364436267634, 5304.6286759926625, 4951.0, 5188.533032027136, 5192.169252919514, 5287.233351164079, 5292.761825717833, 5038.042771873333, 5109.514596113363, 5191.636585439021, 5225.111731005738, 5252.474752524755, 5554.750036248379, 5589.804356847531, 5602.467765562265, 5607.831765047055, 5613.887722245532, 5939.495142912858, 6079.908801367938, 6159.137720719103, 6176.135341052256, 6183.81448556544, 6158.137860699506, 6272.8118156455375, 6298.716557754912, 6302.842428939267, 6305.432511073979, 5815.621984570989, 5831.9416805831825, 5838.591298609099, 5840.3575606683235, 5854.619449735754, 6115.755369785159, 6122.510199184144, 6225.750969961241, 6270.404311590391, 6309.242890853127, 6056.242969628796, 6084.300305464928, 6094.664793436404, 6103.877922441615, 6117.388261173847, 6175.682006199553, 6184.938150618436, 6193.287771906198, 6193.535484838655, 6221.222347206599, 5812.82561523155, 5923.052311630118, 5996.970015149896, 6125.836893610466, 6127.988881834536, 6114.973461912185, 6143.477804386588, 6146.385361463907, 6147.323794382614, 6149.7847575334645, 4688.718676879398, 4697.4832768395445, 4699.600533954652, 4701.670883038223, 4709.481956984729, 4080.469538959916, 4090.2228576570355, 4177.4778152730905, 4189.413482112506, 4196.181744559843, 5701.771929122789, 5718.942810571924, 5720.11338242573, 5720.628159169641, 5743.0811070228565, 5975.910361344583, 6073.08903664445, 6107.358727333655, 6109.419605137505, 6117.0, 5675.460831221028, 5737.3688894221605, 5769.509591684669, 5789.739461724233, 5819.388964158787, 5127.333446651912, 5138.2806407103035, 5143.17709166539, 5146.356705411824, 5149.150390185652, 6115.755369785248, 6196.783112591128, 6299.118123462719, 6303.086052522359, 6320.494360451153, 6019.548533859978, 6129.908051379322, 6136.662483563447, 6140.4473597376455, 6143.907841382472, 6301.0, 6303.2120984875055, 6308.179936608211, 6313.936860631335, 6327.557071004985, 5812.273465816773, 5919.260092488439, 5958.225430693982, 6041.335453100243, 6082.0, 6194.969025154845, 6197.938020619736, 6210.7515699371515, 6212.378762123933, 6216.53375996793, 6038.184845046038, 6045.75816967334, 6050.334463208956, 6052.243469566304, 6065.631087358344, 5742.942570574241, 5846.0, 5899.705014749159, 6036.63780173191, 6060.606060606046, 5945.227120474311, 5959.433853783884, 5968.970155149196, 5970.313413957277, 5974.342822289458, 5985.850353741192, 6072.271327440735, 6078.3921607838865, 6086.452219300284, 6095.390460954047, 5683.658980461186, 5748.885022299447, 5794.507466865195, 5834.591578589458, 5858.9121163181735], "unit": "ms", "name": "dojo.html", "value": 5635.135080134491}, {"lowerIsBetter": false, "replicates": [599.9310079340877, 619.6560908695673, 633.1958412815721, 635.1330433957647, 639.865628218074, 28681.426371472557, 43114.784426077866, 43513.7824310878, 43531.56468435315, 43609.56390436095, 46355.536444635545, 46533.0, 46999.76500117503, 47009.0, 47049.764751176284, 8294.170582941688, 8399.832003359928, 9160.267178625727, 9332.766680832965, 10736.409497477636, 10773.892261077406, 10857.23999320048, 10870.61952831651, 10875.0, 10887.891121088767, 10929.890701093007, 10956.178286628496, 11069.391183484906, 11071.72320691981, 11072.224944253905, 10794.892051079507, 10883.727906802314, 10889.183311251687, 10892.019718225398, 10900.672979810579, 10715.517801698943, 10757.246992710514, 10819.945900270448, 10850.24048316618, 10857.131429485702, 42527.57472425267, 42886.785566072285, 42976.785116074534, 42991.78504107491, 42994.0, 3561.982190089059, 3650.963490365089, 3658.5975542690285, 3729.9813500932323, 4362.411074504933, 32583.0, 32888.835555822305, 32930.0, 32993.175170620685, 34156.14609634754, 3198.6725508914005, 3227.4190645683766, 3280.1963518937773, 3289.1448223461834, 3327.5674162358737, 327887.0, 329859.0, 462615.0, 468091.0, 536881.0, 62395.6880215596, 62494.0, 62686.0, 63172.36827631757, 63231.367686323465, 65443.345566544675, 65653.67173164104, 65682.0, 65737.0, 65899.67050164718, 17721.0, 17754.644907101785, 17788.644227115386, 17839.46481605528, 17933.103344832707, 14474.131552106906, 14587.489437869624, 14670.559883203521, 14688.41246350134, 14704.632384190267, 21023.68464473034, 21378.14487420517, 21472.141114355563, 21472.4631884201, 21500.89249553742, 7687.500312479671, 7921.0, 7929.1277959424415, 8006.479578827358, 8044.597770111471, 1003.9146672532917, 1078.1644225725126, 1092.852464917226, 1111.4165063341648, 1219.036960800975, 30341.848290758404, 30782.846085769877, 30903.0, 30918.53622195669, 31624.209394765316, 1092.317301686446, 1134.1493879590307, 1186.7211205366732, 1245.9252444853337, 1302.3813688497887, 13483.0, 13682.794758078637, 14781.778273325694, 14942.178180200193, 15445.613859653598, 384929.0, 389011.0, 392108.0, 393042.0, 393043.0, 57712.4228757707, 57914.420855791745, 57949.71025144847, 58007.41992580105, 58039.0, 1520.9467668631544, 1521.376235743362, 1522.824875139351, 1523.2612183091192, 1542.2134711297094, 30110.548341774454, 30124.698753012188, 30329.24176895595, 30422.39155216839, 30590.84704576463, 51586.74206628943, 52207.0, 52224.21663674972, 52245.216321755965, 52252.21621675602, 355142.0, 358279.0, 358287.0, 358468.0, 359774.0, 30649.0, 32833.671663283065, 34848.30303393969, 35477.29045419129, 35814.0, 45443.09113817771, 47502.52497475119, 48171.75914120407, 48330.275045875045, 48574.51425485841, 39586.40620390754, 40183.397249041875, 40345.0, 41073.1785364297, 41096.79451602842, 37012.259754805295, 39495.802520987214, 39561.60438395695, 40124.3981340286, 40886.0, 40440.79779601083, 40485.0, 40577.391339129346, 40660.0, 40937.0, 32551.67448325581, 34739.305213896085, 34812.477812833335, 34827.129321766144, 35926.0, 25010.0, 25591.488170236862, 25750.484990299716, 25808.225753227434, 25941.74058259393], "unit": "ms", "name": "ext.html", "value": 20660.361298942982}, {"lowerIsBetter": false, "replicates": [386.9226154769045, 390.1397418691784, 391.8119302734687, 393.86805420184237, 398.4760040546683, 12917.160384574996, 15649.92175039126, 16759.664806703873, 18660.16029278684, 18715.62568748624, 17513.56216094598, 17518.824811751878, 17800.910995445036, 18967.14647840847, 19321.0, 5801.9129713054235, 6063.878722425571, 6064.72708728106, 6094.1772860663905, 6095.420935011167, 4376.868693939187, 4494.505604383531, 4497.0, 4498.707584007046, 6058.666773327465, 6384.968075159618, 6406.93593064068, 6613.537052406334, 6613.735450581971, 6621.56959797614, 6572.7042283097135, 6596.73613055482, 6614.603123812587, 6639.634820084893, 6644.269130395653, 5784.537237021028, 5912.17229587858, 5915.940840591625, 5920.111983202511, 5995.580309378345, 23669.881650591808, 24179.51640967171, 24548.263552093467, 25028.749712502824, 25224.621630675556, 2332.825038122148, 2346.8591884486987, 2366.7988221001237, 2373.6320870265135, 2426.0053378115063, 14969.401223951028, 15112.848871511364, 15197.0, 15363.846361536353, 15619.921900390425, 1778.4858970848193, 1833.120102350879, 1912.512309361118, 1982.544014876572, 2019.030865184705, 62298.68850655718, 69487.0, 70440.64779676069, 71576.0, 72452.0, 7607.9239207608325, 7687.730929417444, 7721.150673425918, 7886.369090472748, 7890.0, 9518.428894266366, 9540.809183816285, 9541.045895410542, 9544.904550954401, 9566.282528810367, 6447.709853056624, 6588.670566471753, 6613.371729685672, 6630.303818099127, 6642.43539299165, 7071.434285257167, 7123.536970096927, 7151.356377926011, 7172.82067948295, 7209.639518024078, 3700.481932529448, 3740.8129593520216, 3744.85020599173, 3762.548494180716, 3776.263628592398, 1239.7892358299111, 1269.9301538415475, 1278.0606254403006, 1280.6862318731971, 1313.4483516922915, 530.5013287509729, 531.44994930247, 533.9012282727708, 540.3812634533438, 555.919391688203, 11679.708007299716, 13074.346282686018, 13643.658908527366, 13699.45202191921, 13890.236037018061, 563.6674362126365, 567.9119736440858, 578.8813293274868, 582.0628787651877, 593.4214141212284, 7190.712371505186, 7234.0, 7353.1911489736085, 7451.993980812629, 7486.438517111238, 614746.0, 616176.0, 620038.0, 621668.0, 636690.0, 87492.0, 88397.1160288389, 90055.0, 90214.54892725495, 91861.54069229745, 594.4531031451075, 600.447588218831, 600.9098635204755, 602.1359349333633, 604.5707547641224, 10928.508217130408, 12214.57248996281, 12430.378481076092, 12459.75080498403, 12507.812382814447, 39444.40833387559, 39956.60043399645, 41334.586654133076, 42042.369364458944, 42272.36591451192, 52571.474285256656, 53650.0, 55079.724601376736, 55116.44883551113, 56596.71701641465, 16323.347066117698, 16468.917655411646, 16674.58313542171, 17032.233549490054, 17081.743773843154, 14901.552953411416, 20607.690884637042, 20915.790842091385, 21039.0, 21456.463588410414, 13707.520236791665, 16182.433614823425, 16358.754618680967, 16395.508134755975, 16589.17054147312, 15756.92121539385, 16323.836761632232, 16413.67172656564, 16603.584910377336, 17188.570285742957, 17356.305747769948, 17488.0, 17544.736828947833, 17723.11384430799, 17778.46664600064, 16264.268107934935, 16609.833901661314, 16664.66670666604, 16842.57893552671, 16885.0, 17271.827281727023, 17365.305387784854, 17437.389691360742, 17697.557561061076, 17765.0], "unit": "ms", "name": "jquery.html", "value": 8977.7411320565}, {"lowerIsBetter": false, "replicates": [1554.5414102839654, 1560.4616407339463, 1777.6355847051357, 1843.7142242952343, 1862.9660538401185, 7086.716531338753, 7295.270472952702, 8061.072976607685, 8178.304844088252, 8208.999999999993, 7927.524348539092, 8083.070446898594, 8160.836783264331, 8163.4693744906535, 8194.959025204866, 2337.474068334624, 2362.3267368799898, 2362.9527409451803, 2364.9527009459803, 2366.0, 2123.3948324727457, 2164.0802658869998, 2352.3413444235644, 2368.336865677613, 2414.9758502415016, 2336.0889253191267, 2348.095983046531, 2354.5290941811622, 2355.5877721398774, 2356.4108972756812, 2330.9650355244685, 2352.847064940773, 2356.7525409832065, 2361.622140457527, 2361.9409514762183, 2379.9048038078454, 2421.987890060556, 2424.854508729482, 2429.246933450618, 2432.8540287582623, 8148.959255203686, 8158.510489370657, 8190.918090819075, 8197.508149511048, 8198.83602327956, 4993.575546078588, 5044.722540260284, 5055.342805435306, 5055.494450554915, 5059.797608095671, 3168.825714585697, 3208.98395508021, 3277.9836100819343, 3291.7037466628117, 3303.339332133583, 1011.6004178349584, 1049.4437947887632, 1060.6075751971744, 1062.6493257225097, 1090.0462095666292, 11367.545298188063, 11402.201845870792, 11409.543618255342, 11410.0, 11413.0, 4571.1086338164405, 4580.450345958439, 4623.214053610895, 4648.930266046012, 4666.393368862026, 9766.9023309766, 9824.90175098254, 9835.459049752333, 9837.114659680663, 9858.309918305793, 5143.742812859342, 5172.534471897472, 5184.611154163453, 5184.662996905265, 5188.429272779991, 4759.881002974953, 4768.785404656729, 4771.33201351811, 4771.498992605796, 4771.809127634856, 3769.246150769802, 3970.5433875104154, 4062.857799976987, 4088.509378874495, 4121.5466298707115, 1238.1271203801298, 1306.7713150198676, 1340.187167508437, 1346.2326473909777, 1355.9186448813102, 1118.5805323003874, 1118.5917140243753, 1138.3397629374942, 1262.1985039499984, 1324.8675132486867, 7891.092524359658, 8041.155678653774, 8093.1502192270145, 8111.918880811117, 8119.7158099467415, 1189.2804853063772, 1195.58154645875, 1232.7411243638758, 1239.3369547292154, 1263.7030297879992, 6465.3857883501, 6520.054592084121, 6575.178102737158, 6575.868482630321, 6585.078089067534, 9748.707538773848, 9896.406215627085, 9915.702528924145, 9928.205743540499, 9955.452450115168, 9169.40398874071, 9894.455804930798, 9931.950340248397, 9935.552900119512, 9938.602455901828, 7839.05931288249, 7894.368450523944, 7915.168907264654, 7919.6832126715435, 7922.128565857636, 7864.646090925808, 7924.20757924203, 7960.641771120429, 7976.481528700616, 8026.43814932949, 8454.450460720034, 8914.55427228649, 9001.414908030956, 9028.90971090307, 9046.864297035681, 8857.778555535906, 9033.457992520469, 9067.546622668708, 9163.816723665355, 9174.128457796498, 7482.2517748224745, 7572.962135189288, 7579.583122928292, 7581.12817026027, 7581.166071732216, 6859.245482996766, 7041.894371584328, 7887.092984306878, 8051.718189863561, 8053.315468185036, 7373.77878663641, 7493.063367079115, 7623.16145224036, 7693.422993275639, 7741.303282704584, 7431.4054875608745, 7469.77590672259, 7562.432817538596, 7632.045994250719, 7663.31030207284, 7073.363397294268, 7450.664720087718, 7750.651220694969, 7787.29914307715, 7788.065432148179, 7001.054857594363, 7045.0, 7048.964755176191, 7060.293970602898, 7061.6822243000215, 7048.964755176191, 7117.786466405809, 7134.714611415693, 7144.821379465347, 7150.5352152109945], "unit": "ms", "name": "mootools.html", "value": 4805.3512170156}, {"lowerIsBetter": false, "replicates": [369.6802266039873, 370.57939238963786, 371.71563753728395, 379.0240131660973, 382.6498753640419, 5863.853403664911, 5880.823575292743, 5927.614705044169, 5934.910976335353, 6003.759849606022, 5758.42415758424, 5834.241548598687, 5851.3563508014195, 5871.530277577795, 5874.559408044403, 4617.399738034051, 4621.214393553088, 4627.398438203046, 4633.582977532022, 4634.86095417138, 4484.910301793962, 4562.543745625444, 4574.611158051553, 4629.398178236841, 4643.8839029024375, 4578.702384345007, 4588.357629931812, 4594.379758732578, 4599.724016559, 4609.654275929302, 4548.499665036843, 4577.084583083397, 4579.084183163364, 4585.816567337302, 4602.79287432063, 4098.057446787226, 4106.691998100155, 4106.77412742299, 4117.094239267386, 4120.17596480705, 5532.114861622121, 5599.608027438081, 5625.662460252398, 5676.403977582336, 5692.544596432275, 5424.538914192299, 5433.4566543345745, 5437.32033495813, 5447.128459446509, 5450.5367043801325, 6005.219321488223, 6291.874162516724, 6318.652474113922, 6328.525360597973, 6330.987042073246, 4303.892402689927, 4491.438570178728, 4516.616087632555, 4521.954780452186, 4564.3838081859185, 6372.426481616631, 6390.169277993831, 6399.328070552571, 6400.167978162855, 6407.583507072025, 4648.86053418398, 4673.228917228687, 4673.439187297478, 4674.041821426599, 4677.976610116928, 4607.792649330788, 4691.413573303337, 4692.694974826693, 4738.123447162286, 4740.19416699162, 5327.627066105335, 5327.840164795062, 5328.3605967284175, 5328.547073498796, 5329.626926115135, 6295.748170073238, 6297.1813664223355, 6300.684965751694, 6303.432691057735, 6305.432511074026, 1677.9244933978, 1697.5925777813236, 1697.983020169807, 1700.0904516083835, 1700.1244359155046, 1128.5260190719916, 1129.7966366054188, 1132.8300754886832, 1157.3345326437332, 1185.8636256830403, 6063.514918806485, 6071.210742603522, 6077.088436734525, 6077.574569780161, 6084.209052823193, 6425.678716064178, 6428.903566446507, 6430.485561155097, 6431.710573024225, 6433.131527243762, 5957.344692083867, 6058.091286307001, 6058.818235452855, 6062.514998800086, 6064.545159112996, 3544.486049522805, 3584.6415358463946, 3585.6235095315137, 3589.10272431892, 3589.9282014359565, 6655.933440665532, 6659.300773418819, 6666.333366663392, 6671.299513551105, 6684.0, 6561.212654481493, 6626.403623673892, 6633.2703402625675, 6634.966825165843, 6661.2006559213205, 6554.770583029571, 6578.671066446658, 6608.173978252815, 6608.306127856602, 6608.43828274602, 6305.779297724557, 6407.967960160169, 6408.102865598913, 6494.318096599789, 6495.870082598227, 6429.035644653152, 6432.614043157426, 6432.903506447501, 6445.097686323825, 6453.483721302191, 6656.5340426169705, 6663.866722665616, 6672.299408562031, 6672.866542669217, 6675.0654908313845, 4479.865604031885, 4566.840160594364, 4570.565796249351, 4572.039871626863, 4580.88547786308, 6518.837029074311, 6598.8350291243105, 6601.306862779531, 6604.405603495708, 6604.7027883746305, 6443.130177425987, 6453.483721302191, 6455.322191169859, 6458.257300410513, 6469.967650161719, 6328.367163283635, 6413.486921046212, 6437.871242575216, 6440.935590644221, 6460.967695161494, 6340.1123842661145, 6343.841403964753, 6347.746090156526, 6355.237371515448, 6362.936370636235, 6484.546081774419, 6485.935140648722, 6492.610443373413, 6495.870082598416, 6498.057781621544, 6149.661768602591, 6219.782307619393, 6231.626102433868, 6319.936800631935, 6337.334579869047], "unit": "ms", "name": "prototype.html", "value": 4825.375369293741}, {"lowerIsBetter": false, "replicates": [826.1036775099017, 877.622622272423, 879.4679219072461, 937.8686983822266, 942.5899733615878, 13395.330233488323, 13707.93146034271, 13711.0, 13715.65710857229, 13740.450381984721, 12865.292408917507, 12888.93555532221, 12891.419886105125, 12893.290869002201, 12901.354932253396, 742.5581778841594, 748.3751067858345, 891.687909231767, 893.3389291923994, 893.4907102951321, 883.5847151838625, 890.7773056735816, 896.4621227263655, 898.0301274623424, 903.4714691905243, 787.1262898183012, 938.4697645830119, 939.1078475448352, 939.5208443693731, 947.2895328503622, 796.191865256762, 941.181172380027, 944.1078181118811, 944.5749412764284, 960.2701946520694, 698.0715648187897, 698.4970821008866, 700.5586480517293, 702.9894551581731, 703.2229386527915, 13199.604011879659, 13301.73396532064, 13325.933370333183, 13328.533501327503, 13328.933355333258, 11587.362695051768, 11919.582814601534, 12017.0, 12208.32854193019, 12251.0, 9958.502074896227, 10013.0, 10095.495225238783, 10113.190944724478, 10154.390736555757, 3402.625711171769, 3423.058658868831, 3424.1610805352598, 3425.246445781924, 3427.9828600857086, 52298.0, 52325.0, 52378.0, 54015.72992135053, 54080.0, 9898.851517227247, 9918.15695665862, 9929.453880036523, 9937.254705897087, 10002.749931251778, 19533.0, 19720.40838774839, 19724.90137549303, 19743.901280493792, 19782.0, 7201.639918004079, 7322.231165727522, 7329.120505539263, 7479.326860582573, 7585.127710313274, 4036.455078564415, 4224.493060832659, 4297.097609501977, 4311.827526898889, 4327.545607711208, 3130.358276553301, 3161.351922855809, 3163.557102005721, 3163.9050828475183, 3172.127664892173, 488.5578551410979, 491.7762418099756, 495.1285737102725, 496.81617801412983, 497.5024975024975, 645.612632420544, 666.167290886392, 689.7654797368918, 691.8132104331802, 742.5433358484493, 8685.435446695945, 8969.14793094655, 9042.231410330196, 9046.502442365732, 9069.77325566853, 613.7238242790762, 697.8594692907282, 726.2447055062781, 741.8961345411647, 781.7654703589014, 4057.8376864925663, 4154.356074808409, 4290.52804191545, 4421.977890110529, 4478.708883922534, 64381.67809161018, 64678.353216467236, 65364.673176634766, 65464.67267663631, 72138.27861721421, 58795.70602146962, 59452.0, 59872.0, 60152.39847601556, 65842.34157658459, 59180.40819591835, 59325.0, 59682.0, 60074.699626501584, 61271.6936415324, 9259.074092590687, 9473.052694730472, 9478.857817132886, 9565.856512152463, 9634.9036509634, 40953.0, 41908.0, 42632.147357053305, 43013.35479967866, 43343.0, 53899.73050134881, 54313.45686543084, 54340.184897227366, 55278.44721552733, 55869.720651396485, 12664.6833829155, 12731.681707957005, 12745.745085098431, 12798.936005319914, 12928.93535532354, 12617.0, 12858.807117893426, 12942.611721648365, 13084.214947103204, 13457.932710336385, 12070.818937716116, 12413.317267550372, 12636.0, 12728.936355318165, 12866.614001579968, 12585.181963172365, 12611.873881261437, 12635.684107897376, 12849.0, 13317.467301307839, 12259.38703064861, 12379.69050773738, 12536.74926501483, 12849.100562960502, 12994.610161695164, 10779.461026948778, 10882.129429645767, 11128.554857805597, 11286.492107855001, 11507.194496385508, 10035.648752293635, 10185.236107291834, 10331.793364132825, 10455.738606534593, 10473.581056757948], "unit": "ms", "name": "yui.html", "value": 6756.191898001964}], "name": "dromaeo_css", "value": 7396.638495513199}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 106.0, 109.0, 111.0, 109.0, 100.0, 108.0, 107.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.9}, {"lowerIsBetter": true, "replicates": [152.0, 128.0, 144.0, 156.0, 123.0, 144.0, 145.0, 124.0, 161.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.8}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 161.0, 160.0, 162.0, 163.0, 158.0, 163.0, 162.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.8}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 71.0, 145.0, 71.0, 71.0, 70.0, 71.0, 153.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 86.7}, {"lowerIsBetter": true, "replicates": [88.0, 88.0, 131.0, 88.0, 88.0, 90.0, 142.0, 88.0, 90.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 102.6}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [147.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 144.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 55.0, 55.0, 58.0, 57.0, 56.0, 57.0, 54.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.5}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0, 63.0, 68.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.7}, {"lowerIsBetter": true, "replicates": [76.0, 78.0, 79.0, 78.0, 86.0, 79.0, 79.0, 78.0, 79.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.0}, {"lowerIsBetter": true, "replicates": [114.0, 108.0, 120.0, 107.0, 113.0, 108.0, 116.0, 125.0, 108.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.7}, {"lowerIsBetter": true, "replicates": [135.0, 137.0, 136.0, 135.0, 146.0, 135.0, 130.0, 134.0, 145.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 137.1}, {"lowerIsBetter": true, "replicates": [61.0, 63.0, 60.0, 61.0, 62.0, 62.0, 59.0, 60.0, 63.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.2}], "name": "kraken", "value": 1458.5}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5001665001665], "unit": "score", "name": "Richards", "value": 212.02381199999994}, {"lowerIsBetter": false, "replicates": [170.2707304614337], "unit": "score", "name": "DeltaBlue", "value": 388.31101400000006}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5030.150753768844], "unit": "score", "name": "Decrypt", "value": 52.91710189810189}, {"lowerIsBetter": false, "replicates": [936.3295880149813], "unit": "score", "name": "RayTrace", "value": 790.3082519999997}, {"lowerIsBetter": false, "replicates": [553.0973451327434], "unit": "score", "name": "Earley", "value": 1204.9651040000003}, {"lowerIsBetter": false, "replicates": [10427.083333333334], "unit": "score", "name": "Boyer", "value": 63.916531468531446}, {"lowerIsBetter": false, "replicates": [23720.93023255814], "unit": "score", "name": "RegExp", "value": 38.40426960784315}, {"lowerIsBetter": false, "replicates": [549.7526113249038], "unit": "score", "name": "Splay", "value": 148.232129}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23238.724139492093}]} 19:18:34 INFO - RETURN: dromaeo_css: 7396.64 19:18:34 INFO - RETURN: kraken: 1458.50 19:18:34 INFO - RETURN: v8_7: 23238.72 19:18:34 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23238.72"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1458.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7396.64"}}} 19:18:34 INFO - Return code: 0 19:18:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:18:34 INFO - # TBPL SUCCESS # 19:18:34 INFO - Running post-action listener: _resource_record_post_action 19:18:34 INFO - Running post-run listener: _resource_record_post_run 19:18:35 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 339968; Write bytes: 498044928; Read time: 252; Write time: 409680 19:18:35 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 222375936; Read time: 0; Write time: 270848 19:18:35 INFO - run-tests - Wall time: 1207s; CPU: 12.0%; Read bytes: 335872; Write bytes: 263528448; Read time: 248; Write time: 136796 19:18:35 INFO - Running post-run listener: _upload_blobber_files 19:18:35 INFO - Blob upload gear active. 19:18:35 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:18:35 INFO - Copying logs to upload dir... 19:18:35 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1265.040192 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-06 19:18:35.446227) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-06 19:18:35.449474) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009933 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452127387/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-06 19:18:35.501350) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:18:35.501715) ========= 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=fa9c93d544b966bd2b8d485a000001c6-1452135361.960163-2120108991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-06 19:18:35.553940) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-06 19:18:35.554303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-06 19:18:35.554614) ========= ========= Total master_lag: 0.17 =========