builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1451337029.02 results: success (0) buildid: 20151228105434 builduid: e02033b704594c13ab6cf1dbd894caf2 revision: c0b161f60c6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.016314) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.017026) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.017337) ========= 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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006357 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.041450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.041749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.095714) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.095993) ========= 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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004650 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.145906) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.146171) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.146584) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.146874) ========= 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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-28 13:10: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2015-12-28 13:10:29 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.195646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.357826) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.358111) ========= 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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014258 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.412338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.412673) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c0b161f60c6b --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 c0b161f60c6b --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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-28 13:10:30,065 Setting DEBUG logging. 2015-12-28 13:10:30,065 attempt 1/10 2015-12-28 13:10:30,065 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c0b161f60c6b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-28 13:10:30,285 unpacking tar archive at: mozilla-beta-c0b161f60c6b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.510804 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.937208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.937525) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.948514) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.948856) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-28 13:10:29.949500) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2015-12-28 13:10:29.949941) ========= /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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:10:30 INFO - MultiFileLogger online at 20151228 13:10:30 in /builds/slave/test-pgo 13:10: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 13:10:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 13:10:30 INFO - {'append_to_log': False, 13:10:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 13:10:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:10:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 13:10:30 INFO - 'branch': 'Mozilla-Beta', 13:10:30 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:30 INFO - 'config_files': ('talos/linux_config.py',), 13:10:30 INFO - 'default_actions': ('clobber', 13:10:30 INFO - 'read-buildbot-config', 13:10:30 INFO - 'download-and-extract', 13:10:30 INFO - 'populate-webroot', 13:10:30 INFO - 'create-virtualenv', 13:10:30 INFO - 'install', 13:10:30 INFO - 'run-tests'), 13:10:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:30 INFO - 'download_minidump_stackwalk': True, 13:10:30 INFO - 'download_symbols': 'ondemand', 13:10:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:30 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:30 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:30 INFO - 'installer_path': 'installer.exe', 13:10:30 INFO - 'log_level': 'info', 13:10:30 INFO - 'log_name': 'talos', 13:10:30 INFO - 'log_to_console': True, 13:10:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:10:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:10:30 INFO - 'opt_config_files': (), 13:10:30 INFO - 'pip_index': False, 13:10:30 INFO - 'sps_profile': False, 13:10:30 INFO - 'sps_profile_interval': 0, 13:10:30 INFO - 'suite': 'dromaeojs', 13:10:30 INFO - 'system_bits': '32', 13:10:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:30 INFO - 'title': 'talos-linux64-ix-003', 13:10:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:30 INFO - 'use_talos_json': True, 13:10:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 13:10:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:30 INFO - 'work_dir': 'build'} 13:10:30 INFO - ##### 13:10:30 INFO - ##### Running clobber step. 13:10:30 INFO - ##### 13:10:30 INFO - Running pre-action listener: _resource_record_pre_action 13:10:30 INFO - Running main action method: clobber 13:10:30 INFO - rmtree: /builds/slave/test-pgo/build 13:10:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 13:10:31 INFO - Running post-action listener: _resource_record_post_action 13:10:31 INFO - ##### 13:10:31 INFO - ##### Running read-buildbot-config step. 13:10:31 INFO - ##### 13:10:31 INFO - Running pre-action listener: _resource_record_pre_action 13:10:31 INFO - Running main action method: read_buildbot_config 13:10:31 INFO - Using buildbot properties: 13:10:31 INFO - { 13:10:31 INFO - "properties": { 13:10:31 INFO - "buildnumber": 48, 13:10:31 INFO - "product": "firefox", 13:10:31 INFO - "script_repo_revision": "production", 13:10:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:10:31 INFO - "repository": "", 13:10:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:10:31 INFO - "buildid": "20151228105434", 13:10:31 INFO - "slavename": "talos-linux64-ix-003", 13:10:31 INFO - "pgo_build": "True", 13:10:31 INFO - "basedir": "/builds/slave/test-pgo", 13:10:31 INFO - "project": "", 13:10:31 INFO - "platform": "ubuntu64_hw", 13:10:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:10:31 INFO - "slavebuilddir": "test", 13:10:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:10:31 INFO - "branch": "mozilla-beta", 13:10:31 INFO - "repo_path": "releases/mozilla-beta", 13:10:31 INFO - "stage_platform": "linux64-pgo", 13:10:31 INFO - "builduid": "e02033b704594c13ab6cf1dbd894caf2", 13:10:31 INFO - "revision": "c0b161f60c6b" 13:10:31 INFO - }, 13:10:31 INFO - "sourcestamp": { 13:10:31 INFO - "repository": "", 13:10:31 INFO - "hasPatch": false, 13:10:31 INFO - "project": "", 13:10:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 13:10:31 INFO - "changes": [ 13:10:31 INFO - { 13:10:31 INFO - "category": null, 13:10:31 INFO - "files": [ 13:10:31 INFO - { 13:10:31 INFO - "url": null, 13:10:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.tar.bz2" 13:10:31 INFO - } 13:10:31 INFO - ], 13:10:31 INFO - "repository": "", 13:10:31 INFO - "rev": "c0b161f60c6b", 13:10:31 INFO - "who": "sendchange", 13:10:31 INFO - "when": 1451336982, 13:10:31 INFO - "number": 7397552, 13:10:31 INFO - "comments": "Bug 1223202 - Only send subscription change events if the Push permission is granted. r=mt a=ritu", 13:10:31 INFO - "project": "", 13:10:31 INFO - "at": "Mon 28 Dec 2015 13:09:42", 13:10:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 13:10:31 INFO - "revlink": "", 13:10:31 INFO - "properties": [ 13:10:31 INFO - [ 13:10:31 INFO - "buildid", 13:10:31 INFO - "20151228105434", 13:10:31 INFO - "Change" 13:10:31 INFO - ], 13:10:31 INFO - [ 13:10:31 INFO - "builduid", 13:10:31 INFO - "e02033b704594c13ab6cf1dbd894caf2", 13:10:31 INFO - "Change" 13:10:31 INFO - ], 13:10:31 INFO - [ 13:10:31 INFO - "pgo_build", 13:10:31 INFO - "True", 13:10:31 INFO - "Change" 13:10:31 INFO - ] 13:10:31 INFO - ], 13:10:31 INFO - "revision": "c0b161f60c6b" 13:10:31 INFO - } 13:10:31 INFO - ], 13:10:31 INFO - "revision": "c0b161f60c6b" 13:10:31 INFO - } 13:10:31 INFO - } 13:10:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.tar.bz2. 13:10:31 INFO - Running post-action listener: _resource_record_post_action 13:10:31 INFO - ##### 13:10:31 INFO - ##### Running download-and-extract step. 13:10:31 INFO - ##### 13:10:31 INFO - Running pre-action listener: _resource_record_pre_action 13:10:31 INFO - Running main action method: download_and_extract 13:10:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 13:10:31 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')]} 13:10:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/test_packages.json 13:10:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 13:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 13:10:31 INFO - Downloaded 1270 bytes. 13:10:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 13:10:31 INFO - Using the following test package requirements: 13:10:31 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:10:31 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:10:31 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'jsshell-linux-x86_64.zip'], 13:10:31 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:10:31 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:10:31 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 13:10:31 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 13:10:31 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:10:31 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 13:10:31 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 13:10:31 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:10:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:10:31 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')]} 13:10:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:10:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:36 INFO - Downloaded 22571921 bytes. 13:10:36 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 13:10:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:10:36 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 13:10:37 INFO - Return code: 0 13:10:37 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 13:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:10:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:37 INFO - Downloaded 22571921 bytes. 13:10:37 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 13:10:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 13:10:37 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 13:10:38 INFO - Return code: 0 13:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:38 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 13:10:38 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:42 INFO - Downloaded 11131970 bytes. 13:10:42 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 13:10:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 13:10:42 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 13:10:42 INFO - Return code: 0 13:10: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')]} 13:10:42 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:10:42 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 13:10:44 INFO - Downloaded 56626884 bytes. 13:10:44 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:10:44 INFO - mkdir: /builds/slave/test-pgo/properties 13:10:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 13:10:44 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 13:10:44 INFO - Contents: 13:10:44 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.tar.bz2 13:10:44 INFO - Running post-action listener: _resource_record_post_action 13:10:44 INFO - Running post-action listener: set_extra_try_arguments 13:10:44 INFO - ##### 13:10:44 INFO - ##### Running populate-webroot step. 13:10:44 INFO - ##### 13:10:44 INFO - Running pre-action listener: _resource_record_pre_action 13:10:44 INFO - Running main action method: populate_webroot 13:10:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:10:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:10:44 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 13:10:44 INFO - '../fennec_ids.txt'], 13:10:44 INFO - 'tests': ['tcheck2']}, 13:10:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:10:44 INFO - '--tppagecycles', 13:10:44 INFO - '7'], 13:10:44 INFO - 'tests': ['tsvgm']}}, 13:10:44 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 13:10:44 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:10:44 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:10:44 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 13:10:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:10:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:10:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'tests': ['damp', 'tps']}, 13:10:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['damp', 'tps']}, 13:10:44 INFO - 'g3': {'tests': ['dromaeo_dom']}, 13:10:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:10:44 INFO - 'other': {'tests': ['a11yr', 13:10:44 INFO - 'ts_paint', 13:10:44 INFO - 'tpaint', 13:10:44 INFO - 'sessionrestore', 13:10:44 INFO - 'sessionrestore_no_auto_restore']}, 13:10:44 INFO - 'other-e10s': {'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['a11yr', 13:10:44 INFO - 'ts_paint', 13:10:44 INFO - 'tpaint', 13:10:44 INFO - 'sessionrestore', 13:10:44 INFO - 'sessionrestore_no_auto_restore']}, 13:10:44 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['a11yr', 13:10:44 INFO - 'ts_paint', 13:10:44 INFO - 'tpaint', 13:10:44 INFO - 'sessionrestore', 13:10:44 INFO - 'sessionrestore_no_auto_restore']}, 13:10:44 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['a11yr', 13:10:44 INFO - 'ts_paint', 13:10:44 INFO - 'tpaint', 13:10:44 INFO - 'sessionrestore', 13:10:44 INFO - 'sessionrestore_no_auto_restore']}, 13:10:44 INFO - 'other_l64': {'tests': ['a11yr', 13:10:44 INFO - 'ts_paint', 13:10:44 INFO - 'tpaint', 13:10:44 INFO - 'sessionrestore', 13:10:44 INFO - 'sessionrestore_no_auto_restore']}, 13:10:44 INFO - 'other_nol64': {'tests': ['a11yr', 13:10:44 INFO - 'ts_paint', 13:10:44 INFO - 'tpaint', 13:10:44 INFO - 'sessionrestore', 13:10:44 INFO - 'sessionrestore_no_auto_restore']}, 13:10:44 INFO - 'svgr': {'tests': ['tsvgx', 13:10:44 INFO - 'tsvgr_opacity', 13:10:44 INFO - 'tart', 13:10:44 INFO - 'tscrollx', 13:10:44 INFO - 'cart']}, 13:10:44 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['tsvgx', 13:10:44 INFO - 'tsvgr_opacity', 13:10:44 INFO - 'tart', 13:10:44 INFO - 'tscrollx', 13:10:44 INFO - 'cart']}, 13:10:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'tests': ['tp5o']}, 13:10:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'talos_options': ['--e10s'], 13:10:44 INFO - 'tests': ['tp5o']}, 13:10:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'talos_options': ['--xperf_path', 13:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:10:44 INFO - 'tests': ['tp5n']}, 13:10:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:10:44 INFO - 'talos_options': ['--e10s', 13:10:44 INFO - '--xperf_path', 13:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:10:44 INFO - 'tests': ['tp5n']}}, 13:10:44 INFO - 'talos.zip': {'path': '', 13:10:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:10:44 INFO - Running post-action listener: _resource_record_post_action 13:10:44 INFO - ##### 13:10:44 INFO - ##### Running create-virtualenv step. 13:10:44 INFO - ##### 13:10:44 INFO - Running pre-action listener: _resource_record_pre_action 13:10:44 INFO - Running main action method: create_virtualenv 13:10:44 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 13:10:44 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 13:10:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 13:10:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:10:44 INFO - Using real prefix '/usr' 13:10:44 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 13:10:45 INFO - Installing distribute.............................................................................................................................................................................................done. 13:10:47 INFO - Installing pip.................done. 13:10:47 INFO - Return code: 0 13:10:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:47 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:10:47 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 13:10:47 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 13:10:47 INFO - Using env: {'DISPLAY': ':0', 13:10:47 INFO - 'HOME': '/home/cltbld', 13:10:47 INFO - 'LANG': 'en_US.UTF-8', 13:10:47 INFO - 'LANGUAGE': 'en_US:en', 13:10:47 INFO - 'LOGNAME': 'cltbld', 13:10:47 INFO - 'MAIL': '/var/mail/cltbld', 13:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:47 INFO - 'MOZ_NO_REMOTE': '1', 13:10:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:10:47 INFO - 'NO_EM_RESTART': '1', 13:10:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:10:47 INFO - 'PWD': '/builds/slave/test-pgo', 13:10:47 INFO - 'SHELL': '/bin/bash', 13:10:47 INFO - 'SHLVL': '1', 13:10:47 INFO - 'TERM': 'linux', 13:10:47 INFO - 'TMOUT': '86400', 13:10:47 INFO - 'USER': 'cltbld', 13:10:47 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:47 INFO - '_': '/tools/buildbot/bin/python'} 13:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:47 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 13:10:47 INFO - Cleaning up... 13:10:47 INFO - Return code: 0 13:10:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:47 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:10:47 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 13:10:47 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 13:10:47 INFO - Using env: {'DISPLAY': ':0', 13:10:47 INFO - 'HOME': '/home/cltbld', 13:10:47 INFO - 'LANG': 'en_US.UTF-8', 13:10:47 INFO - 'LANGUAGE': 'en_US:en', 13:10:47 INFO - 'LOGNAME': 'cltbld', 13:10:47 INFO - 'MAIL': '/var/mail/cltbld', 13:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:47 INFO - 'MOZ_NO_REMOTE': '1', 13:10:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:10:47 INFO - 'NO_EM_RESTART': '1', 13:10:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:10:47 INFO - 'PWD': '/builds/slave/test-pgo', 13:10:47 INFO - 'SHELL': '/bin/bash', 13:10:47 INFO - 'SHLVL': '1', 13:10:47 INFO - 'TERM': 'linux', 13:10:47 INFO - 'TMOUT': '86400', 13:10:47 INFO - 'USER': 'cltbld', 13:10:47 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:47 INFO - '_': '/tools/buildbot/bin/python'} 13:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:47 INFO - Downloading/unpacking psutil>=0.7.1 13:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:49 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 13:10:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 13:10:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 13:10:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:10:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:10:49 INFO - Installing collected packages: psutil 13:10:49 INFO - Running setup.py install for psutil 13:10:49 INFO - building 'psutil._psutil_linux' extension 13:10:49 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 13:10:49 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 13:10:49 INFO - building 'psutil._psutil_posix' extension 13:10:49 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 13:10:49 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 13:10:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:10:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:10:49 INFO - Successfully installed psutil 13:10:49 INFO - Cleaning up... 13:10:49 INFO - Return code: 0 13:10:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 13:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:49 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:10:49 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 13:10:49 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 13:10:49 INFO - Using env: {'DISPLAY': ':0', 13:10:49 INFO - 'HOME': '/home/cltbld', 13:10:49 INFO - 'LANG': 'en_US.UTF-8', 13:10:49 INFO - 'LANGUAGE': 'en_US:en', 13:10:49 INFO - 'LOGNAME': 'cltbld', 13:10:49 INFO - 'MAIL': '/var/mail/cltbld', 13:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:49 INFO - 'MOZ_NO_REMOTE': '1', 13:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:10:49 INFO - 'NO_EM_RESTART': '1', 13:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:10:49 INFO - 'PWD': '/builds/slave/test-pgo', 13:10:49 INFO - 'SHELL': '/bin/bash', 13:10:49 INFO - 'SHLVL': '1', 13:10:49 INFO - 'TERM': 'linux', 13:10:49 INFO - 'TMOUT': '86400', 13:10:49 INFO - 'USER': 'cltbld', 13:10:49 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:50 INFO - '_': '/tools/buildbot/bin/python'} 13:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:10:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:10:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 13:10:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:10:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:10:51 INFO - Installing collected packages: mozsystemmonitor 13:10:51 INFO - Running setup.py install for mozsystemmonitor 13:10:52 INFO - Successfully installed mozsystemmonitor 13:10:52 INFO - Cleaning up... 13:10:52 INFO - Return code: 0 13:10:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 13:10: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')]} 13:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10: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')]} 13:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10: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', '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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:10: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 13:10: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 blobuploader==1.2.4 13:10:52 INFO - Using env: {'DISPLAY': ':0', 13:10:52 INFO - 'HOME': '/home/cltbld', 13:10:52 INFO - 'LANG': 'en_US.UTF-8', 13:10:52 INFO - 'LANGUAGE': 'en_US:en', 13:10:52 INFO - 'LOGNAME': 'cltbld', 13:10:52 INFO - 'MAIL': '/var/mail/cltbld', 13:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:52 INFO - 'MOZ_NO_REMOTE': '1', 13:10:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:10:52 INFO - 'NO_EM_RESTART': '1', 13:10:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:10:52 INFO - 'PWD': '/builds/slave/test-pgo', 13:10:52 INFO - 'SHELL': '/bin/bash', 13:10:52 INFO - 'SHLVL': '1', 13:10:52 INFO - 'TERM': 'linux', 13:10:52 INFO - 'TMOUT': '86400', 13:10:52 INFO - 'USER': 'cltbld', 13:10:52 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:52 INFO - '_': '/tools/buildbot/bin/python'} 13:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:52 INFO - Downloading/unpacking blobuploader==1.2.4 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10:53 INFO - Downloading blobuploader-1.2.4.tar.gz 13:10:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 13:10:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:10:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 13:10:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 13:10:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 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 13:10:54 INFO - Downloading docopt-0.6.1.tar.gz 13:10:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 13:10:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 13:10:54 INFO - Installing collected packages: blobuploader, requests, docopt 13:10:54 INFO - Running setup.py install for blobuploader 13:10:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:10:54 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 13:10:54 INFO - Running setup.py install for requests 13:10:55 INFO - Running setup.py install for docopt 13:10:55 INFO - Successfully installed blobuploader requests docopt 13:10:55 INFO - Cleaning up... 13:10:55 INFO - Return code: 0 13:10:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:55 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:10:55 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 13:10:55 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 13:10:55 INFO - Using env: {'DISPLAY': ':0', 13:10:55 INFO - 'HOME': '/home/cltbld', 13:10:55 INFO - 'LANG': 'en_US.UTF-8', 13:10:55 INFO - 'LANGUAGE': 'en_US:en', 13:10:55 INFO - 'LOGNAME': 'cltbld', 13:10:55 INFO - 'MAIL': '/var/mail/cltbld', 13:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:55 INFO - 'MOZ_NO_REMOTE': '1', 13:10:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:10:55 INFO - 'NO_EM_RESTART': '1', 13:10:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:10:55 INFO - 'PWD': '/builds/slave/test-pgo', 13:10:55 INFO - 'SHELL': '/bin/bash', 13:10:55 INFO - 'SHLVL': '1', 13:10:55 INFO - 'TERM': 'linux', 13:10:55 INFO - 'TMOUT': '86400', 13:10:55 INFO - 'USER': 'cltbld', 13:10:55 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:55 INFO - '_': '/tools/buildbot/bin/python'} 13:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:10:55 INFO - Running setup.py (path:/tmp/pip-rINitr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:10:55 INFO - Running setup.py (path:/tmp/pip-MjnFjA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:10:55 INFO - Running setup.py (path:/tmp/pip-Ks2C1i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:10:55 INFO - Running setup.py (path:/tmp/pip-5r4D4D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:10:55 INFO - Running setup.py (path:/tmp/pip-uomZCk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:10:55 INFO - Running setup.py (path:/tmp/pip-sfn1ft-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:10:55 INFO - Running setup.py (path:/tmp/pip-jFZW7h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:10:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:10:55 INFO - Running setup.py (path:/tmp/pip-ugdIhF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:10:56 INFO - Running setup.py (path:/tmp/pip-kOSKiP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:10:56 INFO - Running setup.py (path:/tmp/pip-bWB9nB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:10:56 INFO - Running setup.py (path:/tmp/pip-KiYJ6w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:10:56 INFO - Running setup.py (path:/tmp/pip-vOZitO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:10:56 INFO - Running setup.py (path:/tmp/pip-iRnYHb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:10:56 INFO - Running setup.py (path:/tmp/pip-PMKdSp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:10:56 INFO - Running setup.py (path:/tmp/pip-GuFIhQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:10:56 INFO - Running setup.py (path:/tmp/pip-P7xl80-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:10:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:10:56 INFO - Running setup.py (path:/tmp/pip-FKxM6C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:10:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:10:56 INFO - Running setup.py install for manifestparser 13:10:56 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 13:10:56 INFO - Running setup.py install for mozcrash 13:10:56 INFO - Running setup.py install for mozdebug 13:10:57 INFO - Running setup.py install for mozdevice 13:10:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Running setup.py install for mozfile 13:10:57 INFO - Running setup.py install for mozhttpd 13:10:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Running setup.py install for mozinfo 13:10:57 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Running setup.py install for mozInstall 13:10:57 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Running setup.py install for mozleak 13:10:57 INFO - Running setup.py install for mozlog 13:10:57 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Running setup.py install for moznetwork 13:10:57 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 13:10:57 INFO - Running setup.py install for mozprocess 13:10:57 INFO - Running setup.py install for mozprofile 13:10:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 13:10:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 13:10:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 13:10:58 INFO - Running setup.py install for mozrunner 13:10:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 13:10:58 INFO - Running setup.py install for mozscreenshot 13:10:58 INFO - Running setup.py install for moztest 13:10:58 INFO - Running setup.py install for mozversion 13:10:58 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 13:10:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:10:58 INFO - Cleaning up... 13:10:58 INFO - Return code: 0 13:10:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:10:58 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')]} 13:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:58 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')]} 13:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:10:58 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:10:58 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 13:10:58 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 13:10:58 INFO - Using env: {'DISPLAY': ':0', 13:10:58 INFO - 'HOME': '/home/cltbld', 13:10:58 INFO - 'LANG': 'en_US.UTF-8', 13:10:58 INFO - 'LANGUAGE': 'en_US:en', 13:10:58 INFO - 'LOGNAME': 'cltbld', 13:10:58 INFO - 'MAIL': '/var/mail/cltbld', 13:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:10:58 INFO - 'MOZ_NO_REMOTE': '1', 13:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:10:58 INFO - 'NO_EM_RESTART': '1', 13:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:10:58 INFO - 'PWD': '/builds/slave/test-pgo', 13:10:58 INFO - 'SHELL': '/bin/bash', 13:10:58 INFO - 'SHLVL': '1', 13:10:58 INFO - 'TERM': 'linux', 13:10:58 INFO - 'TMOUT': '86400', 13:10:58 INFO - 'USER': 'cltbld', 13:10:58 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:10:58 INFO - '_': '/tools/buildbot/bin/python'} 13:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:10:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:10:58 INFO - Running setup.py (path:/tmp/pip-D6qSnf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 13:10:58 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)) 13:10:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:10:58 INFO - Running setup.py (path:/tmp/pip-QHmNV3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 13:10:58 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)) 13:10:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:10:58 INFO - Running setup.py (path:/tmp/pip-av4lLM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 13:10:58 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)) 13:10:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:10:58 INFO - Running setup.py (path:/tmp/pip-3EJLQl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 13:10:59 INFO - Running setup.py (path:/tmp/pip-6jf0R0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:10:59 INFO - Running setup.py (path:/tmp/pip-1riiIj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:10:59 INFO - Running setup.py (path:/tmp/pip-hKZ8jz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:10:59 INFO - Running setup.py (path:/tmp/pip-rBRv8n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 13:10:59 INFO - Running setup.py (path:/tmp/pip-5YeLla-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 13:10:59 INFO - Running setup.py (path:/tmp/pip-9y71fR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:10:59 INFO - Running setup.py (path:/tmp/pip-TNkUiw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:10:59 INFO - Running setup.py (path:/tmp/pip-uYRO2r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:10:59 INFO - Running setup.py (path:/tmp/pip-ILNj9J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:10:59 INFO - Running setup.py (path:/tmp/pip-9r7Amc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:10:59 INFO - Running setup.py (path:/tmp/pip-uwTZAv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 13:10:59 INFO - Running setup.py (path:/tmp/pip-G076lT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 13:10:59 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)) 13:10:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 13:10:59 INFO - Running setup.py (path:/tmp/pip-Nzbg6K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 13:10:59 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)) 13:10:59 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)) 13:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 13:10:59 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)) 13:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 13:10:59 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)) 13:10:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:10: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 13:11:01 INFO - Downloading blessings-1.5.1.tar.gz 13:11:01 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 13:11:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 13:11:01 INFO - Installing collected packages: blessings 13:11:01 INFO - Running setup.py install for blessings 13:11:01 INFO - Successfully installed blessings 13:11:01 INFO - Cleaning up... 13:11:01 INFO - Return code: 0 13:11:01 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 13:11:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:11:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:11:02 INFO - Reading from file tmpfile_stdout 13:11:02 INFO - Current package versions: 13:11:02 INFO - argparse == 1.2.1 13:11:02 INFO - blessings == 1.5.1 13:11:02 INFO - blobuploader == 1.2.4 13:11:02 INFO - docopt == 0.6.1 13:11:02 INFO - manifestparser == 1.1 13:11:02 INFO - mozInstall == 1.12 13:11:02 INFO - mozcrash == 0.16 13:11:02 INFO - mozdebug == 0.1 13:11:02 INFO - mozdevice == 0.46 13:11:02 INFO - mozfile == 1.2 13:11:02 INFO - mozhttpd == 0.7 13:11:02 INFO - mozinfo == 0.8 13:11:02 INFO - mozleak == 0.1 13:11:02 INFO - mozlog == 3.0 13:11:02 INFO - moznetwork == 0.27 13:11:02 INFO - mozprocess == 0.22 13:11:02 INFO - mozprofile == 0.27 13:11:02 INFO - mozrunner == 6.11 13:11:02 INFO - mozscreenshot == 0.1 13:11:02 INFO - mozsystemmonitor == 0.0 13:11:02 INFO - moztest == 0.7 13:11:02 INFO - mozversion == 1.4 13:11:02 INFO - psutil == 3.1.1 13:11:02 INFO - requests == 1.2.3 13:11:02 INFO - wsgiref == 0.1.2 13:11:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 13:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:02 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:11:02 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 13:11:02 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 13:11:02 INFO - Using env: {'DISPLAY': ':0', 13:11:02 INFO - 'HOME': '/home/cltbld', 13:11:02 INFO - 'LANG': 'en_US.UTF-8', 13:11:02 INFO - 'LANGUAGE': 'en_US:en', 13:11:02 INFO - 'LOGNAME': 'cltbld', 13:11:02 INFO - 'MAIL': '/var/mail/cltbld', 13:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:02 INFO - 'MOZ_NO_REMOTE': '1', 13:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:02 INFO - 'NO_EM_RESTART': '1', 13:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:11:02 INFO - 'PWD': '/builds/slave/test-pgo', 13:11:02 INFO - 'SHELL': '/bin/bash', 13:11:02 INFO - 'SHLVL': '1', 13:11:02 INFO - 'TERM': 'linux', 13:11:02 INFO - 'TMOUT': '86400', 13:11:02 INFO - 'USER': 'cltbld', 13:11:02 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:02 INFO - '_': '/tools/buildbot/bin/python'} 13:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 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)) 13:11:02 INFO - Cleaning up... 13:11:02 INFO - Return code: 0 13:11:02 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 13:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:02 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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:11:02 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 13:11:02 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 13:11:02 INFO - Using env: {'DISPLAY': ':0', 13:11:02 INFO - 'HOME': '/home/cltbld', 13:11:02 INFO - 'LANG': 'en_US.UTF-8', 13:11:02 INFO - 'LANGUAGE': 'en_US:en', 13:11:02 INFO - 'LOGNAME': 'cltbld', 13:11:02 INFO - 'MAIL': '/var/mail/cltbld', 13:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:02 INFO - 'MOZ_NO_REMOTE': '1', 13:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:02 INFO - 'NO_EM_RESTART': '1', 13:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:11:02 INFO - 'PWD': '/builds/slave/test-pgo', 13:11:02 INFO - 'SHELL': '/bin/bash', 13:11:02 INFO - 'SHLVL': '1', 13:11:02 INFO - 'TERM': 'linux', 13:11:02 INFO - 'TMOUT': '86400', 13:11:02 INFO - 'USER': 'cltbld', 13:11:02 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:02 INFO - '_': '/tools/buildbot/bin/python'} 13:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:02 INFO - Downloading/unpacking jsonschema 13:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:04 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 13:11:04 INFO - Downloading/unpacking functools32 (from jsonschema) 13:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:04 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 13:11:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:04 INFO - warning: no files found matching '*.txt' 13:11:04 INFO - no previously-included directories found matching 'build' 13:11:04 INFO - no previously-included directories found matching 'dist' 13:11:04 INFO - no previously-included directories found matching '.git*' 13:11:04 INFO - Installing collected packages: jsonschema, functools32 13:11:04 INFO - Running setup.py install for functools32 13:11:04 INFO - warning: no files found matching '*.txt' 13:11:04 INFO - no previously-included directories found matching 'build' 13:11:04 INFO - no previously-included directories found matching 'dist' 13:11:04 INFO - no previously-included directories found matching '.git*' 13:11:04 INFO - Successfully installed jsonschema functools32 13:11:04 INFO - Cleaning up... 13:11:04 INFO - Return code: 0 13:11:04 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 13:11: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')]} 13:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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', '--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 0x7f856a2c0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6b600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, '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': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', '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 13:11:04 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 13:11:04 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 13:11:04 INFO - Using env: {'DISPLAY': ':0', 13:11:04 INFO - 'HOME': '/home/cltbld', 13:11:04 INFO - 'LANG': 'en_US.UTF-8', 13:11:04 INFO - 'LANGUAGE': 'en_US:en', 13:11:04 INFO - 'LOGNAME': 'cltbld', 13:11:04 INFO - 'MAIL': '/var/mail/cltbld', 13:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:04 INFO - 'MOZ_NO_REMOTE': '1', 13:11:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:04 INFO - 'NO_EM_RESTART': '1', 13:11:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:11:04 INFO - 'PWD': '/builds/slave/test-pgo', 13:11:04 INFO - 'SHELL': '/bin/bash', 13:11:04 INFO - 'SHLVL': '1', 13:11:04 INFO - 'TERM': 'linux', 13:11:04 INFO - 'TMOUT': '86400', 13:11:04 INFO - 'USER': 'cltbld', 13:11:04 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:04 INFO - '_': '/tools/buildbot/bin/python'} 13:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:04 INFO - Downloading/unpacking flake8 13:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:11:06 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 13:11:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:11:06 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 13:11:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:06 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 13:11:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 13:11:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 13:11:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:11:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:11:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:11:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:11:07 INFO - no previously-included directories found matching 'docs/_build' 13:11:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:11:07 INFO - Running setup.py install for pep8 13:11:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:11:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:11:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:11:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:11:07 INFO - no previously-included directories found matching 'docs/_build' 13:11:07 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 13:11:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:11:07 INFO - Cleaning up... 13:11:07 INFO - Return code: 0 13:11:07 INFO - Running post-action listener: _resource_record_post_action 13:11:07 INFO - Running post-action listener: _start_resource_monitoring 13:11:07 INFO - Starting resource monitoring. 13:11:07 INFO - ##### 13:11:07 INFO - ##### Running install step. 13:11:07 INFO - ##### 13:11:07 INFO - Running pre-action listener: _resource_record_pre_action 13:11:07 INFO - Running main action method: install 13:11:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 13:11:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 13:11:07 INFO - Reading from file tmpfile_stdout 13:11:07 INFO - Detecting whether we're running mozinstall >=1.0... 13:11:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 13:11:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 13:11:07 INFO - Reading from file tmpfile_stdout 13:11:07 INFO - Output received: 13:11:07 INFO - Usage: mozinstall [options] installer 13:11:07 INFO - Options: 13:11:07 INFO - -h, --help show this help message and exit 13:11:07 INFO - -d DEST, --destination=DEST 13:11:07 INFO - Directory to install application into. [default: 13:11:07 INFO - "/builds/slave/test-pgo"] 13:11:07 INFO - --app=APP Application being installed. [default: firefox] 13:11:07 INFO - mkdir: /builds/slave/test-pgo/build/application 13:11:07 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'] 13:11:07 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 13:11:22 INFO - Reading from file tmpfile_stdout 13:11:22 INFO - Output received: 13:11:22 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 13:11:22 INFO - Running post-action listener: _resource_record_post_action 13:11:22 INFO - ##### 13:11:22 INFO - ##### Running run-tests step. 13:11:22 INFO - ##### 13:11:22 INFO - Running pre-action listener: _resource_record_pre_action 13:11:22 INFO - Running main action method: run_tests 13:11:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 13:11:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 13:11:22 INFO - Python 2.7.3 13:11:22 INFO - Return code: 0 13:11:22 INFO - grabbing minidump binary from tooltool 13:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:22 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 0x1dfa350>, '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 0x1dfb250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfb6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 13:11:22 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 13:11:22 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 13:11:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:11:22 INFO - Return code: 0 13:11:22 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 13:11:22 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 13:11:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 13:11:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 13:11:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 13:11:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 13:11:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 13:11:23 INFO - Return code: 0 13:11:23 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/1451328874/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 13:11:23 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/1451328874/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --webServer localhost 13:11:23 INFO - Using env: {'DISPLAY': ':0', 13:11:23 INFO - 'HOME': '/home/cltbld', 13:11:23 INFO - 'LANG': 'en_US.UTF-8', 13:11:23 INFO - 'LANGUAGE': 'en_US:en', 13:11:23 INFO - 'LOGNAME': 'cltbld', 13:11:23 INFO - 'MAIL': '/var/mail/cltbld', 13:11:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:11:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 13:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:23 INFO - 'MOZ_NO_REMOTE': '1', 13:11:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 13:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:11:23 INFO - 'NO_EM_RESTART': '1', 13:11:23 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 13:11:23 INFO - 'PWD': '/builds/slave/test-pgo', 13:11:23 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 13:11:23 INFO - 'SHELL': '/bin/bash', 13:11:23 INFO - 'SHLVL': '1', 13:11:23 INFO - 'TERM': 'linux', 13:11:23 INFO - 'TMOUT': '86400', 13:11:23 INFO - 'USER': 'cltbld', 13:11:23 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451335642.518220-312537356', 13:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:23 INFO - '_': '/tools/buildbot/bin/python'} 13:11:23 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/1451328874/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--webServer', 'localhost'] with output_timeout 3600 13:11:23 INFO - mozversion INFO | application_buildid: 20151228105434 13:11:23 INFO - mozversion INFO | application_changeset: c0b161f60c6b037555b97bab0e084ec9945945dc 13:11:23 INFO - mozversion INFO | application_display_name: Nightly 13:11:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:11:23 INFO - mozversion INFO | application_name: Firefox 13:11:23 INFO - mozversion INFO | application_remotingname: firefox 13:11:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:11:23 INFO - mozversion INFO | application_vendor: Mozilla 13:11:23 INFO - mozversion INFO | application_version: 44.0 13:11:23 INFO - mozversion INFO | platform_buildid: 20151228105434 13:11:23 INFO - mozversion INFO | platform_changeset: c0b161f60c6b037555b97bab0e084ec9945945dc 13:11:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:11:23 INFO - mozversion INFO | platform_version: 44.0 13:11:23 INFO - 2015-12-28 13:11:23,220 DEBUG : using testdate: 1451337083 13:11:23 INFO - 2015-12-28 13:11:23,220 DEBUG : actual date: 1451337083 13:11:23 INFO - 2015-12-28 13:11:23,298 INFO : starting webserver on 'localhost:51394' 13:11:23 INFO - 2015-12-28 13:11:23,299 INFO : Starting test suite talos-linux64-ix-003 13:11:23 INFO - 2015-12-28 13:11:23,299 INFO : Starting test dromaeo_css 13:11:23 INFO - 2015-12-28 13:11:23,299 DEBUG : operating with platform_type : linux_ 13:11:23 INFO - 2015-12-28 13:11:23,300 INFO : Initialising browser for dromaeo_css test... 13:11:23 INFO - 2015-12-28 13:11:23,312 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGKnFni/profile http://localhost:51394/getInfo.html 13:11:27 INFO - 2015-12-28 13:11:27,113 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:11:27 INFO - 2015-12-28 13:11:27,114 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:11:27 INFO - 2015-12-28 13:11:27,114 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:11:27 INFO - 2015-12-28 13:11:27,114 DEBUG : BROWSER_OUTPUT: __metrics 13:11:27 INFO - 2015-12-28 13:11:27,218 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] 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: console.error: 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: Stack: 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:1 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 13:11:27 INFO - 2015-12-28 13:11:27,871 DEBUG : BROWSER_OUTPUT: 13:11:28 INFO - 2015-12-28 13:11:28,377 INFO : Browser initialized. 13:11:28 INFO - 2015-12-28 13:11:28,378 INFO : Running cycle 1/1 for dromaeo_css test... 13:11:28 INFO - 2015-12-28 13:11:28,379 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGKnFni/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:11:39 INFO - 2015-12-28 13:11:39,866 DEBUG : BROWSER_OUTPUT: RSS: Main: 129048576 13:11:39 INFO - 2015-12-28 13:11:39,866 DEBUG : BROWSER_OUTPUT: 13:12:30 INFO - 2015-12-28 13:12:30,734 DEBUG : BROWSER_OUTPUT: 1451337150727 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] 13:14:38 INFO - 2015-12-28 13:14:38,739 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51394/tests/dromaeo/cssquery-ext.html) 13:14:39 INFO - 2015-12-28 13:14:39,832 DEBUG : BROWSER_OUTPUT: RSS: Main: 242421760 13:14:39 INFO - 2015-12-28 13:14:39,832 DEBUG : BROWSER_OUTPUT: 13:17:41 INFO - 2015-12-28 13:17:41,913 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/dromaeo/cssquery-ext.html (next: http://localhost:51394/tests/dromaeo/cssquery-jquery.html) 13:17:42 INFO - 2015-12-28 13:17:42,474 DEBUG : BROWSER_OUTPUT: RSS: Main: 211873792 13:17:42 INFO - 2015-12-28 13:17:42,474 DEBUG : BROWSER_OUTPUT: 13:20:44 INFO - 2015-12-28 13:20:44,309 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51394/tests/dromaeo/cssquery-mootools.html) 13:20:44 INFO - 2015-12-28 13:20:44,871 DEBUG : BROWSER_OUTPUT: RSS: Main: 211292160 13:20:44 INFO - 2015-12-28 13:20:44,871 DEBUG : BROWSER_OUTPUT: 13:23:46 INFO - 2015-12-28 13:23:46,505 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51394/tests/dromaeo/cssquery-prototype.html) 13:23:47 INFO - 2015-12-28 13:23:47,078 DEBUG : BROWSER_OUTPUT: RSS: Main: 194502656 13:23:47 INFO - 2015-12-28 13:23:47,079 DEBUG : BROWSER_OUTPUT: 13:26:49 INFO - 2015-12-28 13:26:49,342 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51394/tests/dromaeo/cssquery-yui.html) 13:26:49 INFO - 2015-12-28 13:26:49,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 195743744 13:26:49 INFO - 2015-12-28 13:26:49,908 DEBUG : BROWSER_OUTPUT: 13:29:51 INFO - 2015-12-28 13:29:51,928 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/dromaeo/cssquery-yui.html (next: http://localhost:51394/tests/dromaeo/cssquery-dojo.html) 13:29:52 INFO - 2015-12-28 13:29:52,172 DEBUG : BROWSER_OUTPUT: RSS: Main: 242995200 13:29:52 INFO - 2015-12-28 13:29:52,172 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,176 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:29:52 INFO - 2015-12-28 13:29:52,176 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:29:52 INFO - 2015-12-28 13:29:52,176 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:29:52 INFO - 2015-12-28 13:29:52,176 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:29:52 INFO - 2015-12-28 13:29:52,176 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3310.586176727909;3449.465332873404;3458.0663220930364;3471.600765911919;3481.286336301057;5701.144828275756;5721;5724.112762521816;5731.283589551306;5742.196092547047;5689.260396148495;5717.514011309034;5753.309602847654;5760.884782304352;5787;4916.606671466274;5187;5212.374515058198;5286.101362768339;5313.070212712779;5115.667481613683;5130.281760553526;5140.074786538422;5141.845744627667;5147.022065807485;5015.22264049073;5044.041632089909;5114.7698353574;5130.871728206787;5131.948680513203;5105.081085404626;5189.506996835295;5224.085784987612;5233.842984710464;5237.083510385667;5160.974195129001;5187.533122019036;5208.973955130238;5238.92141617876;5239.3712754469325;5281.683099014072;5331.067063263952;5412.621116521845;5453.754581043823;5529.557635389159;6127.908081378783;6133.049377346519;6133.417325354084;6150.938490615126;6188.381161883821;6127.938720612826;6135.233095863017;6135.263768347782;6136.496558343234;6138.1099740537375;5490.066688662937;5587.357453892813;5617.831465056055;5710.400407957188;5746.2529871116485;6257.530685198628;6287.2140982377205;6290.119383286344;6301.054841773771;6306.810795676137;5799.536037117021;6097.634141951408;6107.236595425572;6112.052631842072;6113.174721412642;6001.2498437695285;6074.54440916933;6186.81439556814;6219.533534984895;6232.034034724625;6065.090236464565;6101.450869421773;6104.969475152596;6121.877562448726;6155.261368635793;5845.912311315334;5954.55340849438;6101.908471372933;6103.0050633670035;6108.1143234230785;4574.817007319736;4583.495815460296;4588.151192029485;4588.862334129982;4590.242609969385;4126.649234815074;4150.107726838744;4226.323788193874;4235.089455767024;4250.893727656833;5807.128930660435;5837.357890632027;5845.298564172326;5846.649201047951;5850.678212698257;5900.380460051632;6135.815925522242;6138.938610613926;6147.907781383283;6155.230596175478;5705.971470142623;5774.04728219839;5776.277965254343;5810.4770570648;5835.883282334329;5276.63063585553;5349.679019258857;5374.435684253175;5376.1129413647095;5389.973050134724;6066.514678825683;6134.895838532271;6397.072424498421;6422.454091402283;6422.582532135396;6070.726817293144;6151.9384806151365;6164.7842325518395;6166.445019948226;6193.102000210034;6226.25284965807;6231.719572619152;6233.065040244;6236.095766113979;6255.749770009331;5864.266966629171;5994.520438364833;6046.818595442144;6060.424259695322;6079.544034197541;6165.106059621419;6168.691565421801;6169.876602468015;6177.567570270038;6187.84530386744;5993.910091348546;6005.49672540809;6006.159137720635;6027.21646185993;6044.7279872406725;5791.53667706574;5906.675132867734;5979.521638268841;6025.879482410415;6035.305939816802;6013.037913933749;6036;6041.033434650435;6041.154238406539;6042.45617894374;6046.0930860369535;6127.111568822406;6145.754169833157;6151;6154.907676384947;5643.943560564342;5749.051406517878;5917.556183286185;6036.396360364104;6073.058675905242 13:29:52 INFO - 2015-12-28 13:29:52,180 DEBUG : BROWSER_OUTPUT: |1;ext.html;588.1090148425137;636.6021236727046;652.4356431686591;673.7540797608874;676.1581830620875;43842;44332.3350149748;44375.55624443758;44438.33342499865;44443.555564444345;47329.52670473294;47706.52293477064;47789;47819.760901195536;47842.521574784245;8259.174082591753;8380.83238335233;9329.766755831108;9516.762080947963;10715.303505272172;11066;11172.329660220414;11197.216194866363;11211.327320360764;11222.326660400362;11108.944455277713;11164;11169.273997190197;11210.66368008957;11229.382383968878;11102.389368584725;11211.439428028607;11259.099272058256;11272.887271127265;11309.49107290174;10770.353778773298;10818.188635852264;10837;10900.727481812939;10917.781644367069;43202.351964720554;43487.130257394674;43493.34759978603;43850.12299754019;43960.780196099135;3651.434027725707;3743.401055831081;3949.644531992106;4019.4573732546023;4498.550144985508;33318.83340583306;33526;33538.32923341544;33818.323633527434;34543.827280863436;3763.357827873957;3772.396416573335;3937.3897045957924;3939.3106206413763;3944.23087497938;297904;300820;301705;302917;303103.9999999978;59982;61414.38585614176;69690.3030969694;70466.29533704597;70513.29486705169;64534.35465645284;64866.35133648697;64875.35124648787;65356.346436535976;65446;18485.260589576268;18503.53741156455;18570.81429185718;18666.253349865852;18683.346082887034;14141.787873181911;14185.148891066568;14247.43010279598;14415.42338306477;14416.711665766627;23161.3051608452;23304.650430243222;23342.524219563948;23435.296941091794;23449.88275058648;8481.40630155883;8698.652053917773;8713.302935765123;8803.251723603438;8831.161039701217;1027.3219675014454;1058.3914249306679;1143.6511863881597;1185.205912038936;1222.0895432902544;29792;30078.398432031674;30333.848330758206;30747.692523074482;31181.68818311788;1134.5291703942937;1159.646307876106;1167.3929556630674;1181.261711430356;1237.1958227152243;14691.327168207948;16234.269457874423;16283.104429256267;16404.75392869108;17502.649947000988;436339;437609;440206;440911.7726468211;443039;63236.683816580626;63525.364746351945;63659;63743.36256637467;63744.36255637477;1363.222962911151;1374.093098554949;1480.7482727936276;1482.2440555316866;1490.8956373053782;31740.365192695554;31907.840460797546;31961.200969975936;32300.909258861953;32352.83823580867;51200;51832;51914;51941;52068.47931520788;313633;313950;315693;315719;316794;37274.813625931696;37630.62369376271;37713.24573508569;53219.46780532145;54000.45999539954;49721;49801.50198497969;49891.25163122483;50261.49738502568;50937.48281739835;41553.37669935014;42674.14250653486;42986.35520467133;43111.784441078846;43612.78193609012;41214.175716486105;41898.581014190684;42058.579414205466;42210.73367798971;42372;41193.794031029654;41208;41300;41648;41715.7914210427;36772.44841327329;37483.06292342713;37621.247575048896;40705.79647101864;40949.1810163801;28007.439851203268;28060.43879122447;28369.858150709115;28511.42977140487;28557 13:29:52 INFO - 2015-12-28 13:29:52,180 DEBUG : BROWSER_OUTPUT: |2;jquery.html;372.8322254985254;374.3000588898759;375.9060234941265;376.7268730170625;377.0517149369336;10493;10808.837867431996;12772.105952583323;13214.669633259173;17211;12903.096783225177;13031.934840325785;13194.208347499132;13205.867941320585;13311.467541298336;3718.274936387392;4726.645501587386;4848.248521479177;4855.975720121412;4860.295257187706;4793.18515852306;5337.439568845273;5345.786168553253;5348.946510534904;5349.7592608332525;5204.895902081956;5229.7385130743505;5257.36911570612;5258.815941442051;5334.439883812202;4533.5013148553635;5353.652012619187;5383.892322153574;5386.8383948481605;5399.541039011689;4594.517575654541;4656.4645065817185;4679.906401871977;4680.7191568506;4699.154152252593;14484.05853619522;14674.926625366912;14762.630934226623;14784.334704938196;14804.40782368704;2152.1499007891794;2156.9352919412445;2216.2907869481774;2228.8885555722313;2365.27859003004;10095.697129086138;10547.156227501782;10621.097206737437;10914.181436392302;11059.944700276528;1752.1764770557818;1790.4002159276658;1812.664657038452;1819.8908065516111;1840.9447716568525;60784.39215607831;61403.38596614022;63927.3607263926;68531;70190.29809701888;7807.492512986638;7845;7911.802204944923;7941.444098913021;7972.242636949481;9580.0898914603;9586.424814511152;9587.280953928483;9591.664291749896;9595.28035397348;6369.681515924185;6478.060681201199;6481.09264702942;6501.739930402826;6518.370407398151;6775.762848300285;6796.048553202556;6826.283240259701;6855.383015528626;6866.862662746717;3210.4060748761553;3562.3587754203973;3624.909377265537;3656.067702735785;3680.8527658893345;1262.3435813376993;1262.753763016203;1263.2988691276246;1269.847618285793;1287.2662582327982;466.2051202699457;474.12760520641496;476.4235275316836;479.261936617605;482.2452861272098;11797.88202117985;12119.939400302943;12238.449269782881;12278.508859645513;12285.447154878051;500.82220811611353;503.82366171840295;505.50460548662517;512.8333291680219;526.307905104786;6132.63204207749;6520.217573891164;6569.605823650596;6605.042268871083;6680.164979377578;610769;612387;619272;620820;628055;79042.20957790464;81306.59346703347;82833;85438.57280713557;86692.56653716817;545.8880929409462;551.4760977071718;555.8888222355545;557.9609427340047;558.3383690326975;11086;11202.887971120184;11327.943360283145;11370.374629395463;11372.772544548898;38377.42433863439;38455.2308953825;38796;39862;40005;51345.743271283405;52604;53175.734121329144;54106;54195.729021354644;7595.772126836204;7603.163651998165;7747.18654541265;7755.991721076224;7771.33943614799;9936.950315248378;12451.315177665316;12906.741865162456;12921.224726516439;13319.66700832487;11118.499667515143;11197.720056998314;11309.773804523698;11323.547058117583;11339.659810205707;10564.313319634199;10909.618163364245;11124.61063862761;11248.493817778057;11391.715207119889;8722.912770872465;8982.461052336881;10730.678079657622;10951.507182176661;11126.888731112585;7689.269519395649;7707.92292077072;7784.065912090359;7797.649105790139;7797.649105790139;11743.295402275891;11790.94104529472;12076.215046022331;12249.510019599116;12327.568535101229 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1565.1078885035533;1587.0239802521453;1825.3520000399867;1853.2772218834655;1863.1708889544154;8042.195780421955;8416.452930559524;8416.789580260489;8422.578871056445;8432.241098301167;8163.346932245421;8365.91634083659;8378.874316885252;8381.161883811616;8393.748187554367;2375.0737212487143;2389.761023897605;2389.7729715676983;2408.1812183857487;2417.2627348658652;2339.0526836631193;2358.610829213186;2365.810735141193;2372.7271363793125;2373.3473294843884;2071.647819870618;2108.019770806575;2330.685357476736;2376.3346263046374;2390.9521809563885;2313.2135074074895;2336.299110266913;2339.1695947938406;2340.4850932794757;2340.9063637454483;2352.294311706481;2427.7450867658995;2428.150147448379;2428.2229686500327;2428.3807629054477;7019.5788252705;7205.639718014131;7750.527399794021;7870.370370370357;7924.722634707815;4996.525330093636;5072.923906141411;5082.898342033176;5083.237514372837;5087;3238.17426556229;3257.1042963185077;3274.721648659867;3286.7041966223155;3303.4053870303337;1061.029158320136;1917.3481016454332;1997.9300724474574;2000.7899170587025;2049.7027930950076;11491.540338386538;11536.307821530734;11546.191766576343;11560.6531804046;11578;4343.435353404037;4398.164348773723;4402.3836662867225;4437.822487100544;4493.595576398075;9323.533823308808;9396.107369799858;9421;9425.293103017164;9438.622455101857;4961.280614310905;4974.980129073531;4981.37732783402;4985.725785081855;4993.251012348177;4619.0530941156985;4630.513796051433;4630.53694630541;4631.3516107744945;4633.49031606523;3735.327641024587;3790.6019867913474;3800.6009369015856;3813.1992281620614;3835.328817456934;1145.3700464744356;1153.0743658091008;1160.4835848047708;1161.848959635259;1162.7790719763218;1482.2588705647177;1483.7700156475764;1502.9624259393602;1510.9017913835567;1515.5226103777275;8168.387370947203;8187.3040791533385;8194.09864914859;8198.42611017235;8204.630791614392;496.0599663637459;497.0605555500035;497.4801332607434;497.77351305156066;505.54753495621213;5936.198613187251;5969.910451343233;6302.590331628429;6354.523410744213;6358.872822543523;9947.55236014381;10204.285700001077;10225.897741022494;10230.948845255725;10244;9285.907140928504;9335.626574937063;9386.342955993152;9672.322937394314;10143.746406339753;7788;7882.802929926797;7889.09275433321;7892.250236227549;7892.644830982619;7699.538027718355;7949.085855126735;7960.164182760958;8000;8009.959950200445;8867.512286824287;8877.911220887709;8897.599608017525;8977.102289771232;9041.68354107603;8645.697400590949;8662;8695.608697608497;8735.344849136467;9070.501122438065;7431.219721929336;7468.402527797872;7508.812279693051;7529.3223609873185;7658.8085297868;8280.888373464968;8305.83388332242;8325.333973282244;8329.042160151661;8341.708040218564;7450.376505648666;7740.303372696484;7763.92236077632;7835.647395867314;7874.448788584744;7469;7484.663190156347;7513.962430187814;7551.056117985252;7604.80987975305;7801.29788319054;7839.137694853675;7843.6862525498345;7849.411294152847;7856.292933635772;7096.396806271525;7119.6084215368655;7201.459890508338;7202.351788339075;7226.205117436973;7135.678894449658;7173.5337203081635;7185.245549217466;7187.640617968976;7192.604406757679 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: |4;prototype.html;341.3105526835792;343.23629923420395;345.75451429485065;348.25647243135904;351.6747009016659;5726.37009928908;5778.942210577893;5783.6529808211435;5798.98517759392;5881.911771323434;5700.37295897452;5729.770809167639;5784.334801497829;5796.333421656511;5807.099899515571;4517.209488339544;4517.932231016537;4545.704529205607;4552.2943943688615;4562.429696287964;4520.073384956077;4551.2717965125585;4552.8861778455475;4556.020455602045;4566.132434837386;4434.97782511087;4492.595666390041;4497.887552811173;4526.705764125338;4531.184386810372;4469.016816300409;4476.104779044204;4478.35063915732;4483.103379324132;4485.237509623372;3784.243151369737;3930.2925473414775;3931.646151846319;3934.213157368538;3951.7826519541413;5439.619226654136;5519.172124181365;5559.277293951801;5595.468430499096;5615;5201.297824793643;5213.2701421800975;5215.765290561896;5219.478052194788;5219.71291578963;6161.815145545641;6178.598391104609;6180.134781130645;6180.907286390708;6184.474319682832;4310.892227694301;4375.496817865955;4416.2713152329825;4418.3372494125815;4432.268675668511;6373.362663733636;6422.26143993442;6439.066335381343;6449.645269510175;6454.967725161391;4477.104579084196;4477.910441791211;4478.350639157304;4480.238359478896;4481.731096134243;4511.977440112844;4519.683622146418;4528.320751887178;4531.68278220528;4532.773361331921;5130.435652078343;5225.921611175835;5237.16205407133;5243.082460569385;5245.082110630625;6273.278572964167;6274.874502509924;6278.058291256348;6278.968605157036;6280.842978925563;1617.6764647070397;1643.441229981788;1643.8684905207795;1646.8353164683435;1646.9258883350278;1088.3361149698678;1091.8089334366614;1097.1003776902862;1099.0438318662814;1118.4407796101948;6178.4439400453275;6186.10301506288;6191.597546159485;6193.102000209944;6193.597416167935;6480.805575832733;6486.416222539994;6486.708098135595;6488.902666460007;6491.253505846794;6076.787312444043;6179.567430279927;6182.721777519932;6183.96908015466;6184.319724830265;3547.6807087361744;3575.3206890690685;3580.122869896892;3580.838862251205;3580.8925732228076;6440.516961227927;6543.378379054077;6555.770548030796;6665.466762658975;6667.166604174478;6556.3443655634055;6597.076409302702;6597.901031484532;6605.07528945948;6610.8016759497295;6621.735130594722;6622;6623.768168114093;6625.933740662532;6629.9337006630285;6343.3022367538615;6439.484841212786;6463.450606698483;6464.482841372773;6475.255345635313;6330.556861019684;6430.3891130342545;6436.259830119596;6444;6445.065465507569;6719.992001199859;6733.93266067333;6734.898976515258;6735.2927942567285;6742.932570674427;4378.715383500063;4504.414426124583;4507.706999045052;4591.104734576859;4606;6417.48660107181;6418.293987661447;6421.775237866839;6423.678816059271;6423.871522569616;6174.567780255339;6199.783007594893;6229.750809967551;6237.625742455467;6246.718897649525;6115.296740874856;6195.783147589813;6198.163247961467;6236.438720515175;6250.062490626442;6196.969015154896;6205.751769929333;6208.472279856082;6217.564770466024;6226.439620434002;6344.9048264277;6354.491640668828;6355.396237357444;6359.55483116196;6360.809175724736;6081.5134789217645;6205.317415084247;6205.379462053939;6214.316425193316;6219.440250377488 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: |5;yui.html;881.1188811188812;937.9577918993645;952.9285303602231;955.1594596754857;970.5535453691301;12895.290759008263;13274.867251327483;13305.1351662142;13350.799738003938;13352.666183345422;12516.311602861839;12519.749605007893;12539.686507837287;12546.309952952584;12652.114351995364;764.3846703403758;777.3004296133469;921.3366376209119;936.3492372800906;938.6526985015554;919.595378033663;920.1902325953151;925.8518637018078;926.3376685669745;926.6015613286285;801.5551368990238;949.2738055387634;960.8846938367406;963.3545524498568;964.113016025255;807.3420162567488;960.7069843697669;984.256886051034;984.9507524623776;986.255377190224;693.0747452151334;704.1761139466836;708.482807550491;708.7909066825299;709.3048812164056;14407.063540869915;14800.111993280438;14909.552713418614;14915.403383864632;14931.328090235966;13368.465261389532;13375.130616509896;13551.864481355258;13915.791263131061;14006.159630422208;11410;11498.540058397653;11716.414179291001;11735.94132029343;11797.764044719144;3966.6430021297933;3975.0459889626572;3985;3994.720369574131;4001.5398229203724;54124.45875541273;54199;54220;54321.45678543164;54374.18438723422;10807.135429165648;10898;10900.945495272474;10910;11041.944790275997;19388.4183474496;19496.220151193793;19587.706184407245;19666.410007699793;19769.308074217333;8316.750497484963;8493.787655308543;8714.69498567547;8753.824923501494;8984.191422771983;4570.840020599329;4641.396618439581;4767.570918617341;4775.617950563947;4817.686850354716;3408.1649995750704;3433.1588760753775;3435.038189306998;3443.1220038889924;3444.1045328214846;483.1810081911127;493.66774273652845;500.62953414473026;501.3081946913309;509.20818127811606;733.8605664923648;759.5215014540804;762.6034462079798;774.9147593764682;786.6302837666287;9194.402363846328;9449.43303401798;9513.619455221851;9514.33399662031;9606.951965240129;643.2923783837834;675.1627981303149;680.0173748932745;687.9105716256954;703.261575345895;4514.593686568159;4650;4723.315119307681;4744.525547445228;4749.287606858929;65394.67302663456;65433;65706.34293657097;65783;65813.67093164503;58331;58376.708116459144;58431.41568584345;58908;59831;51524.742376287875;51564.22653660198;51601.2259816103;52263.21605175926;53010;9522.571484283086;9541.666041688784;9650.565724542554;9827.85258221113;9872.40765554069;38031;38086.61913380831;38227;40283.1943361137;40835.79582102071;51534.22698659598;51959.22061169161;52228.21657675214;52556.47443525516;52942;8406.369522285731;8634.913650863411;8678.218960293603;8944.239739622206;9088.727338179866;12715;12816.231026138461;12958.157719748186;13372.598822035354;13373.465061397435;11682.591109311134;11850.229735067192;11862.762744745229;12167.33079680626;12240.632781016584;11997.160198786001;12082.09384296199;12334.198277111958;12394.62816115518;12408.56570020045;11269.774604508028;11551.422428878355;12320.507179712711;12447.564335248222;12537.185082969578;10603.363798172135;10790.56837726482;10825.188110891871;11204.831927521256;11653.53385864556;10090.293679442368;10524.473776311306;10592.470376481298;10832;10924.617638382937 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,839 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:29:52 INFO - 2015-12-28 13:29:52,184 DEBUG : BROWSER_OUTPUT: __startTimestamp1451338192173__endTimestamp 13:29:52 INFO - 2015-12-28 13:29:52,185 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,185 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:29:52 INFO - 2015-12-28 13:29:52,185 DEBUG : BROWSER_OUTPUT: Number of tests: 6 13:29:52 INFO - 2015-12-28 13:29:52,185 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,185 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5677.21 Median:5906.68 stddev:644.07 (10.9%) stddev-sans-first:618.71 13:29:52 INFO - 2015-12-28 13:29:52,186 DEBUG : BROWSER_OUTPUT: Values: 3310.6 3449.5 3458.1 3471.6 3481.3 5701.1 5721.0 5724.1 5731.3 5742.2 5689.3 5717.5 5753.3 5760.9 5787.0 4916.6 5187.0 5212.4 5286.1 5313.1 5115.7 5130.3 5140.1 5141.8 5147.0 5015.2 5044.0 5114.8 5130.9 5131.9 5105.1 5189.5 5224.1 5233.8 5237.1 5161.0 5187.5 5209.0 5238.9 5239.4 5281.7 5331.1 5412.6 5453.8 5529.6 6127.9 6133.0 6133.4 6150.9 6188.4 6127.9 6135.2 6135.3 6136.5 6138.1 5490.1 5587.4 5617.8 5710.4 5746.3 6257.5 6287.2 6290.1 6301.1 6306.8 5799.5 6097.6 6107.2 6112.1 6113.2 6001.2 6074.5 6186.8 6219.5 6232.0 6065.1 6101.5 6105.0 6121.9 6155.3 5845.9 5954.6 6101.9 6103.0 6108.1 4574.8 4583.5 4588.2 4588.9 4590.2 4126.6 4150.1 4226.3 4235.1 4250.9 5807.1 5837.4 5845.3 5846.6 5850.7 5900.4 6135.8 6138.9 6147.9 6155.2 5706.0 5774.0 5776.3 5810.5 5835.9 5276.6 5349.7 5374.4 5376.1 5390.0 6066.5 6134.9 6397.1 6422.5 6422.6 6070.7 6151.9 6164.8 6166.4 6193.1 6226.3 6231.7 6233.1 6236.1 6255.7 5864.3 5994.5 6046.8 6060.4 6079.5 6165.1 6168.7 6169.9 6177.6 6187.8 5993.9 6005.5 6006.2 6027.2 6044.7 5791.5 5906.7 5979.5 6025.9 6035.3 6013.0 6036.0 6041.0 6041.2 6042.5 6046.1 6127.1 6145.8 6151.0 6154.9 5643.9 5749.1 5917.6 6036.4 6073.1 13:29:52 INFO - 2015-12-28 13:29:52,187 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,187 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54693.42 Median:37552.16 stddev:93723.26 (249.6%) stddev-sans-first:93898.14 13:29:52 INFO - 2015-12-28 13:29:52,187 DEBUG : BROWSER_OUTPUT: Values: 588.1 636.6 652.4 673.8 676.2 43842.0 44332.3 44375.6 44438.3 44443.6 47329.5 47706.5 47789.0 47819.8 47842.5 8259.2 8380.8 9329.8 9516.8 10715.3 11066.0 11172.3 11197.2 11211.3 11222.3 11108.9 11164.0 11169.3 11210.7 11229.4 11102.4 11211.4 11259.1 11272.9 11309.5 10770.4 10818.2 10837.0 10900.7 10917.8 43202.4 43487.1 43493.3 43850.1 43960.8 3651.4 3743.4 3949.6 4019.5 4498.6 33318.8 33526.0 33538.3 33818.3 34543.8 3763.4 3772.4 3937.4 3939.3 3944.2 297904.0 300820.0 301705.0 302917.0 303104.0 59982.0 61414.4 69690.3 70466.3 70513.3 64534.4 64866.4 64875.4 65356.3 65446.0 18485.3 18503.5 18570.8 18666.3 18683.3 14141.8 14185.1 14247.4 14415.4 14416.7 23161.3 23304.7 23342.5 23435.3 23449.9 8481.4 8698.7 8713.3 8803.3 8831.2 1027.3 1058.4 1143.7 1185.2 1222.1 29792.0 30078.4 30333.8 30747.7 31181.7 1134.5 1159.6 1167.4 1181.3 1237.2 14691.3 16234.3 16283.1 16404.8 17502.6 436339.0 437609.0 440206.0 440911.8 443039.0 63236.7 63525.4 63659.0 63743.4 63744.4 1363.2 1374.1 1480.7 1482.2 1490.9 31740.4 31907.8 31961.2 32300.9 32352.8 51200.0 51832.0 51914.0 51941.0 52068.5 313633.0 313950.0 315693.0 315719.0 316794.0 37274.8 37630.6 37713.2 53219.5 54000.5 49721.0 49801.5 49891.3 50261.5 50937.5 41553.4 42674.1 42986.4 43111.8 43612.8 41214.2 41898.6 42058.6 42210.7 42372.0 41193.8 41208.0 41300.0 41648.0 41715.8 36772.4 37483.1 37621.2 40705.8 40949.2 28007.4 28060.4 28369.9 28511.4 28557.0 13:29:52 INFO - 2015-12-28 13:29:52,187 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29957.72 Median:2600.39 stddev:101334.48 (3896.9%) stddev-sans-first:101594.39 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: Values: 372.8 374.3 375.9 376.7 377.1 10493.0 10808.8 12772.1 13214.7 17211.0 12903.1 13031.9 13194.2 13205.9 13311.5 3718.3 4726.6 4848.2 4856.0 4860.3 4793.2 5337.4 5345.8 5348.9 5349.8 5204.9 5229.7 5257.4 5258.8 5334.4 4533.5 5353.7 5383.9 5386.8 5399.5 4594.5 4656.5 4679.9 4680.7 4699.2 14484.1 14674.9 14762.6 14784.3 14804.4 2152.1 2156.9 2216.3 2228.9 2365.3 10095.7 10547.2 10621.1 10914.2 11059.9 1752.2 1790.4 1812.7 1819.9 1840.9 60784.4 61403.4 63927.4 68531.0 70190.3 7807.5 7845.0 7911.8 7941.4 7972.2 9580.1 9586.4 9587.3 9591.7 9595.3 6369.7 6478.1 6481.1 6501.7 6518.4 6775.8 6796.0 6826.3 6855.4 6866.9 3210.4 3562.4 3624.9 3656.1 3680.9 1262.3 1262.8 1263.3 1269.8 1287.3 466.2 474.1 476.4 479.3 482.2 11797.9 12119.9 12238.4 12278.5 12285.4 500.8 503.8 505.5 512.8 526.3 6132.6 6520.2 6569.6 6605.0 6680.2 610769.0 612387.0 619272.0 620820.0 628055.0 79042.2 81306.6 82833.0 85438.6 86692.6 545.9 551.5 555.9 558.0 558.3 11086.0 11202.9 11327.9 11370.4 11372.8 38377.4 38455.2 38796.0 39862.0 40005.0 51345.7 52604.0 53175.7 54106.0 54195.7 7595.8 7603.2 7747.2 7756.0 7771.3 9937.0 12451.3 12906.7 12921.2 13319.7 11118.5 11197.7 11309.8 11323.5 11339.7 10564.3 10909.6 11124.6 11248.5 11391.7 8722.9 8982.5 10730.7 10951.5 11126.9 7689.3 7707.9 7784.1 7797.6 7797.6 11743.3 11790.9 12076.2 12249.5 12327.6 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5851.87 Median:5953.05 stddev:3026.13 (50.8%) stddev-sans-first:3017.46 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: Values: 1565.1 1587.0 1825.4 1853.3 1863.2 8042.2 8416.5 8416.8 8422.6 8432.2 8163.3 8365.9 8378.9 8381.2 8393.7 2375.1 2389.8 2389.8 2408.2 2417.3 2339.1 2358.6 2365.8 2372.7 2373.3 2071.6 2108.0 2330.7 2376.3 2391.0 2313.2 2336.3 2339.2 2340.5 2340.9 2352.3 2427.7 2428.2 2428.2 2428.4 7019.6 7205.6 7750.5 7870.4 7924.7 4996.5 5072.9 5082.9 5083.2 5087.0 3238.2 3257.1 3274.7 3286.7 3303.4 1061.0 1917.3 1997.9 2000.8 2049.7 11491.5 11536.3 11546.2 11560.7 11578.0 4343.4 4398.2 4402.4 4437.8 4493.6 9323.5 9396.1 9421.0 9425.3 9438.6 4961.3 4975.0 4981.4 4985.7 4993.3 4619.1 4630.5 4630.5 4631.4 4633.5 3735.3 3790.6 3800.6 3813.2 3835.3 1145.4 1153.1 1160.5 1161.8 1162.8 1482.3 1483.8 1503.0 1510.9 1515.5 8168.4 8187.3 8194.1 8198.4 8204.6 496.1 497.1 497.5 497.8 505.5 5936.2 5969.9 6302.6 6354.5 6358.9 9947.6 10204.3 10225.9 10230.9 10244.0 9285.9 9335.6 9386.3 9672.3 10143.7 7788.0 7882.8 7889.1 7892.3 7892.6 7699.5 7949.1 7960.2 8000.0 8010.0 8867.5 8877.9 8897.6 8977.1 9041.7 8645.7 8662.0 8695.6 8735.3 9070.5 7431.2 7468.4 7508.8 7529.3 7658.8 8280.9 8305.8 8325.3 8329.0 8341.7 7450.4 7740.3 7763.9 7835.6 7874.4 7469.0 7484.7 7514.0 7551.1 7604.8 7801.3 7839.1 7843.7 7849.4 7856.3 7096.4 7119.6 7201.5 7202.4 7226.2 7135.7 7173.5 7185.2 7187.6 7192.6 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,188 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5243.39 Median:6079.15 stddev:1562.22 (25.7%) stddev-sans-first:1522.66 13:29:52 INFO - 2015-12-28 13:29:52,190 DEBUG : BROWSER_OUTPUT: Values: 341.3 343.2 345.8 348.3 351.7 5726.4 5778.9 5783.7 5799.0 5881.9 5700.4 5729.8 5784.3 5796.3 5807.1 4517.2 4517.9 4545.7 4552.3 4562.4 4520.1 4551.3 4552.9 4556.0 4566.1 4435.0 4492.6 4497.9 4526.7 4531.2 4469.0 4476.1 4478.4 4483.1 4485.2 3784.2 3930.3 3931.6 3934.2 3951.8 5439.6 5519.2 5559.3 5595.5 5615.0 5201.3 5213.3 5215.8 5219.5 5219.7 6161.8 6178.6 6180.1 6180.9 6184.5 4310.9 4375.5 4416.3 4418.3 4432.3 6373.4 6422.3 6439.1 6449.6 6455.0 4477.1 4477.9 4478.4 4480.2 4481.7 4512.0 4519.7 4528.3 4531.7 4532.8 5130.4 5225.9 5237.2 5243.1 5245.1 6273.3 6274.9 6278.1 6279.0 6280.8 1617.7 1643.4 1643.9 1646.8 1646.9 1088.3 1091.8 1097.1 1099.0 1118.4 6178.4 6186.1 6191.6 6193.1 6193.6 6480.8 6486.4 6486.7 6488.9 6491.3 6076.8 6179.6 6182.7 6184.0 6184.3 3547.7 3575.3 3580.1 3580.8 3580.9 6440.5 6543.4 6555.8 6665.5 6667.2 6556.3 6597.1 6597.9 6605.1 6610.8 6621.7 6622.0 6623.8 6625.9 6629.9 6343.3 6439.5 6463.5 6464.5 6475.3 6330.6 6430.4 6436.3 6444.0 6445.1 6720.0 6733.9 6734.9 6735.3 6742.9 4378.7 4504.4 4507.7 4591.1 4606.0 6417.5 6418.3 6421.8 6423.7 6423.9 6174.6 6199.8 6229.8 6237.6 6246.7 6115.3 6195.8 6198.2 6236.4 6250.1 6197.0 6205.8 6208.5 6217.6 6226.4 6344.9 6354.5 6355.4 6359.6 6360.8 6081.5 6205.3 6205.4 6214.3 6219.4 13:29:52 INFO - 2015-12-28 13:29:52,190 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,190 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15272.58 Median:488.42 stddev:18232.49 (3732.9%) stddev-sans-first:18251.61 13:29:52 INFO - 2015-12-28 13:29:52,190 DEBUG : BROWSER_OUTPUT: Values: 881.1 938.0 952.9 955.2 970.6 12895.3 13274.9 13305.1 13350.8 13352.7 12516.3 12519.7 12539.7 12546.3 12652.1 764.4 777.3 921.3 936.3 938.7 919.6 920.2 925.9 926.3 926.6 801.6 949.3 960.9 963.4 964.1 807.3 960.7 984.3 985.0 986.3 693.1 704.2 708.5 708.8 709.3 14407.1 14800.1 14909.6 14915.4 14931.3 13368.5 13375.1 13551.9 13915.8 14006.2 11410.0 11498.5 11716.4 11735.9 11797.8 3966.6 3975.0 3985.0 3994.7 4001.5 54124.5 54199.0 54220.0 54321.5 54374.2 10807.1 10898.0 10900.9 10910.0 11041.9 19388.4 19496.2 19587.7 19666.4 19769.3 8316.8 8493.8 8714.7 8753.8 8984.2 4570.8 4641.4 4767.6 4775.6 4817.7 3408.2 3433.2 3435.0 3443.1 3444.1 483.2 493.7 500.6 501.3 509.2 733.9 759.5 762.6 774.9 786.6 9194.4 9449.4 9513.6 9514.3 9607.0 643.3 675.2 680.0 687.9 703.3 4514.6 4650.0 4723.3 4744.5 4749.3 65394.7 65433.0 65706.3 65783.0 65813.7 58331.0 58376.7 58431.4 58908.0 59831.0 51524.7 51564.2 51601.2 52263.2 53010.0 9522.6 9541.7 9650.6 9827.9 9872.4 38031.0 38086.6 38227.0 40283.2 40835.8 51534.2 51959.2 52228.2 52556.5 52942.0 8406.4 8634.9 8678.2 8944.2 9088.7 12715.0 12816.2 12958.2 13372.6 13373.5 11682.6 11850.2 11862.8 12167.3 12240.6 11997.2 12082.1 12334.2 12394.6 12408.6 11269.8 11551.4 12320.5 12447.6 12537.2 10603.4 10790.6 10825.2 11204.8 11653.5 10090.3 10524.5 10592.5 10832.0 10924.6 13:29:52 INFO - 2015-12-28 13:29:52,190 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:29:52 INFO - 2015-12-28 13:29:52,190 DEBUG : BROWSER_OUTPUT: 13:29:52 INFO - 2015-12-28 13:29:52,773 INFO : Browser exited with error code: 0 13:29:52 INFO - 2015-12-28 13:29:52,789 INFO : Completed test dromaeo_css (00:18:29) 13:29:52 INFO - 2015-12-28 13:29:52,789 INFO : Starting test kraken 13:29:52 INFO - 2015-12-28 13:29:52,789 DEBUG : operating with platform_type : linux_ 13:29:52 INFO - 2015-12-28 13:29:52,790 INFO : Initialising browser for kraken test... 13:29:52 INFO - 2015-12-28 13:29:52,796 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyVTHoY/profile http://localhost:51394/getInfo.html 13:29:56 INFO - 2015-12-28 13:29:56,450 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:29:56 INFO - 2015-12-28 13:29:56,450 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:29:56 INFO - 2015-12-28 13:29:56,451 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:29:56 INFO - 2015-12-28 13:29:56,451 DEBUG : BROWSER_OUTPUT: __metrics 13:29:56 INFO - 2015-12-28 13:29:56,552 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] 13:29:57 INFO - 2015-12-28 13:29:57,825 INFO : Browser initialized. 13:29:57 INFO - 2015-12-28 13:29:57,826 INFO : Running cycle 1/1 for kraken test... 13:29:57 INFO - 2015-12-28 13:29:57,826 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpyVTHoY/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:30:09 INFO - 2015-12-28 13:30:09,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 128045056 13:30:09 INFO - 2015-12-28 13:30:09,382 DEBUG : BROWSER_OUTPUT: 13:30:11 INFO - 2015-12-28 13:30:11,809 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51394/tests/kraken/driver.html?testName=audio-beat-detection) 13:30:12 INFO - 2015-12-28 13:30:12,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 163536896 13:30:12 INFO - 2015-12-28 13:30:12,378 DEBUG : BROWSER_OUTPUT: 13:30:14 INFO - 2015-12-28 13:30:14,975 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51394/tests/kraken/driver.html?testName=audio-dft) 13:30:15 INFO - 2015-12-28 13:30:15,536 DEBUG : BROWSER_OUTPUT: RSS: Main: 162037760 13:30:15 INFO - 2015-12-28 13:30:15,536 DEBUG : BROWSER_OUTPUT: 13:30:18 INFO - 2015-12-28 13:30:18,318 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51394/tests/kraken/driver.html?testName=audio-fft) 13:30:18 INFO - 2015-12-28 13:30:18,878 DEBUG : BROWSER_OUTPUT: RSS: Main: 157257728 13:30:18 INFO - 2015-12-28 13:30:18,878 DEBUG : BROWSER_OUTPUT: 13:30:20 INFO - 2015-12-28 13:30:20,842 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51394/tests/kraken/driver.html?testName=audio-oscillator) 13:30:21 INFO - 2015-12-28 13:30:21,403 DEBUG : BROWSER_OUTPUT: RSS: Main: 158928896 13:30:21 INFO - 2015-12-28 13:30:21,403 DEBUG : BROWSER_OUTPUT: 13:30:23 INFO - 2015-12-28 13:30:23,557 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51394/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:30:24 INFO - 2015-12-28 13:30:24,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 157614080 13:30:24 INFO - 2015-12-28 13:30:24,112 DEBUG : BROWSER_OUTPUT: 13:30:26 INFO - 2015-12-28 13:30:26,995 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51394/tests/kraken/driver.html?testName=imaging-darkroom) 13:30:27 INFO - 2015-12-28 13:30:27,564 DEBUG : BROWSER_OUTPUT: RSS: Main: 155688960 13:30:27 INFO - 2015-12-28 13:30:27,565 DEBUG : BROWSER_OUTPUT: 13:30:30 INFO - 2015-12-28 13:30:30,924 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51394/tests/kraken/driver.html?testName=imaging-desaturate) 13:30:31 INFO - 2015-12-28 13:30:31,490 DEBUG : BROWSER_OUTPUT: RSS: Main: 158347264 13:30:31 INFO - 2015-12-28 13:30:31,491 DEBUG : BROWSER_OUTPUT: 13:30:34 INFO - 2015-12-28 13:30:34,187 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51394/tests/kraken/driver.html?testName=json-parse-financial) 13:30:34 INFO - 2015-12-28 13:30:34,754 DEBUG : BROWSER_OUTPUT: RSS: Main: 157392896 13:30:34 INFO - 2015-12-28 13:30:34,755 DEBUG : BROWSER_OUTPUT: 13:30:36 INFO - 2015-12-28 13:30:36,264 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51394/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:30:36 INFO - 2015-12-28 13:30:36,817 DEBUG : BROWSER_OUTPUT: RSS: Main: 166445056 13:30:36 INFO - 2015-12-28 13:30:36,817 DEBUG : BROWSER_OUTPUT: 13:30:38 INFO - 2015-12-28 13:30:38,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:30:39 INFO - 2015-12-28 13:30:39,141 DEBUG : BROWSER_OUTPUT: RSS: Main: 163115008 13:30:39 INFO - 2015-12-28 13:30:39,141 DEBUG : BROWSER_OUTPUT: 13:30:41 INFO - 2015-12-28 13:30:41,092 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:30:41 INFO - 2015-12-28 13:30:41,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 168599552 13:30:41 INFO - 2015-12-28 13:30:41,658 DEBUG : BROWSER_OUTPUT: 13:30:43 INFO - 2015-12-28 13:30:43,972 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:30:44 INFO - 2015-12-28 13:30:44,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 172859392 13:30:44 INFO - 2015-12-28 13:30:44,541 DEBUG : BROWSER_OUTPUT: 13:30:46 INFO - 2015-12-28 13:30:46,980 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:30:47 INFO - 2015-12-28 13:30:47,547 DEBUG : BROWSER_OUTPUT: RSS: Main: 169279488 13:30:47 INFO - 2015-12-28 13:30:47,547 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51394/tests/kraken/driver.html?testName=ai-astar) 13:30:49 INFO - 2015-12-28 13:30:49,431 DEBUG : BROWSER_OUTPUT: RSS: Main: 228757504 13:30:49 INFO - 2015-12-28 13:30:49,431 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,432 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;106;108;110;110;100;110;102;108 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;140;146;166;129;150;158;125;172;123 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;159;163;161;161;162;159;162;161;160 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;71;155;72;72;72;71;152;72 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;140;88;90;90;141;89;89;131 13:30:49 INFO - 2015-12-28 13:30:49,433 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;110;110;110;109;109;109;110;109 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;146;146;145;146;146;145;145;146 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;95 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;55;55;55;56;54;56;54;56;54 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;65;64;64;64;64;66;64 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;76;76;78;85;78;77;80;78;77 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;109;113;115;111;115;117;109;107;113 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;138;136;137;136;135;139;134;136;151 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;62;62;65;62;63;63;64;64;63 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: __start_cc_report 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,850 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: __end_cc_report 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: __startTimestamp1451338249431__endTimestamp 13:30:49 INFO - 2015-12-28 13:30:49,434 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: Number of tests: 14 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:109.50 stddev:3.59 (3.3%) stddev-sans-first:3.76 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 106.0 108.0 110.0 110.0 100.0 110.0 102.0 108.0 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.00 Median:150.50 stddev:16.85 (11.2%) stddev-sans-first:17.78 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: Values: 151.0 140.0 146.0 166.0 129.0 150.0 158.0 125.0 172.0 123.0 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 13:30:49 INFO - 2015-12-28 13:30:49,438 DEBUG : BROWSER_OUTPUT: Values: 161.0 159.0 163.0 161.0 161.0 162.0 159.0 162.0 161.0 160.0 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.00 Median:72.00 stddev:34.53 (48.0%) stddev-sans-first:36.14 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 155.0 72.0 72.0 72.0 71.0 152.0 72.0 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:103.60 Median:89.00 stddev:23.43 (26.3%) stddev-sans-first:24.25 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 140.0 88.0 90.0 90.0 141.0 89.0 89.0 131.0 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,439 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 110.0 110.0 110.0 109.0 109.0 109.0 110.0 109.0 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 145.0 145.0 146.0 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.87 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: Values: 54.0 55.0 55.0 55.0 56.0 54.0 56.0 54.0 56.0 54.0 13:30:49 INFO - 2015-12-28 13:30:49,440 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 65.0 64.0 64.0 64.0 64.0 66.0 64.0 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:2.63 (3.4%) stddev-sans-first:2.78 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: Values: 78.0 76.0 76.0 78.0 85.0 78.0 77.0 80.0 78.0 77.0 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.60 Median:114.00 stddev:3.50 (3.1%) stddev-sans-first:3.33 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: Values: 117.0 109.0 113.0 115.0 111.0 115.0 117.0 109.0 107.0 113.0 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.00 Median:137.50 stddev:4.81 (3.5%) stddev-sans-first:5.10 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: Values: 138.0 138.0 136.0 137.0 136.0 135.0 139.0 134.0 136.0 151.0 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.10 Median:63.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 62.0 65.0 62.0 63.0 63.0 64.0 64.0 63.0 13:30:49 INFO - 2015-12-28 13:30:49,441 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:30:49 INFO - 2015-12-28 13:30:49,442 DEBUG : BROWSER_OUTPUT: 13:30:50 INFO - 2015-12-28 13:30:50,121 INFO : Browser exited with error code: 0 13:30:50 INFO - 2015-12-28 13:30:50,170 INFO : Completed test kraken (00:00:57) 13:30:50 INFO - 2015-12-28 13:30:50,170 INFO : Starting test v8_7 13:30:50 INFO - 2015-12-28 13:30:50,170 DEBUG : operating with platform_type : linux_ 13:30:50 INFO - 2015-12-28 13:30:50,170 INFO : Initialising browser for v8_7 test... 13:30:50 INFO - 2015-12-28 13:30:50,176 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmX63BO/profile http://localhost:51394/getInfo.html 13:30:53 INFO - 2015-12-28 13:30:53,700 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 13:30:53 INFO - 2015-12-28 13:30:53,700 DEBUG : BROWSER_OUTPUT: colorDepth:24 13:30:53 INFO - 2015-12-28 13:30:53,700 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 13:30:53 INFO - 2015-12-28 13:30:53,700 DEBUG : BROWSER_OUTPUT: __metrics 13:30:53 INFO - 2015-12-28 13:30:53,862 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] 13:30:54 INFO - 2015-12-28 13:30:54,941 INFO : Browser initialized. 13:30:54 INFO - 2015-12-28 13:30:54,942 INFO : Running cycle 1/1 for v8_7 test... 13:30:54 INFO - 2015-12-28 13:30:54,942 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmX63BO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 13:31:06 INFO - 2015-12-28 13:31:06,472 DEBUG : BROWSER_OUTPUT: RSS: Main: 129486848 13:31:06 INFO - 2015-12-28 13:31:06,472 DEBUG : BROWSER_OUTPUT: 13:31:27 INFO - 2015-12-28 13:31:27,772 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:51394/tests/v8_7/run.html (next: http://localhost:51394/tests/v8_7/run.html) 13:31:28 INFO - 2015-12-28 13:31:28,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 418766848 13:31:28 INFO - 2015-12-28 13:31:28,023 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,023 DEBUG : BROWSER_OUTPUT: __start_tp_report 13:31:28 INFO - 2015-12-28 13:31:28,023 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 13:31:28 INFO - 2015-12-28 13:31:28,024 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 13:31:28 INFO - 2015-12-28 13:31:28,024 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 13:31:28 INFO - 2015-12-28 13:31:28,024 DEBUG : BROWSER_OUTPUT: |0;Richards;163.8001638001638 13:31:28 INFO - 2015-12-28 13:31:28,024 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.33853738701725 13:31:28 INFO - 2015-12-28 13:31:28,024 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.56388034665923 13:31:28 INFO - 2015-12-28 13:31:28,024 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4990.049751243781 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: |4;RayTrace;951.4747859181732 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: |5;Earley;550.9641873278237 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: |6;Boyer;10427.083333333334 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: |7;RegExp;23809.52380952381 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: |8;Splay;531.3496280552604 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: __end_tp_report 13:31:28 INFO - 2015-12-28 13:31:28,025 DEBUG : BROWSER_OUTPUT: __startTimestamp1451338288022__endTimestamp 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: Number of tests: 10 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:163.80 Median:163.80 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: Values: 163.8 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.34 Median:164.34 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: Values: 164.3 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.56 Median:279.56 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,026 DEBUG : BROWSER_OUTPUT: Values: 279.6 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4990.05 Median:4990.05 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: Values: 4990.0 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:951.47 Median:951.47 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: Values: 951.5 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:550.96 Median:550.96 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: Values: 551.0 13:31:28 INFO - 2015-12-28 13:31:28,027 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10427.08 Median:10427.08 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: Values: 10427.1 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23809.52 Median:23809.52 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: Values: 23809.5 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:531.35 Median:531.35 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: Values: 531.3 13:31:28 INFO - 2015-12-28 13:31:28,028 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,029 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 13:31:28 INFO - 2015-12-28 13:31:28,029 DEBUG : BROWSER_OUTPUT: Values: 4812.5 13:31:28 INFO - 2015-12-28 13:31:28,029 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 13:31:28 INFO - 2015-12-28 13:31:28,029 DEBUG : BROWSER_OUTPUT: 13:31:28 INFO - 2015-12-28 13:31:28,574 INFO : Browser exited with error code: 0 13:31:28 INFO - 2015-12-28 13:31:28,590 INFO : Completed test v8_7 (00:00:38) 13:31:28 INFO - 2015-12-28 13:31:28,617 INFO : Completed test suite (00:20:05) 13:31:28 INFO - 2015-12-28 13:31:28,617 DEBUG : Working with test: dromaeo_css 13:31:28 INFO - 2015-12-28 13:31:28,617 DEBUG : Generating results file: dromaeo_css 13:31:28 INFO - 2015-12-28 13:31:28,618 DEBUG : Working with test: kraken 13:31:28 INFO - 2015-12-28 13:31:28,618 DEBUG : Generating results file: kraken 13:31:28 INFO - 2015-12-28 13:31:28,619 INFO : javascript benchmark 13:31:28 INFO - 2015-12-28 13:31:28,619 DEBUG : Working with test: v8_7 13:31:28 INFO - 2015-12-28 13:31:28,619 DEBUG : Generating results file: v8_7 13:31:28 INFO - 2015-12-28 13:31:28,620 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:31:28 INFO - 2015-12-28 13:31:28,675 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 13:31:28 INFO - 2015-12-28 13:31:28,675 DEBUG : process_Request line: dromaeo_css 7184.76 graph.html#tests=[[72,53,35]] 13:31:28 INFO - 2015-12-28 13:31:28,675 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:31:28 INFO - 2015-12-28 13:31:28,710 DEBUG : process_Request line: kraken 1467.30 graph.html#tests=[[232,53,35]] 13:31:28 INFO - 2015-12-28 13:31:28,710 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 13:31:28 INFO - 2015-12-28 13:31:28,740 DEBUG : process_Request line: v8_7 23424.39 graph.html#tests=[[230,53,35]] 13:31:28 INFO - 2015-12-28 13:31:28,742 INFO : javascript benchmark 13:31:28 INFO - 2015-12-28 13:31:28,745 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3310.586176727909, 3449.465332873404, 3458.0663220930364, 3471.600765911919, 3481.286336301057, 5701.144828275756, 5721.0, 5724.112762521816, 5731.283589551306, 5742.196092547047, 5689.260396148495, 5717.514011309034, 5753.309602847654, 5760.884782304352, 5787.0, 4916.606671466274, 5187.0, 5212.374515058198, 5286.101362768339, 5313.070212712779, 5115.667481613683, 5130.281760553526, 5140.074786538422, 5141.845744627667, 5147.022065807485, 5015.22264049073, 5044.041632089909, 5114.7698353574, 5130.871728206787, 5131.948680513203, 5105.081085404626, 5189.506996835295, 5224.085784987612, 5233.842984710464, 5237.083510385667, 5160.974195129001, 5187.533122019036, 5208.973955130238, 5238.92141617876, 5239.3712754469325, 5281.683099014072, 5331.067063263952, 5412.621116521845, 5453.754581043823, 5529.557635389159, 6127.908081378783, 6133.049377346519, 6133.417325354084, 6150.938490615126, 6188.381161883821, 6127.938720612826, 6135.233095863017, 6135.263768347782, 6136.496558343234, 6138.1099740537375, 5490.066688662937, 5587.357453892813, 5617.831465056055, 5710.400407957188, 5746.2529871116485, 6257.530685198628, 6287.2140982377205, 6290.119383286344, 6301.054841773771, 6306.810795676137, 5799.536037117021, 6097.634141951408, 6107.236595425572, 6112.052631842072, 6113.174721412642, 6001.2498437695285, 6074.54440916933, 6186.81439556814, 6219.533534984895, 6232.034034724625, 6065.090236464565, 6101.450869421773, 6104.969475152596, 6121.877562448726, 6155.261368635793, 5845.912311315334, 5954.55340849438, 6101.908471372933, 6103.0050633670035, 6108.1143234230785, 4574.817007319736, 4583.495815460296, 4588.151192029485, 4588.862334129982, 4590.242609969385, 4126.649234815074, 4150.107726838744, 4226.323788193874, 4235.089455767024, 4250.893727656833, 5807.128930660435, 5837.357890632027, 5845.298564172326, 5846.649201047951, 5850.678212698257, 5900.380460051632, 6135.815925522242, 6138.938610613926, 6147.907781383283, 6155.230596175478, 5705.971470142623, 5774.04728219839, 5776.277965254343, 5810.4770570648, 5835.883282334329, 5276.63063585553, 5349.679019258857, 5374.435684253175, 5376.1129413647095, 5389.973050134724, 6066.514678825683, 6134.895838532271, 6397.072424498421, 6422.454091402283, 6422.582532135396, 6070.726817293144, 6151.9384806151365, 6164.7842325518395, 6166.445019948226, 6193.102000210034, 6226.25284965807, 6231.719572619152, 6233.065040244, 6236.095766113979, 6255.749770009331, 5864.266966629171, 5994.520438364833, 6046.818595442144, 6060.424259695322, 6079.544034197541, 6165.106059621419, 6168.691565421801, 6169.876602468015, 6177.567570270038, 6187.84530386744, 5993.910091348546, 6005.49672540809, 6006.159137720635, 6027.21646185993, 6044.7279872406725, 5791.53667706574, 5906.675132867734, 5979.521638268841, 6025.879482410415, 6035.305939816802, 6013.037913933749, 6036.0, 6041.033434650435, 6041.154238406539, 6042.45617894374, 6046.0930860369535, 6127.111568822406, 6145.754169833157, 6151.0, 6154.907676384947, 5643.943560564342, 5749.051406517878, 5917.556183286185, 6036.396360364104, 6073.058675905242], "unit": "ms", "name": "dojo.html", "value": 5634.920330450224}, {"lowerIsBetter": false, "replicates": [588.1090148425137, 636.6021236727046, 652.4356431686591, 673.7540797608874, 676.1581830620875, 43842.0, 44332.3350149748, 44375.55624443758, 44438.33342499865, 44443.555564444345, 47329.52670473294, 47706.52293477064, 47789.0, 47819.760901195536, 47842.521574784245, 8259.174082591753, 8380.83238335233, 9329.766755831108, 9516.762080947963, 10715.303505272172, 11066.0, 11172.329660220414, 11197.216194866363, 11211.327320360764, 11222.326660400362, 11108.944455277713, 11164.0, 11169.273997190197, 11210.66368008957, 11229.382383968878, 11102.389368584725, 11211.439428028607, 11259.099272058256, 11272.887271127265, 11309.49107290174, 10770.353778773298, 10818.188635852264, 10837.0, 10900.727481812939, 10917.781644367069, 43202.351964720554, 43487.130257394674, 43493.34759978603, 43850.12299754019, 43960.780196099135, 3651.434027725707, 3743.401055831081, 3949.644531992106, 4019.4573732546023, 4498.550144985508, 33318.83340583306, 33526.0, 33538.32923341544, 33818.323633527434, 34543.827280863436, 3763.357827873957, 3772.396416573335, 3937.3897045957924, 3939.3106206413763, 3944.23087497938, 297904.0, 300820.0, 301705.0, 302917.0, 303103.9999999978, 59982.0, 61414.38585614176, 69690.3030969694, 70466.29533704597, 70513.29486705169, 64534.35465645284, 64866.35133648697, 64875.35124648787, 65356.346436535976, 65446.0, 18485.260589576268, 18503.53741156455, 18570.81429185718, 18666.253349865852, 18683.346082887034, 14141.787873181911, 14185.148891066568, 14247.43010279598, 14415.42338306477, 14416.711665766627, 23161.3051608452, 23304.650430243222, 23342.524219563948, 23435.296941091794, 23449.88275058648, 8481.40630155883, 8698.652053917773, 8713.302935765123, 8803.251723603438, 8831.161039701217, 1027.3219675014454, 1058.3914249306679, 1143.6511863881597, 1185.205912038936, 1222.0895432902544, 29792.0, 30078.398432031674, 30333.848330758206, 30747.692523074482, 31181.68818311788, 1134.5291703942937, 1159.646307876106, 1167.3929556630674, 1181.261711430356, 1237.1958227152243, 14691.327168207948, 16234.269457874423, 16283.104429256267, 16404.75392869108, 17502.649947000988, 436339.0, 437609.0, 440206.0, 440911.7726468211, 443039.0, 63236.683816580626, 63525.364746351945, 63659.0, 63743.36256637467, 63744.36255637477, 1363.222962911151, 1374.093098554949, 1480.7482727936276, 1482.2440555316866, 1490.8956373053782, 31740.365192695554, 31907.840460797546, 31961.200969975936, 32300.909258861953, 32352.83823580867, 51200.0, 51832.0, 51914.0, 51941.0, 52068.47931520788, 313633.0, 313950.0, 315693.0, 315719.0, 316794.0, 37274.813625931696, 37630.62369376271, 37713.24573508569, 53219.46780532145, 54000.45999539954, 49721.0, 49801.50198497969, 49891.25163122483, 50261.49738502568, 50937.48281739835, 41553.37669935014, 42674.14250653486, 42986.35520467133, 43111.784441078846, 43612.78193609012, 41214.175716486105, 41898.581014190684, 42058.579414205466, 42210.73367798971, 42372.0, 41193.794031029654, 41208.0, 41300.0, 41648.0, 41715.7914210427, 36772.44841327329, 37483.06292342713, 37621.247575048896, 40705.79647101864, 40949.1810163801, 28007.439851203268, 28060.43879122447, 28369.858150709115, 28511.42977140487, 28557.0], "unit": "ms", "name": "ext.html", "value": 21376.465517811106}, {"lowerIsBetter": false, "replicates": [372.8322254985254, 374.3000588898759, 375.9060234941265, 376.7268730170625, 377.0517149369336, 10493.0, 10808.837867431996, 12772.105952583323, 13214.669633259173, 17211.0, 12903.096783225177, 13031.934840325785, 13194.208347499132, 13205.867941320585, 13311.467541298336, 3718.274936387392, 4726.645501587386, 4848.248521479177, 4855.975720121412, 4860.295257187706, 4793.18515852306, 5337.439568845273, 5345.786168553253, 5348.946510534904, 5349.7592608332525, 5204.895902081956, 5229.7385130743505, 5257.36911570612, 5258.815941442051, 5334.439883812202, 4533.5013148553635, 5353.652012619187, 5383.892322153574, 5386.8383948481605, 5399.541039011689, 4594.517575654541, 4656.4645065817185, 4679.906401871977, 4680.7191568506, 4699.154152252593, 14484.05853619522, 14674.926625366912, 14762.630934226623, 14784.334704938196, 14804.40782368704, 2152.1499007891794, 2156.9352919412445, 2216.2907869481774, 2228.8885555722313, 2365.27859003004, 10095.697129086138, 10547.156227501782, 10621.097206737437, 10914.181436392302, 11059.944700276528, 1752.1764770557818, 1790.4002159276658, 1812.664657038452, 1819.8908065516111, 1840.9447716568525, 60784.39215607831, 61403.38596614022, 63927.3607263926, 68531.0, 70190.29809701888, 7807.492512986638, 7845.0, 7911.802204944923, 7941.444098913021, 7972.242636949481, 9580.0898914603, 9586.424814511152, 9587.280953928483, 9591.664291749896, 9595.28035397348, 6369.681515924185, 6478.060681201199, 6481.09264702942, 6501.739930402826, 6518.370407398151, 6775.762848300285, 6796.048553202556, 6826.283240259701, 6855.383015528626, 6866.862662746717, 3210.4060748761553, 3562.3587754203973, 3624.909377265537, 3656.067702735785, 3680.8527658893345, 1262.3435813376993, 1262.753763016203, 1263.2988691276246, 1269.847618285793, 1287.2662582327982, 466.2051202699457, 474.12760520641496, 476.4235275316836, 479.261936617605, 482.2452861272098, 11797.88202117985, 12119.939400302943, 12238.449269782881, 12278.508859645513, 12285.447154878051, 500.82220811611353, 503.82366171840295, 505.50460548662517, 512.8333291680219, 526.307905104786, 6132.63204207749, 6520.217573891164, 6569.605823650596, 6605.042268871083, 6680.164979377578, 610769.0, 612387.0, 619272.0, 620820.0, 628055.0, 79042.20957790464, 81306.59346703347, 82833.0, 85438.57280713557, 86692.56653716817, 545.8880929409462, 551.4760977071718, 555.8888222355545, 557.9609427340047, 558.3383690326975, 11086.0, 11202.887971120184, 11327.943360283145, 11370.374629395463, 11372.772544548898, 38377.42433863439, 38455.2308953825, 38796.0, 39862.0, 40005.0, 51345.743271283405, 52604.0, 53175.734121329144, 54106.0, 54195.729021354644, 7595.772126836204, 7603.163651998165, 7747.18654541265, 7755.991721076224, 7771.33943614799, 9936.950315248378, 12451.315177665316, 12906.741865162456, 12921.224726516439, 13319.66700832487, 11118.499667515143, 11197.720056998314, 11309.773804523698, 11323.547058117583, 11339.659810205707, 10564.313319634199, 10909.618163364245, 11124.61063862761, 11248.493817778057, 11391.715207119889, 8722.912770872465, 8982.461052336881, 10730.678079657622, 10951.507182176661, 11126.888731112585, 7689.269519395649, 7707.92292077072, 7784.065912090359, 7797.649105790139, 7797.649105790139, 11743.295402275891, 11790.94104529472, 12076.215046022331, 12249.510019599116, 12327.568535101229], "unit": "ms", "name": "jquery.html", "value": 7372.446946076545}, {"lowerIsBetter": false, "replicates": [1565.1078885035533, 1587.0239802521453, 1825.3520000399867, 1853.2772218834655, 1863.1708889544154, 8042.195780421955, 8416.452930559524, 8416.789580260489, 8422.578871056445, 8432.241098301167, 8163.346932245421, 8365.91634083659, 8378.874316885252, 8381.161883811616, 8393.748187554367, 2375.0737212487143, 2389.761023897605, 2389.7729715676983, 2408.1812183857487, 2417.2627348658652, 2339.0526836631193, 2358.610829213186, 2365.810735141193, 2372.7271363793125, 2373.3473294843884, 2071.647819870618, 2108.019770806575, 2330.685357476736, 2376.3346263046374, 2390.9521809563885, 2313.2135074074895, 2336.299110266913, 2339.1695947938406, 2340.4850932794757, 2340.9063637454483, 2352.294311706481, 2427.7450867658995, 2428.150147448379, 2428.2229686500327, 2428.3807629054477, 7019.5788252705, 7205.639718014131, 7750.527399794021, 7870.370370370357, 7924.722634707815, 4996.525330093636, 5072.923906141411, 5082.898342033176, 5083.237514372837, 5087.0, 3238.17426556229, 3257.1042963185077, 3274.721648659867, 3286.7041966223155, 3303.4053870303337, 1061.029158320136, 1917.3481016454332, 1997.9300724474574, 2000.7899170587025, 2049.7027930950076, 11491.540338386538, 11536.307821530734, 11546.191766576343, 11560.6531804046, 11578.0, 4343.435353404037, 4398.164348773723, 4402.3836662867225, 4437.822487100544, 4493.595576398075, 9323.533823308808, 9396.107369799858, 9421.0, 9425.293103017164, 9438.622455101857, 4961.280614310905, 4974.980129073531, 4981.37732783402, 4985.725785081855, 4993.251012348177, 4619.0530941156985, 4630.513796051433, 4630.53694630541, 4631.3516107744945, 4633.49031606523, 3735.327641024587, 3790.6019867913474, 3800.6009369015856, 3813.1992281620614, 3835.328817456934, 1145.3700464744356, 1153.0743658091008, 1160.4835848047708, 1161.848959635259, 1162.7790719763218, 1482.2588705647177, 1483.7700156475764, 1502.9624259393602, 1510.9017913835567, 1515.5226103777275, 8168.387370947203, 8187.3040791533385, 8194.09864914859, 8198.42611017235, 8204.630791614392, 496.0599663637459, 497.0605555500035, 497.4801332607434, 497.77351305156066, 505.54753495621213, 5936.198613187251, 5969.910451343233, 6302.590331628429, 6354.523410744213, 6358.872822543523, 9947.55236014381, 10204.285700001077, 10225.897741022494, 10230.948845255725, 10244.0, 9285.907140928504, 9335.626574937063, 9386.342955993152, 9672.322937394314, 10143.746406339753, 7788.0, 7882.802929926797, 7889.09275433321, 7892.250236227549, 7892.644830982619, 7699.538027718355, 7949.085855126735, 7960.164182760958, 8000.0, 8009.959950200445, 8867.512286824287, 8877.911220887709, 8897.599608017525, 8977.102289771232, 9041.68354107603, 8645.697400590949, 8662.0, 8695.608697608497, 8735.344849136467, 9070.501122438065, 7431.219721929336, 7468.402527797872, 7508.812279693051, 7529.3223609873185, 7658.8085297868, 8280.888373464968, 8305.83388332242, 8325.333973282244, 8329.042160151661, 8341.708040218564, 7450.376505648666, 7740.303372696484, 7763.92236077632, 7835.647395867314, 7874.448788584744, 7469.0, 7484.663190156347, 7513.962430187814, 7551.056117985252, 7604.80987975305, 7801.29788319054, 7839.137694853675, 7843.6862525498345, 7849.411294152847, 7856.292933635772, 7096.396806271525, 7119.6084215368655, 7201.459890508338, 7202.351788339075, 7226.205117436973, 7135.678894449658, 7173.5337203081635, 7185.245549217466, 7187.640617968976, 7192.604406757679], "unit": "ms", "name": "mootools.html", "value": 4762.345130917331}, {"lowerIsBetter": false, "replicates": [341.3105526835792, 343.23629923420395, 345.75451429485065, 348.25647243135904, 351.6747009016659, 5726.37009928908, 5778.942210577893, 5783.6529808211435, 5798.98517759392, 5881.911771323434, 5700.37295897452, 5729.770809167639, 5784.334801497829, 5796.333421656511, 5807.099899515571, 4517.209488339544, 4517.932231016537, 4545.704529205607, 4552.2943943688615, 4562.429696287964, 4520.073384956077, 4551.2717965125585, 4552.8861778455475, 4556.020455602045, 4566.132434837386, 4434.97782511087, 4492.595666390041, 4497.887552811173, 4526.705764125338, 4531.184386810372, 4469.016816300409, 4476.104779044204, 4478.35063915732, 4483.103379324132, 4485.237509623372, 3784.243151369737, 3930.2925473414775, 3931.646151846319, 3934.213157368538, 3951.7826519541413, 5439.619226654136, 5519.172124181365, 5559.277293951801, 5595.468430499096, 5615.0, 5201.297824793643, 5213.2701421800975, 5215.765290561896, 5219.478052194788, 5219.71291578963, 6161.815145545641, 6178.598391104609, 6180.134781130645, 6180.907286390708, 6184.474319682832, 4310.892227694301, 4375.496817865955, 4416.2713152329825, 4418.3372494125815, 4432.268675668511, 6373.362663733636, 6422.26143993442, 6439.066335381343, 6449.645269510175, 6454.967725161391, 4477.104579084196, 4477.910441791211, 4478.350639157304, 4480.238359478896, 4481.731096134243, 4511.977440112844, 4519.683622146418, 4528.320751887178, 4531.68278220528, 4532.773361331921, 5130.435652078343, 5225.921611175835, 5237.16205407133, 5243.082460569385, 5245.082110630625, 6273.278572964167, 6274.874502509924, 6278.058291256348, 6278.968605157036, 6280.842978925563, 1617.6764647070397, 1643.441229981788, 1643.8684905207795, 1646.8353164683435, 1646.9258883350278, 1088.3361149698678, 1091.8089334366614, 1097.1003776902862, 1099.0438318662814, 1118.4407796101948, 6178.4439400453275, 6186.10301506288, 6191.597546159485, 6193.102000209944, 6193.597416167935, 6480.805575832733, 6486.416222539994, 6486.708098135595, 6488.902666460007, 6491.253505846794, 6076.787312444043, 6179.567430279927, 6182.721777519932, 6183.96908015466, 6184.319724830265, 3547.6807087361744, 3575.3206890690685, 3580.122869896892, 3580.838862251205, 3580.8925732228076, 6440.516961227927, 6543.378379054077, 6555.770548030796, 6665.466762658975, 6667.166604174478, 6556.3443655634055, 6597.076409302702, 6597.901031484532, 6605.07528945948, 6610.8016759497295, 6621.735130594722, 6622.0, 6623.768168114093, 6625.933740662532, 6629.9337006630285, 6343.3022367538615, 6439.484841212786, 6463.450606698483, 6464.482841372773, 6475.255345635313, 6330.556861019684, 6430.3891130342545, 6436.259830119596, 6444.0, 6445.065465507569, 6719.992001199859, 6733.93266067333, 6734.898976515258, 6735.2927942567285, 6742.932570674427, 4378.715383500063, 4504.414426124583, 4507.706999045052, 4591.104734576859, 4606.0, 6417.48660107181, 6418.293987661447, 6421.775237866839, 6423.678816059271, 6423.871522569616, 6174.567780255339, 6199.783007594893, 6229.750809967551, 6237.625742455467, 6246.718897649525, 6115.296740874856, 6195.783147589813, 6198.163247961467, 6236.438720515175, 6250.062490626442, 6196.969015154896, 6205.751769929333, 6208.472279856082, 6217.564770466024, 6226.439620434002, 6344.9048264277, 6354.491640668828, 6355.396237357444, 6359.55483116196, 6360.809175724736, 6081.5134789217645, 6205.317415084247, 6205.379462053939, 6214.316425193316, 6219.440250377488], "unit": "ms", "name": "prototype.html", "value": 4743.573085548356}, {"lowerIsBetter": false, "replicates": [881.1188811188812, 937.9577918993645, 952.9285303602231, 955.1594596754857, 970.5535453691301, 12895.290759008263, 13274.867251327483, 13305.1351662142, 13350.799738003938, 13352.666183345422, 12516.311602861839, 12519.749605007893, 12539.686507837287, 12546.309952952584, 12652.114351995364, 764.3846703403758, 777.3004296133469, 921.3366376209119, 936.3492372800906, 938.6526985015554, 919.595378033663, 920.1902325953151, 925.8518637018078, 926.3376685669745, 926.6015613286285, 801.5551368990238, 949.2738055387634, 960.8846938367406, 963.3545524498568, 964.113016025255, 807.3420162567488, 960.7069843697669, 984.256886051034, 984.9507524623776, 986.255377190224, 693.0747452151334, 704.1761139466836, 708.482807550491, 708.7909066825299, 709.3048812164056, 14407.063540869915, 14800.111993280438, 14909.552713418614, 14915.403383864632, 14931.328090235966, 13368.465261389532, 13375.130616509896, 13551.864481355258, 13915.791263131061, 14006.159630422208, 11410.0, 11498.540058397653, 11716.414179291001, 11735.94132029343, 11797.764044719144, 3966.6430021297933, 3975.0459889626572, 3985.0, 3994.720369574131, 4001.5398229203724, 54124.45875541273, 54199.0, 54220.0, 54321.45678543164, 54374.18438723422, 10807.135429165648, 10898.0, 10900.945495272474, 10910.0, 11041.944790275997, 19388.4183474496, 19496.220151193793, 19587.706184407245, 19666.410007699793, 19769.308074217333, 8316.750497484963, 8493.787655308543, 8714.69498567547, 8753.824923501494, 8984.191422771983, 4570.840020599329, 4641.396618439581, 4767.570918617341, 4775.617950563947, 4817.686850354716, 3408.1649995750704, 3433.1588760753775, 3435.038189306998, 3443.1220038889924, 3444.1045328214846, 483.1810081911127, 493.66774273652845, 500.62953414473026, 501.3081946913309, 509.20818127811606, 733.8605664923648, 759.5215014540804, 762.6034462079798, 774.9147593764682, 786.6302837666287, 9194.402363846328, 9449.43303401798, 9513.619455221851, 9514.33399662031, 9606.951965240129, 643.2923783837834, 675.1627981303149, 680.0173748932745, 687.9105716256954, 703.261575345895, 4514.593686568159, 4650.0, 4723.315119307681, 4744.525547445228, 4749.287606858929, 65394.67302663456, 65433.0, 65706.34293657097, 65783.0, 65813.67093164503, 58331.0, 58376.708116459144, 58431.41568584345, 58908.0, 59831.0, 51524.742376287875, 51564.22653660198, 51601.2259816103, 52263.21605175926, 53010.0, 9522.571484283086, 9541.666041688784, 9650.565724542554, 9827.85258221113, 9872.40765554069, 38031.0, 38086.61913380831, 38227.0, 40283.1943361137, 40835.79582102071, 51534.22698659598, 51959.22061169161, 52228.21657675214, 52556.47443525516, 52942.0, 8406.369522285731, 8634.913650863411, 8678.218960293603, 8944.239739622206, 9088.727338179866, 12715.0, 12816.231026138461, 12958.157719748186, 13372.598822035354, 13373.465061397435, 11682.591109311134, 11850.229735067192, 11862.762744745229, 12167.33079680626, 12240.632781016584, 11997.160198786001, 12082.09384296199, 12334.198277111958, 12394.62816115518, 12408.56570020045, 11269.774604508028, 11551.422428878355, 12320.507179712711, 12447.564335248222, 12537.185082969578, 10603.363798172135, 10790.56837726482, 10825.188110891871, 11204.831927521256, 11653.53385864556, 10090.293679442368, 10524.473776311306, 10592.470376481298, 10832.0, 10924.617638382937], "unit": "ms", "name": "yui.html", "value": 6855.994924047542}], "name": "dromaeo_css", "value": 7184.755561710205}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 106.0, 108.0, 110.0, 110.0, 100.0, 110.0, 102.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [151.0, 140.0, 146.0, 166.0, 129.0, 150.0, 158.0, 125.0, 172.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.0}, {"lowerIsBetter": true, "replicates": [161.0, 159.0, 163.0, 161.0, 161.0, 162.0, 159.0, 162.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 155.0, 72.0, 72.0, 72.0, 71.0, 152.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.0}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 140.0, 88.0, 90.0, 90.0, 141.0, 89.0, 89.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 103.6}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.1}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 55.0, 55.0, 56.0, 54.0, 56.0, 54.0, 56.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 54.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [78.0, 76.0, 76.0, 78.0, 85.0, 78.0, 77.0, 80.0, 78.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.3}, {"lowerIsBetter": true, "replicates": [117.0, 109.0, 113.0, 115.0, 111.0, 115.0, 117.0, 109.0, 107.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.6}, {"lowerIsBetter": true, "replicates": [138.0, 138.0, 136.0, 137.0, 136.0, 135.0, 139.0, 134.0, 136.0, 151.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.0}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 62.0, 65.0, 62.0, 63.0, 63.0, 64.0, 64.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.1}], "name": "kraken", "value": 1467.2999999999997}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [163.8001638001638], "unit": "score", "name": "Richards", "value": 215.5187099999999}, {"lowerIsBetter": false, "replicates": [164.33853738701725], "unit": "score", "name": "DeltaBlue", "value": 402.3280300000001}, {"lowerIsBetter": false, "replicates": [279.56388034665923], "unit": "score", "name": "Encrypt", "value": 952.1294369999999}, {"lowerIsBetter": false, "replicates": [4990.049751243781], "unit": "score", "name": "Decrypt", "value": 53.34235393818545}, {"lowerIsBetter": false, "replicates": [951.4747859181732], "unit": "score", "name": "RayTrace", "value": 777.728439}, {"lowerIsBetter": false, "replicates": [550.9641873278237], "unit": "score", "name": "Earley", "value": 1209.6303449999996}, {"lowerIsBetter": false, "replicates": [10427.083333333334], "unit": "score", "name": "Boyer", "value": 63.916531468531446}, {"lowerIsBetter": false, "replicates": [23809.52380952381], "unit": "score", "name": "RegExp", "value": 38.26136999999999}, {"lowerIsBetter": false, "replicates": [531.3496280552604], "unit": "score", "name": "Splay", "value": 153.36606199999997}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23424.38943103613}]} 13:31:28 INFO - RETURN: dromaeo_css: 7184.76 13:31:28 INFO - RETURN: kraken: 1467.30 13:31:28 INFO - RETURN: v8_7: 23424.39 13:31:28 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23424.39"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1467.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7184.76"}}} 13:31:28 INFO - Return code: 0 13:31:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 13:31:28 INFO - # TBPL SUCCESS # 13:31:28 INFO - Running post-action listener: _resource_record_post_action 13:31:28 INFO - Running post-run listener: _resource_record_post_run 13:31:29 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 327680; Write bytes: 510087168; Read time: 144; Write time: 474896 13:31:29 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 8192; Write bytes: 247197696; Read time: 52; Write time: 342988 13:31:29 INFO - run-tests - Wall time: 1207s; CPU: 12.0%; Read bytes: 319488; Write bytes: 262070272; Read time: 92; Write time: 131904 13:31:29 INFO - Running post-run listener: _upload_blobber_files 13:31:29 INFO - Blob upload gear active. 13:31:29 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 13:31:29 INFO - Copying logs to upload dir... 13:31:29 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1259.408321 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2015-12-28 13:31:29.386052) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-28 13:31:29.390020) ========= 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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010022 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1451328874/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 2015-12-28 13:31:29.443955) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:31:29.444478) ========= 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=21869f6c619b95355032545b000001da-1451335642.518220-312537356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004575 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-28 13:31:29.495230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-28 13:31:29.495570) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-28 13:31:29.495875) ========= ========= Total master_lag: 0.25 =========