builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1452817599.03 results: success (0) buildid: 20160114140331 builduid: 25ba8c80d311449eb9bfbc0792aad337 revision: ee694a1db741 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.033511) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.033929) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.034209) ========= 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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005682 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.055895) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.056176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.071908) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.072219) ========= 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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.121232) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.124592) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.124985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.125261) ========= 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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-14 16:26:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 84.2M=0s 2016-01-14 16:26:39 (84.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166964 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.304232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.307575) ========= 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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016426 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.357160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.360526) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ee694a1db741 --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 ee694a1db741 --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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-14 16:26:39,606 Setting DEBUG logging. 2016-01-14 16:26:39,606 attempt 1/10 2016-01-14 16:26:39,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ee694a1db741?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-14 16:26:39,875 unpacking tar archive at: mozilla-beta-ee694a1db741/testing/mozharness/ program finished with exit code 0 elapsedTime=0.608688 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.982388) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.982813) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.990948) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.991193) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 16:26:39.991561) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-14 16:26:39.991837) ========= /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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:26:40 INFO - MultiFileLogger online at 20160114 16:26:40 in /builds/slave/test-pgo 16:26:40 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 16:26:40 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:26:40 INFO - {'append_to_log': False, 16:26:40 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:26:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:26:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:26:40 INFO - 'branch': 'Mozilla-Beta', 16:26:40 INFO - 'buildbot_json_path': 'buildprops.json', 16:26:40 INFO - 'config_files': ('talos/linux_config.py',), 16:26:40 INFO - 'default_actions': ('clobber', 16:26:40 INFO - 'read-buildbot-config', 16:26:40 INFO - 'download-and-extract', 16:26:40 INFO - 'populate-webroot', 16:26:40 INFO - 'create-virtualenv', 16:26:40 INFO - 'install', 16:26:40 INFO - 'run-tests'), 16:26:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:26:40 INFO - 'download_minidump_stackwalk': True, 16:26:40 INFO - 'download_symbols': 'ondemand', 16:26:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:26:40 INFO - 'tooltool.py': '/tools/tooltool.py', 16:26:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:26:40 INFO - '/tools/misc-python/virtualenv.py')}, 16:26:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:26:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:26:40 INFO - 'installer_path': 'installer.exe', 16:26:40 INFO - 'log_level': 'info', 16:26:40 INFO - 'log_name': 'talos', 16:26:40 INFO - 'log_to_console': True, 16:26:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:26:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:26:40 INFO - 'opt_config_files': (), 16:26:40 INFO - 'pip_index': False, 16:26:40 INFO - 'sps_profile': False, 16:26:40 INFO - 'sps_profile_interval': 0, 16:26:40 INFO - 'suite': 'dromaeojs', 16:26:40 INFO - 'system_bits': '32', 16:26:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:26:40 INFO - 'title': 'talos-linux64-ix-018', 16:26:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:26:40 INFO - 'use_talos_json': True, 16:26:40 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:26:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:26:40 INFO - 'work_dir': 'build'} 16:26:40 INFO - ##### 16:26:40 INFO - ##### Running clobber step. 16:26:40 INFO - ##### 16:26:40 INFO - Running pre-action listener: _resource_record_pre_action 16:26:40 INFO - Running main action method: clobber 16:26:40 INFO - rmtree: /builds/slave/test-pgo/build 16:26:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:26:41 INFO - Running post-action listener: _resource_record_post_action 16:26:41 INFO - ##### 16:26:41 INFO - ##### Running read-buildbot-config step. 16:26:41 INFO - ##### 16:26:41 INFO - Running pre-action listener: _resource_record_pre_action 16:26:41 INFO - Running main action method: read_buildbot_config 16:26:41 INFO - Using buildbot properties: 16:26:41 INFO - { 16:26:41 INFO - "properties": { 16:26:41 INFO - "buildnumber": 53, 16:26:41 INFO - "product": "firefox", 16:26:41 INFO - "script_repo_revision": "production", 16:26:41 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:26:41 INFO - "repository": "", 16:26:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:26:41 INFO - "buildid": "20160114140331", 16:26:41 INFO - "slavename": "talos-linux64-ix-018", 16:26:41 INFO - "pgo_build": "True", 16:26:41 INFO - "basedir": "/builds/slave/test-pgo", 16:26:41 INFO - "project": "", 16:26:41 INFO - "platform": "ubuntu64_hw", 16:26:41 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:26:41 INFO - "slavebuilddir": "test", 16:26:41 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:26:41 INFO - "branch": "mozilla-beta", 16:26:41 INFO - "repo_path": "releases/mozilla-beta", 16:26:41 INFO - "stage_platform": "linux64-pgo", 16:26:41 INFO - "builduid": "25ba8c80d311449eb9bfbc0792aad337", 16:26:41 INFO - "revision": "ee694a1db741" 16:26:41 INFO - }, 16:26:41 INFO - "sourcestamp": { 16:26:41 INFO - "repository": "", 16:26:41 INFO - "hasPatch": false, 16:26:41 INFO - "project": "", 16:26:41 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:26:41 INFO - "changes": [ 16:26:41 INFO - { 16:26:41 INFO - "category": null, 16:26:41 INFO - "files": [ 16:26:41 INFO - { 16:26:41 INFO - "url": null, 16:26:41 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2" 16:26:41 INFO - } 16:26:41 INFO - ], 16:26:41 INFO - "repository": "", 16:26:41 INFO - "rev": "ee694a1db741", 16:26:41 INFO - "who": "sendchange", 16:26:41 INFO - "when": 1452817423, 16:26:41 INFO - "number": 7463778, 16:26:41 INFO - "comments": "Bug 1224200. Allow downscaler to get (and ignore) new input lines after it has finished producing all output lines. r=milan a=ritu", 16:26:41 INFO - "project": "", 16:26:41 INFO - "at": "Thu 14 Jan 2016 16:23:43", 16:26:41 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:26:41 INFO - "revlink": "", 16:26:41 INFO - "properties": [ 16:26:41 INFO - [ 16:26:41 INFO - "buildid", 16:26:41 INFO - "20160114140331", 16:26:41 INFO - "Change" 16:26:41 INFO - ], 16:26:41 INFO - [ 16:26:41 INFO - "builduid", 16:26:41 INFO - "25ba8c80d311449eb9bfbc0792aad337", 16:26:41 INFO - "Change" 16:26:41 INFO - ], 16:26:41 INFO - [ 16:26:41 INFO - "pgo_build", 16:26:41 INFO - "True", 16:26:41 INFO - "Change" 16:26:41 INFO - ] 16:26:41 INFO - ], 16:26:41 INFO - "revision": "ee694a1db741" 16:26:41 INFO - } 16:26:41 INFO - ], 16:26:41 INFO - "revision": "ee694a1db741" 16:26:41 INFO - } 16:26:41 INFO - } 16:26:41 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2. 16:26:41 INFO - Running post-action listener: _resource_record_post_action 16:26:41 INFO - ##### 16:26:41 INFO - ##### Running download-and-extract step. 16:26:41 INFO - ##### 16:26:41 INFO - Running pre-action listener: _resource_record_pre_action 16:26:41 INFO - Running main action method: download_and_extract 16:26:41 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/test_packages.json 16:26:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:26:41 INFO - Downloaded 1270 bytes. 16:26:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:26:41 INFO - Using the following test package requirements: 16:26:41 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:26:41 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:26:41 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'jsshell-linux-x86_64.zip'], 16:26:41 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:26:41 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:26:41 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 16:26:41 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 16:26:41 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:26:41 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 16:26:41 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 16:26:41 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:26:41 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:26:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:43 INFO - Downloaded 22572217 bytes. 16:26:43 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 16:26:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:26:43 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 16:26:44 INFO - Return code: 0 16:26:44 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 16:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:44 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:26:44 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:46 INFO - Downloaded 22572217 bytes. 16:26:46 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 16:26:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 16:26:46 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 16:26:47 INFO - Return code: 0 16:26: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')]} 16:26:47 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 16:26:47 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:48 INFO - Downloaded 11131970 bytes. 16:26:48 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 16:26:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 16:26:48 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 16:26:48 INFO - Return code: 0 16:26:48 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')]} 16:26:48 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:26:48 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/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 16:26:51 INFO - Downloaded 56630066 bytes. 16:26:51 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:26:51 INFO - mkdir: /builds/slave/test-pgo/properties 16:26:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:26:51 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:26:51 INFO - Contents: 16:26:51 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2 16:26:51 INFO - Running post-action listener: _resource_record_post_action 16:26:51 INFO - Running post-action listener: set_extra_try_arguments 16:26:51 INFO - ##### 16:26:51 INFO - ##### Running populate-webroot step. 16:26:51 INFO - ##### 16:26:51 INFO - Running pre-action listener: _resource_record_pre_action 16:26:51 INFO - Running main action method: populate_webroot 16:26:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:26:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:26:51 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:26:51 INFO - '../fennec_ids.txt'], 16:26:51 INFO - 'tests': ['tcheck2']}, 16:26:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:26:51 INFO - '--tppagecycles', 16:26:51 INFO - '7'], 16:26:51 INFO - 'tests': ['tsvgm']}}, 16:26:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:26:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:26:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:26:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:26:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:26:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:26:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'tests': ['damp', 'tps']}, 16:26:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['damp', 'tps']}, 16:26:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:26:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:26:51 INFO - 'other': {'tests': ['a11yr', 16:26:51 INFO - 'ts_paint', 16:26:51 INFO - 'tpaint', 16:26:51 INFO - 'sessionrestore', 16:26:51 INFO - 'sessionrestore_no_auto_restore']}, 16:26:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['a11yr', 16:26:51 INFO - 'ts_paint', 16:26:51 INFO - 'tpaint', 16:26:51 INFO - 'sessionrestore', 16:26:51 INFO - 'sessionrestore_no_auto_restore']}, 16:26:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['a11yr', 16:26:51 INFO - 'ts_paint', 16:26:51 INFO - 'tpaint', 16:26:51 INFO - 'sessionrestore', 16:26:51 INFO - 'sessionrestore_no_auto_restore']}, 16:26:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['a11yr', 16:26:51 INFO - 'ts_paint', 16:26:51 INFO - 'tpaint', 16:26:51 INFO - 'sessionrestore', 16:26:51 INFO - 'sessionrestore_no_auto_restore']}, 16:26:51 INFO - 'other_l64': {'tests': ['a11yr', 16:26:51 INFO - 'ts_paint', 16:26:51 INFO - 'tpaint', 16:26:51 INFO - 'sessionrestore', 16:26:51 INFO - 'sessionrestore_no_auto_restore']}, 16:26:51 INFO - 'other_nol64': {'tests': ['a11yr', 16:26:51 INFO - 'ts_paint', 16:26:51 INFO - 'tpaint', 16:26:51 INFO - 'sessionrestore', 16:26:51 INFO - 'sessionrestore_no_auto_restore']}, 16:26:51 INFO - 'svgr': {'tests': ['tsvgx', 16:26:51 INFO - 'tsvgr_opacity', 16:26:51 INFO - 'tart', 16:26:51 INFO - 'tscrollx', 16:26:51 INFO - 'cart']}, 16:26:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['tsvgx', 16:26:51 INFO - 'tsvgr_opacity', 16:26:51 INFO - 'tart', 16:26:51 INFO - 'tscrollx', 16:26:51 INFO - 'cart']}, 16:26:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'tests': ['tp5o']}, 16:26:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'talos_options': ['--e10s'], 16:26:51 INFO - 'tests': ['tp5o']}, 16:26:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'talos_options': ['--xperf_path', 16:26:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:26:51 INFO - 'tests': ['tp5n']}, 16:26:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:26:51 INFO - 'talos_options': ['--e10s', 16:26:51 INFO - '--xperf_path', 16:26:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:26:51 INFO - 'tests': ['tp5n']}}, 16:26:51 INFO - 'talos.zip': {'path': '', 16:26:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:26:51 INFO - Running post-action listener: _resource_record_post_action 16:26:51 INFO - ##### 16:26:51 INFO - ##### Running create-virtualenv step. 16:26:51 INFO - ##### 16:26:51 INFO - Running pre-action listener: _resource_record_pre_action 16:26:51 INFO - Running main action method: create_virtualenv 16:26:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:26:51 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 16:26:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:26:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:26:51 INFO - Using real prefix '/usr' 16:26:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:26:51 INFO - Installing distribute.............................................................................................................................................................................................done. 16:26:53 INFO - Installing pip.................done. 16:26:53 INFO - Return code: 0 16:26:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:53 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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:26:53 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 16:26:53 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 16:26:53 INFO - Using env: {'DISPLAY': ':0', 16:26:53 INFO - 'HOME': '/home/cltbld', 16:26:53 INFO - 'LANG': 'en_US.UTF-8', 16:26:53 INFO - 'LANGUAGE': 'en_US:en', 16:26:53 INFO - 'LOGNAME': 'cltbld', 16:26:53 INFO - 'MAIL': '/var/mail/cltbld', 16:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:53 INFO - 'MOZ_NO_REMOTE': '1', 16:26:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:26:53 INFO - 'NO_EM_RESTART': '1', 16:26:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:26:53 INFO - 'PWD': '/builds/slave/test-pgo', 16:26:53 INFO - 'SHELL': '/bin/bash', 16:26:53 INFO - 'SHLVL': '1', 16:26:53 INFO - 'TERM': 'linux', 16:26:53 INFO - 'TMOUT': '86400', 16:26:53 INFO - 'USER': 'cltbld', 16:26:53 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:53 INFO - '_': '/tools/buildbot/bin/python'} 16:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:54 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 16:26:54 INFO - Cleaning up... 16:26:54 INFO - Return code: 0 16:26:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:26:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:26:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:26:54 INFO - Using env: {'DISPLAY': ':0', 16:26:54 INFO - 'HOME': '/home/cltbld', 16:26:54 INFO - 'LANG': 'en_US.UTF-8', 16:26:54 INFO - 'LANGUAGE': 'en_US:en', 16:26:54 INFO - 'LOGNAME': 'cltbld', 16:26:54 INFO - 'MAIL': '/var/mail/cltbld', 16:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:54 INFO - 'MOZ_NO_REMOTE': '1', 16:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:26:54 INFO - 'NO_EM_RESTART': '1', 16:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:26:54 INFO - 'PWD': '/builds/slave/test-pgo', 16:26:54 INFO - 'SHELL': '/bin/bash', 16:26:54 INFO - 'SHLVL': '1', 16:26:54 INFO - 'TERM': 'linux', 16:26:54 INFO - 'TMOUT': '86400', 16:26:54 INFO - 'USER': 'cltbld', 16:26:54 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:54 INFO - '_': '/tools/buildbot/bin/python'} 16:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:54 INFO - Downloading/unpacking psutil>=0.7.1 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:56 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:26:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:26:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:26:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:26:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:26:56 INFO - Installing collected packages: psutil 16:26:56 INFO - Running setup.py install for psutil 16:26:56 INFO - building 'psutil._psutil_linux' extension 16:26:56 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 16:26:56 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 16:26:56 INFO - building 'psutil._psutil_posix' extension 16:26:56 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 16:26:56 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 16:26:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:26:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:26:56 INFO - Successfully installed psutil 16:26:56 INFO - Cleaning up... 16:26:56 INFO - Return code: 0 16:26:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:56 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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:26:56 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 16:26:56 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 16:26:56 INFO - Using env: {'DISPLAY': ':0', 16:26:56 INFO - 'HOME': '/home/cltbld', 16:26:56 INFO - 'LANG': 'en_US.UTF-8', 16:26:56 INFO - 'LANGUAGE': 'en_US:en', 16:26:56 INFO - 'LOGNAME': 'cltbld', 16:26:56 INFO - 'MAIL': '/var/mail/cltbld', 16:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:56 INFO - 'MOZ_NO_REMOTE': '1', 16:26:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:26:56 INFO - 'NO_EM_RESTART': '1', 16:26:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:26:56 INFO - 'PWD': '/builds/slave/test-pgo', 16:26:56 INFO - 'SHELL': '/bin/bash', 16:26:56 INFO - 'SHLVL': '1', 16:26:56 INFO - 'TERM': 'linux', 16:26:56 INFO - 'TMOUT': '86400', 16:26:56 INFO - 'USER': 'cltbld', 16:26:56 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:56 INFO - '_': '/tools/buildbot/bin/python'} 16:26:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:26:56 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 16:26:56 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 16:26:56 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 16:26:56 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 16:26:56 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 16:26:56 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 16:26:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:26:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:26:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:26:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:26:58 INFO - Installing collected packages: mozsystemmonitor 16:26:58 INFO - Running setup.py install for mozsystemmonitor 16:26:58 INFO - Successfully installed mozsystemmonitor 16:26:58 INFO - Cleaning up... 16:26:58 INFO - Return code: 0 16:26:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:26: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')]} 16:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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', '--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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:26:58 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 16:26:58 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 16:26:58 INFO - Using env: {'DISPLAY': ':0', 16:26:58 INFO - 'HOME': '/home/cltbld', 16:26:58 INFO - 'LANG': 'en_US.UTF-8', 16:26:58 INFO - 'LANGUAGE': 'en_US:en', 16:26:58 INFO - 'LOGNAME': 'cltbld', 16:26:58 INFO - 'MAIL': '/var/mail/cltbld', 16:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:58 INFO - 'MOZ_NO_REMOTE': '1', 16:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:26:58 INFO - 'NO_EM_RESTART': '1', 16:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:26:58 INFO - 'PWD': '/builds/slave/test-pgo', 16:26:58 INFO - 'SHELL': '/bin/bash', 16:26:58 INFO - 'SHLVL': '1', 16:26:58 INFO - 'TERM': 'linux', 16:26:58 INFO - 'TMOUT': '86400', 16:26:58 INFO - 'USER': 'cltbld', 16:26:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:58 INFO - '_': '/tools/buildbot/bin/python'} 16:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:58 INFO - Downloading/unpacking blobuploader==1.2.4 16:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:26:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:26:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:26:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:27:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:27:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:27:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:27:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:27:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:27:01 INFO - Downloading docopt-0.6.1.tar.gz 16:27:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:27:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:27:01 INFO - Installing collected packages: blobuploader, requests, docopt 16:27:01 INFO - Running setup.py install for blobuploader 16:27:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:27:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:27:01 INFO - Running setup.py install for requests 16:27:01 INFO - Running setup.py install for docopt 16:27:01 INFO - Successfully installed blobuploader requests docopt 16:27:01 INFO - Cleaning up... 16:27:02 INFO - Return code: 0 16:27:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:27: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')]} 16:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27: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')]} 16:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:02 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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:27:02 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 16:27:02 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 16:27:02 INFO - Using env: {'DISPLAY': ':0', 16:27:02 INFO - 'HOME': '/home/cltbld', 16:27:02 INFO - 'LANG': 'en_US.UTF-8', 16:27:02 INFO - 'LANGUAGE': 'en_US:en', 16:27:02 INFO - 'LOGNAME': 'cltbld', 16:27:02 INFO - 'MAIL': '/var/mail/cltbld', 16:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:02 INFO - 'MOZ_NO_REMOTE': '1', 16:27:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:02 INFO - 'NO_EM_RESTART': '1', 16:27:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:27:02 INFO - 'PWD': '/builds/slave/test-pgo', 16:27:02 INFO - 'SHELL': '/bin/bash', 16:27:02 INFO - 'SHLVL': '1', 16:27:02 INFO - 'TERM': 'linux', 16:27:02 INFO - 'TMOUT': '86400', 16:27:02 INFO - 'USER': 'cltbld', 16:27:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:02 INFO - '_': '/tools/buildbot/bin/python'} 16:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:27:02 INFO - Running setup.py (path:/tmp/pip-y7uIDx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:27:02 INFO - Running setup.py (path:/tmp/pip-TjtKUS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:27:02 INFO - Running setup.py (path:/tmp/pip-OZLNYI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:27:02 INFO - Running setup.py (path:/tmp/pip-x8vBMN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:27:02 INFO - Running setup.py (path:/tmp/pip-trG7Gf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:27:02 INFO - Running setup.py (path:/tmp/pip-VMTmso-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:27:02 INFO - Running setup.py (path:/tmp/pip-gYZPl5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:27:02 INFO - Running setup.py (path:/tmp/pip-AUgPaf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:27:02 INFO - Running setup.py (path:/tmp/pip-olzspS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:27:02 INFO - Running setup.py (path:/tmp/pip-L32i6G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:27:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:27:02 INFO - Running setup.py (path:/tmp/pip-OpQhFK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:27:03 INFO - Running setup.py (path:/tmp/pip-J1UFWd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:27:03 INFO - Running setup.py (path:/tmp/pip-tCaMSp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:27:03 INFO - Running setup.py (path:/tmp/pip-ttpOQd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:27:03 INFO - Running setup.py (path:/tmp/pip-l5x8in-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:27:03 INFO - Running setup.py (path:/tmp/pip-2nAnhQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:27:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:27:03 INFO - Running setup.py (path:/tmp/pip-kGxflD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:27:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:27:03 INFO - Running setup.py install for manifestparser 16:27:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:27:03 INFO - Running setup.py install for mozcrash 16:27:03 INFO - Running setup.py install for mozdebug 16:27:03 INFO - Running setup.py install for mozdevice 16:27:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:27:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:27:03 INFO - Running setup.py install for mozfile 16:27:04 INFO - Running setup.py install for mozhttpd 16:27:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for mozinfo 16:27:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for mozInstall 16:27:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for mozleak 16:27:04 INFO - Running setup.py install for mozlog 16:27:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for moznetwork 16:27:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for mozprocess 16:27:04 INFO - Running setup.py install for mozprofile 16:27:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for mozrunner 16:27:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:27:04 INFO - Running setup.py install for mozscreenshot 16:27:05 INFO - Running setup.py install for moztest 16:27:05 INFO - Running setup.py install for mozversion 16:27:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:27:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:27:05 INFO - Cleaning up... 16:27:05 INFO - Return code: 0 16:27:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:05 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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:27:05 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 16:27:05 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 16:27:05 INFO - Using env: {'DISPLAY': ':0', 16:27:05 INFO - 'HOME': '/home/cltbld', 16:27:05 INFO - 'LANG': 'en_US.UTF-8', 16:27:05 INFO - 'LANGUAGE': 'en_US:en', 16:27:05 INFO - 'LOGNAME': 'cltbld', 16:27:05 INFO - 'MAIL': '/var/mail/cltbld', 16:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:05 INFO - 'MOZ_NO_REMOTE': '1', 16:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:05 INFO - 'NO_EM_RESTART': '1', 16:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:27:05 INFO - 'PWD': '/builds/slave/test-pgo', 16:27:05 INFO - 'SHELL': '/bin/bash', 16:27:05 INFO - 'SHLVL': '1', 16:27:05 INFO - 'TERM': 'linux', 16:27:05 INFO - 'TMOUT': '86400', 16:27:05 INFO - 'USER': 'cltbld', 16:27:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:05 INFO - '_': '/tools/buildbot/bin/python'} 16:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:27:05 INFO - Running setup.py (path:/tmp/pip-R3bqQ0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:27:05 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)) 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:27:05 INFO - Running setup.py (path:/tmp/pip-QcQEaH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:27:05 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)) 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:27:05 INFO - Running setup.py (path:/tmp/pip-5UTMsi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:27:05 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)) 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:27:05 INFO - Running setup.py (path:/tmp/pip-uLjnLv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:27:05 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)) 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:27:05 INFO - Running setup.py (path:/tmp/pip-s6ZGPb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:27:05 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)) 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:27:05 INFO - Running setup.py (path:/tmp/pip-ibVAxN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:27:05 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)) 16:27:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:27:05 INFO - Running setup.py (path:/tmp/pip-B7ZJ37-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:27:06 INFO - Running setup.py (path:/tmp/pip-R6SXRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:27:06 INFO - Running setup.py (path:/tmp/pip-7s7uZ4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:27:06 INFO - Running setup.py (path:/tmp/pip-9s2h55-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:27:06 INFO - Running setup.py (path:/tmp/pip-tP9kFW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:27:06 INFO - Running setup.py (path:/tmp/pip-fCVx1J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:27:06 INFO - Running setup.py (path:/tmp/pip-qYMkYk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:27:06 INFO - Running setup.py (path:/tmp/pip-0BaU4E-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:27:06 INFO - Running setup.py (path:/tmp/pip-gMLJeY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:27:06 INFO - Running setup.py (path:/tmp/pip-s8n4dz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:27:06 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)) 16:27:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:27:06 INFO - Running setup.py (path:/tmp/pip-pXDOKf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:27:06 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)) 16:27:06 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)) 16:27:06 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)) 16:27:06 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)) 16:27:06 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)) 16:27:06 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)) 16:27:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27: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 16:27:08 INFO - Downloading blessings-1.5.1.tar.gz 16:27:08 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 16:27:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:27:08 INFO - Installing collected packages: blessings 16:27:08 INFO - Running setup.py install for blessings 16:27:08 INFO - Successfully installed blessings 16:27:08 INFO - Cleaning up... 16:27:08 INFO - Return code: 0 16:27:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:27:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:27:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:27:08 INFO - Reading from file tmpfile_stdout 16:27:08 INFO - Current package versions: 16:27:08 INFO - argparse == 1.2.1 16:27:08 INFO - blessings == 1.5.1 16:27:08 INFO - blobuploader == 1.2.4 16:27:08 INFO - docopt == 0.6.1 16:27:08 INFO - manifestparser == 1.1 16:27:08 INFO - mozInstall == 1.12 16:27:08 INFO - mozcrash == 0.16 16:27:08 INFO - mozdebug == 0.1 16:27:08 INFO - mozdevice == 0.46 16:27:08 INFO - mozfile == 1.2 16:27:08 INFO - mozhttpd == 0.7 16:27:08 INFO - mozinfo == 0.8 16:27:08 INFO - mozleak == 0.1 16:27:08 INFO - mozlog == 3.0 16:27:08 INFO - moznetwork == 0.27 16:27:08 INFO - mozprocess == 0.22 16:27:08 INFO - mozprofile == 0.27 16:27:08 INFO - mozrunner == 6.11 16:27:08 INFO - mozscreenshot == 0.1 16:27:08 INFO - mozsystemmonitor == 0.0 16:27:08 INFO - moztest == 0.7 16:27:08 INFO - mozversion == 1.4 16:27:08 INFO - psutil == 3.1.1 16:27:08 INFO - requests == 1.2.3 16:27:08 INFO - wsgiref == 0.1.2 16:27:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:27:08 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 16:27:08 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 16:27:08 INFO - Using env: {'DISPLAY': ':0', 16:27:08 INFO - 'HOME': '/home/cltbld', 16:27:08 INFO - 'LANG': 'en_US.UTF-8', 16:27:08 INFO - 'LANGUAGE': 'en_US:en', 16:27:08 INFO - 'LOGNAME': 'cltbld', 16:27:08 INFO - 'MAIL': '/var/mail/cltbld', 16:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:08 INFO - 'MOZ_NO_REMOTE': '1', 16:27:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:08 INFO - 'NO_EM_RESTART': '1', 16:27:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:27:08 INFO - 'PWD': '/builds/slave/test-pgo', 16:27:08 INFO - 'SHELL': '/bin/bash', 16:27:08 INFO - 'SHLVL': '1', 16:27:08 INFO - 'TERM': 'linux', 16:27:08 INFO - 'TMOUT': '86400', 16:27:08 INFO - 'USER': 'cltbld', 16:27:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:08 INFO - '_': '/tools/buildbot/bin/python'} 16:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 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)) 16:27:09 INFO - Cleaning up... 16:27:09 INFO - Return code: 0 16:27:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:09 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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:27:09 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 16:27:09 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 16:27:09 INFO - Using env: {'DISPLAY': ':0', 16:27:09 INFO - 'HOME': '/home/cltbld', 16:27:09 INFO - 'LANG': 'en_US.UTF-8', 16:27:09 INFO - 'LANGUAGE': 'en_US:en', 16:27:09 INFO - 'LOGNAME': 'cltbld', 16:27:09 INFO - 'MAIL': '/var/mail/cltbld', 16:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:09 INFO - 'MOZ_NO_REMOTE': '1', 16:27:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:09 INFO - 'NO_EM_RESTART': '1', 16:27:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:27:09 INFO - 'PWD': '/builds/slave/test-pgo', 16:27:09 INFO - 'SHELL': '/bin/bash', 16:27:09 INFO - 'SHLVL': '1', 16:27:09 INFO - 'TERM': 'linux', 16:27:09 INFO - 'TMOUT': '86400', 16:27:09 INFO - 'USER': 'cltbld', 16:27:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:09 INFO - '_': '/tools/buildbot/bin/python'} 16:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:09 INFO - Downloading/unpacking jsonschema 16:27:09 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 16:27:09 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 16:27:09 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 16:27:09 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 16:27:09 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 16:27:09 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 16:27:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:27:11 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 16:27:11 INFO - Downloading/unpacking functools32 (from jsonschema) 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:27:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:27:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:27:11 INFO - warning: no files found matching '*.txt' 16:27:11 INFO - no previously-included directories found matching 'build' 16:27:11 INFO - no previously-included directories found matching 'dist' 16:27:11 INFO - no previously-included directories found matching '.git*' 16:27:11 INFO - Installing collected packages: jsonschema, functools32 16:27:11 INFO - Running setup.py install for functools32 16:27:11 INFO - warning: no files found matching '*.txt' 16:27:11 INFO - no previously-included directories found matching 'build' 16:27:11 INFO - no previously-included directories found matching 'dist' 16:27:11 INFO - no previously-included directories found matching '.git*' 16:27:11 INFO - Successfully installed jsonschema functools32 16:27:11 INFO - Cleaning up... 16:27:11 INFO - Return code: 0 16:27:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:27:11 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')]} 16:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:27:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:11 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')]} 16:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:27:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:27:11 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 0x7f454913bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20fa8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21c7600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, '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': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', '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 16:27:11 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 16:27:11 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 16:27:11 INFO - Using env: {'DISPLAY': ':0', 16:27:11 INFO - 'HOME': '/home/cltbld', 16:27:11 INFO - 'LANG': 'en_US.UTF-8', 16:27:11 INFO - 'LANGUAGE': 'en_US:en', 16:27:11 INFO - 'LOGNAME': 'cltbld', 16:27:11 INFO - 'MAIL': '/var/mail/cltbld', 16:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:11 INFO - 'MOZ_NO_REMOTE': '1', 16:27:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:11 INFO - 'NO_EM_RESTART': '1', 16:27:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:27:11 INFO - 'PWD': '/builds/slave/test-pgo', 16:27:11 INFO - 'SHELL': '/bin/bash', 16:27:11 INFO - 'SHLVL': '1', 16:27:11 INFO - 'TERM': 'linux', 16:27:11 INFO - 'TMOUT': '86400', 16:27:11 INFO - 'USER': 'cltbld', 16:27:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:11 INFO - '_': '/tools/buildbot/bin/python'} 16:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:27:11 INFO - Downloading/unpacking flake8 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 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 16:27:11 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 16:27:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:27:13 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 16:27:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:27:13 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 16:27:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:13 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 16:27:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:27:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:27:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:27:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:27:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:27:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:27:14 INFO - no previously-included directories found matching 'docs/_build' 16:27:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:27:14 INFO - Running setup.py install for pep8 16:27:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:27:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:27:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:27:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:27:14 INFO - no previously-included directories found matching 'docs/_build' 16:27:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:27:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:27:14 INFO - Cleaning up... 16:27:14 INFO - Return code: 0 16:27:14 INFO - Running post-action listener: _resource_record_post_action 16:27:14 INFO - Running post-action listener: _start_resource_monitoring 16:27:14 INFO - Starting resource monitoring. 16:27:14 INFO - ##### 16:27:14 INFO - ##### Running install step. 16:27:14 INFO - ##### 16:27:14 INFO - Running pre-action listener: _resource_record_pre_action 16:27:14 INFO - Running main action method: install 16:27:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:27:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:27:14 INFO - Reading from file tmpfile_stdout 16:27:14 INFO - Detecting whether we're running mozinstall >=1.0... 16:27:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:27:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:27:14 INFO - Reading from file tmpfile_stdout 16:27:14 INFO - Output received: 16:27:14 INFO - Usage: mozinstall [options] installer 16:27:14 INFO - Options: 16:27:14 INFO - -h, --help show this help message and exit 16:27:14 INFO - -d DEST, --destination=DEST 16:27:14 INFO - Directory to install application into. [default: 16:27:14 INFO - "/builds/slave/test-pgo"] 16:27:14 INFO - --app=APP Application being installed. [default: firefox] 16:27:14 INFO - mkdir: /builds/slave/test-pgo/build/application 16:27:14 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'] 16:27:14 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 16:27:28 INFO - Reading from file tmpfile_stdout 16:27:28 INFO - Output received: 16:27:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:27:28 INFO - Running post-action listener: _resource_record_post_action 16:27:28 INFO - ##### 16:27:28 INFO - ##### Running run-tests step. 16:27:28 INFO - ##### 16:27:28 INFO - Running pre-action listener: _resource_record_pre_action 16:27:28 INFO - Running main action method: run_tests 16:27:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:27:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:27:28 INFO - Python 2.7.3 16:27:28 INFO - Return code: 0 16:27:28 INFO - grabbing minidump binary from tooltool 16:27:28 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')]} 16:27:28 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 0x2256350>, '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 0x2257250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22576e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:27:28 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 16:27:28 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 16:27:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:27:28 INFO - Return code: 0 16:27:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:27:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:27:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:27:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:27:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:27:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:27:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:27:29 INFO - Return code: 0 16:27:29 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/1452809011/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:27:29 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/1452809011/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost 16:27:29 INFO - Using env: {'DISPLAY': ':0', 16:27:29 INFO - 'HOME': '/home/cltbld', 16:27:29 INFO - 'LANG': 'en_US.UTF-8', 16:27:29 INFO - 'LANGUAGE': 'en_US:en', 16:27:29 INFO - 'LOGNAME': 'cltbld', 16:27:29 INFO - 'MAIL': '/var/mail/cltbld', 16:27:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:27:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:27:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:29 INFO - 'MOZ_NO_REMOTE': '1', 16:27:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:27:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:27:29 INFO - 'NO_EM_RESTART': '1', 16:27:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:27:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:27:29 INFO - 'PWD': '/builds/slave/test-pgo', 16:27:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:27:29 INFO - 'SHELL': '/bin/bash', 16:27:29 INFO - 'SHLVL': '1', 16:27:29 INFO - 'TERM': 'linux', 16:27:29 INFO - 'TMOUT': '86400', 16:27:29 INFO - 'USER': 'cltbld', 16:27:29 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638', 16:27:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:29 INFO - '_': '/tools/buildbot/bin/python'} 16:27:29 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/1452809011/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost'] with output_timeout 3600 16:27:30 INFO - mozversion INFO | application_buildid: 20160114140331 16:27:30 INFO - mozversion INFO | application_changeset: ee694a1db741e9fa9e373e9918c72838e43df994 16:27:30 INFO - mozversion INFO | application_display_name: Nightly 16:27:30 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:27:30 INFO - mozversion INFO | application_name: Firefox 16:27:30 INFO - mozversion INFO | application_remotingname: firefox 16:27:30 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:27:30 INFO - mozversion INFO | application_vendor: Mozilla 16:27:30 INFO - mozversion INFO | application_version: 44.0 16:27:30 INFO - mozversion INFO | platform_buildid: 20160114140331 16:27:30 INFO - mozversion INFO | platform_changeset: ee694a1db741e9fa9e373e9918c72838e43df994 16:27:30 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:27:30 INFO - mozversion INFO | platform_version: 44.0 16:27:30 INFO - 2016-01-14 16:27:30,073 DEBUG : using testdate: 1452817650 16:27:30 INFO - 2016-01-14 16:27:30,074 DEBUG : actual date: 1452817650 16:27:30 INFO - 2016-01-14 16:27:30,082 INFO : starting webserver on 'localhost:40043' 16:27:30 INFO - 2016-01-14 16:27:30,083 INFO : Starting test suite talos-linux64-ix-018 16:27:30 INFO - 2016-01-14 16:27:30,083 INFO : Starting test dromaeo_css 16:27:30 INFO - 2016-01-14 16:27:30,083 DEBUG : operating with platform_type : linux_ 16:27:30 INFO - 2016-01-14 16:27:30,084 INFO : Initialising browser for dromaeo_css test... 16:27:30 INFO - 2016-01-14 16:27:30,100 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplvG22o/profile http://localhost:40043/getInfo.html 16:27:33 INFO - 2016-01-14 16:27:33,771 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:27:33 INFO - 2016-01-14 16:27:33,772 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:27:33 INFO - 2016-01-14 16:27:33,772 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:27:33 INFO - 2016-01-14 16:27:33,772 DEBUG : BROWSER_OUTPUT: __metrics 16:27:33 INFO - 2016-01-14 16:27:33,969 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] 16:27:35 INFO - 2016-01-14 16:27:35,037 INFO : Browser initialized. 16:27:35 INFO - 2016-01-14 16:27:35,038 INFO : Running cycle 1/1 for dromaeo_css test... 16:27:35 INFO - 2016-01-14 16:27:35,038 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplvG22o/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:27:46 INFO - 2016-01-14 16:27:46,602 DEBUG : BROWSER_OUTPUT: RSS: Main: 128692224 16:27:46 INFO - 2016-01-14 16:27:46,602 DEBUG : BROWSER_OUTPUT: 16:28:37 INFO - 2016-01-14 16:28:37,262 DEBUG : BROWSER_OUTPUT: 1452817717261 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] 16:30:45 INFO - 2016-01-14 16:30:45,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40043/tests/dromaeo/cssquery-ext.html) 16:30:46 INFO - 2016-01-14 16:30:46,244 DEBUG : BROWSER_OUTPUT: RSS: Main: 228352000 16:30:46 INFO - 2016-01-14 16:30:46,245 DEBUG : BROWSER_OUTPUT: 16:33:48 INFO - 2016-01-14 16:33:48,260 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/dromaeo/cssquery-ext.html (next: http://localhost:40043/tests/dromaeo/cssquery-jquery.html) 16:33:48 INFO - 2016-01-14 16:33:48,822 DEBUG : BROWSER_OUTPUT: RSS: Main: 203862016 16:33:48 INFO - 2016-01-14 16:33:48,822 DEBUG : BROWSER_OUTPUT: 16:36:50 INFO - 2016-01-14 16:36:50,467 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40043/tests/dromaeo/cssquery-mootools.html) 16:36:51 INFO - 2016-01-14 16:36:51,029 DEBUG : BROWSER_OUTPUT: RSS: Main: 202059776 16:36:51 INFO - 2016-01-14 16:36:51,029 DEBUG : BROWSER_OUTPUT: 16:39:52 INFO - 2016-01-14 16:39:52,666 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40043/tests/dromaeo/cssquery-prototype.html) 16:39:53 INFO - 2016-01-14 16:39:53,240 DEBUG : BROWSER_OUTPUT: RSS: Main: 189689856 16:39:53 INFO - 2016-01-14 16:39:53,241 DEBUG : BROWSER_OUTPUT: 16:42:55 INFO - 2016-01-14 16:42:55,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40043/tests/dromaeo/cssquery-yui.html) 16:42:56 INFO - 2016-01-14 16:42:56,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 191729664 16:42:56 INFO - 2016-01-14 16:42:56,182 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,166 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/dromaeo/cssquery-yui.html (next: http://localhost:40043/tests/dromaeo/cssquery-dojo.html) 16:45:58 INFO - 2016-01-14 16:45:58,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 231550976 16:45:58 INFO - 2016-01-14 16:45:58,425 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,425 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:45:58 INFO - 2016-01-14 16:45:58,425 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:45:58 INFO - 2016-01-14 16:45:58,425 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:45:58 INFO - 2016-01-14 16:45:58,425 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:45:58 INFO - 2016-01-14 16:45:58,426 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3261.445554255776;3371.393149233137;3402.4726167444055;3407.1822762537;3425.8972230833074;5523.585731070176;5548.084566046603;5554.833354999351;5577.944220557793;5600.887982240352;5499.862503437416;5534.114541673333;5537.6954267515375;5542.0301447246775;5544.833654990357;5030.421501527317;5078.7968481260705;5085.694858308495;5175.741212939357;5180.844574662766;5029.421616514092;5121.462246464123;5143.971205758845;5149.227615857614;5151.278820965068;5089.312942752718;5162.1224391853475;5169.741512924357;5172.09488339541;5174.637775355726;5129.897402051956;5136.922946155811;5150.124478838607;5158.535731784139;5172.689638621695;4911.287863259808;5025.020121076392;5055.545000949932;5078.974605126988;5126.102931986925;5404.243405923174;5427.375851777057;5444.564434845203;5447.373552041527;5480.095784195642;6049.304330002018;6052.182955301022;6053.122297266872;6056.182415373956;6058.545609079292;5913.379095194986;6032.547558933054;6064.545159113084;6107.4808641265545;6117.949202904687;5698.943010569924;5702.857428564277;5706.1440783882335;5706.4864162225595;5717.771289148429;6182.1345011698395;6191.1641928339095;6201.503879689614;6202.81391558254;6210.130581718563;5749.252597162342;5794.072948328248;5830.912536311958;5868.911966320508;5870.324912635102;5994.0409534474275;6019.608725432833;6023.036314189709;6026.638401695912;6028.849278767978;5865.530757539386;5982.49148822355;5986.191864098291;5986.551008674367;5989.431004054608;5883.911741323884;5936.406359364116;5965.075413310857;5968.432998865101;5969.04495280753;4619.930701039487;4646.047560250141;4653.53464653532;4653.906921861544;4671.696339737906;4081.469408976873;4160.105577300895;4167.020750123718;4169.249535083714;4202.915941681149;5646.407127251661;5727.284089488814;5755.309362876398;5764.855878603068;5780.1907732917425;6013.037913933749;6018.969905150446;6024.4879185268865;6025.698715064229;6025.9096113558335;5419.3225846769155;5625.409332020161;5636.520895723827;5701.657900525899;5707.029804933171;5211.869703257449;5212.3223980883;5217.034848553029;5219.060569097599;5271.314729085195;6189.412005859436;6192.783252586228;6193;6198.132261483396;6199.380061993855;5944.375840536682;5990.460858522754;6002.069679199731;6071.2714474263375;6123.050927106306;6223.626582405071;6224.37756224374;6232;6238.719257633326;6240.750369985331;5971.701414929323;5973.163757073926;5975.342712301556;5982.521398288213;6019.909701354571;5923.526117910471;5925.111233315038;6052.425019623129;6059.212302400836;6064.272287325549;5828.038373668297;5830.82507524775;5834.27071616048;5839.649621022752;5846.064629659234;5688.288963879515;5826.970865145647;5827.796027139199;5831.650100993954;5835.416458354131;5668.886622267449;5728.3985181557;5754.280714910637;5761.798337058183;5772.884542309214;5849.151872978479;5898.233229680114;5909.793157239476;5915.171875937286;5927.288725352985;5556.666600004012;5618.578606604603;5794.246747922912;5799;5804.303483581997 16:45:58 INFO - 2016-01-14 16:45:58,426 DEBUG : BROWSER_OUTPUT: |1;ext.html;589.3016775121479;614.7172300741659;632.3960617610181;681.8806708825954;712.7398499547667;35789.46315805262;44118.77940610296;44782.55217447829;44793;44831.327530086994;48743.268850967266;49419.258711119364;49711.75144124274;49809.50190498094;49822.252666210035;8250.628721707508;8359.83280334393;9190.21883139934;9397.81204375912;10669.946650266776;10792.514336854822;10913.290636108628;10929.672109836718;10931.617393391232;10932.125429965583;10870.673879783579;10895.073918716918;10975.50610222542;10977.39624320662;10988.066014388787;10824.567017319338;10862.565497380096;10896.564137434454;10903;10912.454377281105;10737.194710396752;10876.782464350668;10892.074173695246;10894;10908;45795.08409831818;46067.30899036517;46224;46300.536994630296;46303;3797.7531460454984;3906.2187562487334;3948.565657777642;4072.165206132736;4607.815687372501;34071.82964085164;34551.82724086389;34629.65370346289;34647.30705385903;34665.48001779975;3785.2240290740333;3791.696664266852;3946.743461674982;3957.7823219722904;3964.5392884058456;335481;337222;337350;338236.99999999756;338264;59973.400265997654;61211.69394153001;75493;76595.23404765992;77069.6146519275;71099;71291.2870871295;71686.64156679183;71694;71751;18505.167267472996;18864.528386790167;18891.716624250646;18901.81098189028;18904;13217.73564528704;13397.727215914472;13400.396982135828;13458.125221860548;13482.528111516245;22031.66952495714;22196.77803221947;22394.776052239595;22430.775692243194;22438.663420048713;8634.568271586395;8880.555972201364;8922.152395522415;8951;9014.143656352637;1035.414990530348;1104.089126470665;1104.5471356743697;1130.711668524521;1154.3939431798206;31049.68950310468;31625.051248462583;31738.682613174034;32534.186645334055;32768.836155819066;1144.3267112568747;1155.3703231738723;1178.2341478039173;1212.5574165429555;1262.8673989231;13775.862241377657;14876.330565124596;15200.923995379952;15279.312430940634;15983.418239935978;287667;291694;292279;293225;293620;52602;52868;52909.7354513225;52914;53098.20352694712;1392.5195807446617;1396.9161850289015;1470.6690994526145;1477.549347449017;1637.3286952349479;28503;28783.712162878102;29291.70708292975;29336.413271734018;29651.627869769934;46731;47114.293285600055;47120;47172;47220.05559888714;234920;235729;237938;237980;238120;34871.67224613447;35730.28539429249;35879;36166.27667446689;36199.81900090483;47949.520504794506;48770.26844597405;48875;49387.753061235904;49468.50531494639;40289.79855100706;40617.796911015255;40799;41266.38100428556;41416;40773.79613101915;41098.383524246565;41296.1740765189;41545.39639387562;41923.7903810479;40428.393574097;40913.79543102384;41202;41411.1717765649;41555.168896622505;35747.46378804372;36863.262734745695;37408.43887341637;37876.24247514979;37890;27851;27852.16443506698;28058.2985425366;28117.297067573476;28208.85895570509 16:45:58 INFO - 2016-01-14 16:45:58,426 DEBUG : BROWSER_OUTPUT: |2;jquery.html;368.52276302188665;376.25792580352964;376.9736118471708;388.50271652285085;394.7513066767938;15930.283137258823;17955.64088718225;17997.37009204678;19206;19237.711434328477;15535.456259030936;17887.105644717743;18215.27138914445;18768.812311876878;18836.434906952778;5612.15817627355;5747.971260143693;5748.511376532979;5754.136879468092;5798.971005144968;5750.539956803445;5771.047777116771;5779.768809247625;5786.594938354317;5789.768409263625;5645.520130788888;5789;5789.652620842742;5789.913151302734;5792.044312688422;5625.634333768333;5733.197352370671;5761.567882408836;5775.711214439282;5776.884462310752;5265.526102650741;5266.447023062561;5269.9460107978175;5273.499017593323;5278.947210527922;24223.394415139584;24285.150019749224;24314.635280470808;24766.133185338607;24831.751682483304;2286.1084177170915;2340.566995105911;2346.0263990443946;2380.9285721428387;2435.952540407624;10371.636992705218;10767.623133190378;14903.403863845542;15238.542843714706;15413.768793468107;1743.3462451580658;1858.4610462965725;1865.9813401865943;1871.3450292397688;1881.5484283771796;71062.2893771066;71627.64186179088;72288.6385568074;72352.91470627945;73533.26466735317;8010.319122874621;8016.759497215093;8067.354611631055;8128.374325134997;8134.145914678874;9751.586020027056;9753.927068022513;9756.707298781048;9763.755906102404;9765.462899540451;6443.323451037573;6506.121673574102;6581.83545411359;6584.703688334036;6598.076269322299;6720.731170753213;6733.831654208684;6757.358050985148;6775;6826.795196144124;3538.1862171700136;3599.4240921452965;3644.4715516249994;3726.087108658396;3740.0462881964927;1294.1717300927414;1306.4643496166525;1312.1274352555633;1331.9134256273505;1352.7294541091821;463.71944973291346;513.3019094032113;540.1384791257938;544.8855740294582;569.5500554561933;12675.429605667767;12912;13137.211767293993;13268.071235013456;13304.933475332562;539.5575627985014;553.1591980190088;564.1284215886466;564.4101913500402;576.3516044449993;6032.819015429544;6173.938260617426;7245.5652660840515;7329.340359367576;7409.37020353276;641802;645199;657704;660847;662521;88110.55944720235;91372;92267;92462.53768731114;94657.526712366;596.5287422935833;605.1830029460193;610.4079043327964;610.4322979628988;610.9725062372204;11602.477888495207;11826.34955077479;12075.75848483043;12139.271643701406;12207.38963051826;38008.61991380051;38047;38976.80511597424;38988.02529936774;39739.40390894081;53609;53636;54082.18876716774;55165.72417137888;55199;6787.083743694538;7517.962410188133;7561.243875612395;7603.505772124794;7639.732609358646;10814.404542909802;12965.286909219707;13034.413451394521;13252.668683282995;13281;11250.268732532359;11375.829362559403;11387.259828111146;11478.196526243417;11598.594049208237;11316.547338106384;11317.20779545457;11384.772304553697;11516.827247591125;11839.28964262147;11135.164862635173;11254.7749045017;11359.318440893572;11983.161178717755;12008;7653.540787552764;7680.308772210527;7746.961265193863;7761.301482866569;7773.989381380385;12033.217840840318;12057.397130143283;12106.213096148722;12115.091368147248;12194.75610487803 16:45:58 INFO - 2016-01-14 16:45:58,427 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1531.7625768005962;1543.791588135602;1763.902985335806;1831.981680183198;1861.9852180561593;6485.48116150708;6634.5024123190715;6660.067590537328;6661.966690166549;6666.033425153356;7125.643717814101;7289.599072051035;7823.374130069595;8027.317677997377;8046.114927357986;2402.663627092204;2413.239829453725;2435.147698305597;2435.3302841718582;2438.5001074779716;2177.2706143441887;2377.2511658827416;2406.2660888429023;2423.8303318767694;2425.357280320716;2143.978560214401;2354.0466111224982;2409.9036038558525;2410.433548116191;2412.1436889904094;2316.4787922717423;2349.3539276698875;2351.0360752091733;2373.1100837186054;2376.5009348036933;2346.366481050107;2351.6002279612508;2372.5373552157334;2373.8813059347135;2397.184957114572;7945.284924356778;8003.4797738146835;8005.159458256858;8005.159458256916;8013.158618345106;4812.951870481285;4868.269759536062;4870.09903167915;4877.073356062372;4881.633877459205;3212.903612891617;3235.094173631387;3273.165342837585;3337.5494308268558;3347.1464776482085;1163.5927425401042;1172.5251273234353;1186.7567148734488;1196.8623608284984;1201.7536405037033;10533.631322903662;10535.578576856917;10574.682759517227;10608.893911060868;10628.256022078458;4292.291771857608;4304.827806887752;4358.956410435919;4368.475782906072;4381.846635367747;9051.637934482678;9054.547272636342;9071.954640226757;9095.636174553076;9106.681266155652;4841.443234028062;4852.2479015752615;4853.12643724126;4853.150698627726;4858.05267972749;4385.978070109629;4389.714668546534;4394.692371533962;4397.1865204937185;4398.164348773723;3766.1714422826935;3796.126890815128;3836.76979381238;3839.289731399644;3844.4233364995475;1149.2185313986402;1199.6401079676061;1200.6158029430499;1217.7320989382322;1243.4031664800946;1222.529326209398;1242.236024844714;1263.860975292717;1521.6195951012246;1522.9010114342532;7442.590657513781;7563.394928405714;7619.276168763959;7651.043619547557;7665.233476652402;1235.5366737473448;1244.8195011723249;1246.1214843535286;1247.2391840976998;1247.6631309546372;6142.04798256271;6163.84590385244;6228.750849966042;6242.15730876335;6245.375462453719;9331.253499720005;9527.047295270557;9533;9574.090461406155;9577.185939195107;9259.166674999282;9396.812063758687;9423.293253006052;9424.198943089914;9435.433873967446;7304.306090921354;7361.48469607133;7378.631068446556;7379.6716591013055;7385.261473852464;7321.450891183076;7343.082114735658;7398.96300518494;7427.665755040928;7430.591317477591;8437.282830959437;8443.155684431507;8540.14598540166;8615.310775137848;8620.13798620133;8500.44747091437;8587.699430519902;8634.913650863662;8656.004559475741;8658.696945606873;7205.819854503679;7216.494845360984;7251.56490610565;7257.818554536178;7264.23725508814;7749.535027898344;7783.338416234457;7842.568658723825;7867.488613240121;7868.685252589832;5933.643981361132;7393.55638661682;7426.554406735613;7473.43949203801;7590.8102297443;7208.819779505554;7254.310840470147;7258;7338.559686418832;7347.926520734724;7098.219195888345;7331.266873312839;7363.190049094488;7422.072240969879;7431.70273189088;6668.799936001928;6705;6709.295523970108;6714.798556043326;6726.125603671491;6826.941824017285;6835.89746153818;6866.6566671667215;6873.690683919136;6881.174259088942 16:45:58 INFO - 2016-01-14 16:45:58,431 DEBUG : BROWSER_OUTPUT: |4;prototype.html;357.3139572021718;364.5607043512568;365.58323511197233;367.1134210880723;369.5122438381336;5871.178035075082;5996.100584912266;6024.6385216886965;6047.727852246643;6048.606840555361;5802.39074897136;5911.438413350736;5927.911081333784;5952.70236488176;5963.940360596393;4657.347971284023;4658.883527911796;4667.579917807397;4667.789949452266;4672.018876036036;4522.3216517522305;4573.702709323883;4611.838585649503;4654.790534425942;4655.580997710205;4608.032313214212;4625.560571745696;4634.559716826896;4635.605973492257;4642.257238841786;4600.148972440109;4600.746958917275;4604.562566556172;4619.884502887421;4624.884377890563;4063.3904914262803;4076.8776936691947;4079.6124368185274;4080.510338759368;4085.816138273785;5648.181013753024;5740.741666625012;5741.511971482429;5766.365699773021;5772.047612144033;5447.073997420448;5450.56395488364;5451.100568406208;5453;5453.045716999509;5965.791197308074;6358.523110766711;6367.363263673642;6367.968160159216;6370.267419246799;4441.267190913495;4560.224761790503;4608.608268297199;4611.239145541015;4617.422822147231;6493.448056915215;6513.967430162819;6543.93456065438;6544.443722283611;6553.14809074822;4718.245080787057;4726;4729.12511185425;4729.722974796786;4732.148213321635;4723.7401942893475;4747.525247475225;4784.258439941815;4791.209450440708;4832.251001094836;5467.917981230285;5470.863228419242;5473.562115030788;5474.42518535556;5476.616636835463;6431.5497915146425;6432.2602900667025;6437.066625339299;6440.871182576322;6446.484281257488;1731.2382551677474;1760.6654735600196;1761.4451447793904;1762.2422358386018;1762.4007837335112;1148.9023433008288;1149.3276433286453;1163.4299193395175;1174.6887074925153;1223.5228260978226;6079.452849243589;6091.269047714302;6092.025275955826;6096.451319381188;6096.481799047042;6397.968010160012;6416.679166041679;6421.036844473273;6421.422072013448;6425.518086143558;6071.180390647206;6078.149059131725;6080;6081.270247570232;6126.540509461808;3599.1541987632886;3641.0715267606593;3641.7632853864416;3645;3645.4167333227088;6779.35596118368;6803.047573339736;6807.047013418027;6814.8296292592095;6814.897776533356;6652.966735166293;6785.626790526569;6793.72825086991;6795.082663840417;6796.286389929085;6766.086578311865;6792.422644075309;6796.116504854436;6804.183497980274;6807.047013418125;6338.366163383625;6440.710168042543;6472.35276472349;6530.934690653223;6560.803175904731;6488.610683359014;6489.123968264224;6505.089287499659;6511.511636627367;6519.511036672174;6725.428338591275;6763.86472270542;6770.492213084137;6775;6777.559458635173;4511.684182107221;4592.7014744041535;4637.768111594474;4638.489766125656;4654.092451971833;6520.380563846427;6684.231313398827;6690.29751876046;6692.431143352675;6697.330266973264;6506.674666266762;6510.283868774336;6511.34886511345;6512.73949042033;6513.54405191632;6365.64988925595;6440.903386449113;6446.355364463516;6491.415772580491;6498.057781621733;6334.271558770801;6369.20384951881;6376.776812811529;6377.17096777416;6382.712777924911;6451.580647257913;6462.256840463218;6473.255575608677;6474.352564743488;6478.125453063964;6211.223597050369;6297.992321228581;6299.96850015747;6306.779262725782;6313.210848643919 16:45:58 INFO - 2016-01-14 16:45:58,431 DEBUG : BROWSER_OUTPUT: |5;yui.html;847.3221422861709;944.7874228298633;945.106874004066;947.459947829737;961.5480724059689;13512.256825874572;13843.100198487104;13853.099548529339;13871.861281387182;13874.514391996283;13369;14426;14473.78289325661;14494.855051449482;14510.201938893357;769.4613770360742;909.9863502047441;929.1451864284841;932.0306880843914;932.2122806228726;921.2261700171854;923.0308176414771;925.3430064654103;927.7587827164919;929.2565947242213;806.9677212911506;818.5416166946529;973.9513024348789;979.4564016970578;981.224832382417;956.2063487305554;966.3042609321312;968.7626531499792;972.6060945317158;972.6304004478253;713.9785806425815;720.9567425954459;723.7286017743346;725.9927400725978;726.625787719325;13308.467661293536;13462.798058029137;13474.595762127054;13480.191188528719;13495.66260843477;12508.3745812709;13664.931675341659;14317.42730290787;14380.424783008668;14402.567922962224;10602.416867072308;10646.627368042158;10723.46382680863;10790;10809;3868.825902834379;3953.743006704584;3958.386450100239;3967.8016099195215;3967.920641587181;53261.46738532604;53690;54237.72881135569;54794.452055479735;54903.450965490636;8940.68707595244;8982.191602755784;10376.066154046172;10379;10502.579896804195;13996.510122145675;14115.576532704035;14118.71762564743;14133.293335333397;14156.646083847987;7105.32499412555;7131.251218622074;7380.483366164317;7391.778246652501;7407.148177959496;4675.4155730533685;4735.95264047362;4737.573618374363;4758.452777930583;4779.01652398962;3149.889753858604;3151.101935948244;3154.6372167200607;3181.44324743169;3182.3635272945503;486.98052077917197;491.0865789631283;496.22588761532126;498.53138050232667;499.69019208091214;685.917689877208;697.0902971621981;718.1346477494607;769.8922150898878;783.3498196496894;8802.691905783395;9147.359684821999;9274.118958698913;9309.627614895327;9329.253659707207;647.5143642268299;680.7038938061959;730.3718801830422;732.3921145449265;770.2220757035435;4289.699721019561;4307.827686892552;4351.325544540601;4356.607905288539;4457.465104187519;69685;71073;71133.64433177904;71265;71361;61215.38784612186;61253;61383.38616613777;62996.37003629905;64619.67690161519;55181.448185518435;55329;55423.722881386144;55735.72132139313;55857;8758.430702004349;8786.956065219632;9009.324300677345;9115.134062264075;9330.813383732424;40066.79966600148;40367.1926561473;41046.58953410428;41590.37614435725;43716.065554100285;54217.45782542124;54450.727746361015;54875.451245487035;55006.17490737562;56275.43724562866;8538.402311838112;8573.74278771638;8968.68609598661;9134.406263593111;9146.77133071652;11236;11550;11782.351970641697;12068;12492.750144997231;11300.208985370946;11321.433928303388;11788.528458861892;11845.703857403634;11909.702257443632;12538;12563.246205227715;12651.746965060831;12928;13090.01824863158;11709.238899471507;11879;11922.080510163343;12112.333821639895;12226.44980975876;10590.470476475992;10595.205359598214;10751.623693171014;10924.672259832218;11203;9957.502124893581;10224.693259202237;10226.488675566043;10287.125594324567;10296.382217067 16:45:58 INFO - 2016-01-14 16:45:58,431 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:45:58 INFO - 2016-01-14 16:45:58,431 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:45:58 INFO - 2016-01-14 16:45:58,431 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,766 16:45:58 INFO - 2016-01-14 16:45:58,431 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: __startTimestamp1452818758418__endTimestamp 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5577.86 Median:5772.88 stddev:611.55 (10.6%) stddev-sans-first:585.81 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: Values: 3261.4 3371.4 3402.5 3407.2 3425.9 5523.6 5548.1 5554.8 5577.9 5600.9 5499.9 5534.1 5537.7 5542.0 5544.8 5030.4 5078.8 5085.7 5175.7 5180.8 5029.4 5121.5 5144.0 5149.2 5151.3 5089.3 5162.1 5169.7 5172.1 5174.6 5129.9 5136.9 5150.1 5158.5 5172.7 4911.3 5025.0 5055.5 5079.0 5126.1 5404.2 5427.4 5444.6 5447.4 5480.1 6049.3 6052.2 6053.1 6056.2 6058.5 5913.4 6032.5 6064.5 6107.5 6117.9 5698.9 5702.9 5706.1 5706.5 5717.8 6182.1 6191.2 6201.5 6202.8 6210.1 5749.3 5794.1 5830.9 5868.9 5870.3 5994.0 6019.6 6023.0 6026.6 6028.8 5865.5 5982.5 5986.2 5986.6 5989.4 5883.9 5936.4 5965.1 5968.4 5969.0 4619.9 4646.0 4653.5 4653.9 4671.7 4081.5 4160.1 4167.0 4169.2 4202.9 5646.4 5727.3 5755.3 5764.9 5780.2 6013.0 6019.0 6024.5 6025.7 6025.9 5419.3 5625.4 5636.5 5701.7 5707.0 5211.9 5212.3 5217.0 5219.1 5271.3 6189.4 6192.8 6193.0 6198.1 6199.4 5944.4 5990.5 6002.1 6071.3 6123.1 6223.6 6224.4 6232.0 6238.7 6240.8 5971.7 5973.2 5975.3 5982.5 6019.9 5923.5 5925.1 6052.4 6059.2 6064.3 5828.0 5830.8 5834.3 5839.6 5846.1 5688.3 5827.0 5827.8 5831.7 5835.4 5668.9 5728.4 5754.3 5761.8 5772.9 5849.2 5898.2 5909.8 5915.2 5927.3 5556.7 5618.6 5794.2 5799.0 5804.3 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:48839.40 Median:34768.58 stddev:75960.04 (218.5%) stddev-sans-first:76086.74 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: Values: 589.3 614.7 632.4 681.9 712.7 35789.5 44118.8 44782.6 44793.0 44831.3 48743.3 49419.3 49711.8 49809.5 49822.3 8250.6 8359.8 9190.2 9397.8 10669.9 10792.5 10913.3 10929.7 10931.6 10932.1 10870.7 10895.1 10975.5 10977.4 10988.1 10824.6 10862.6 10896.6 10903.0 10912.5 10737.2 10876.8 10892.1 10894.0 10908.0 45795.1 46067.3 46224.0 46300.5 46303.0 3797.8 3906.2 3948.6 4072.2 4607.8 34071.8 34551.8 34629.7 34647.3 34665.5 3785.2 3791.7 3946.7 3957.8 3964.5 335481.0 337222.0 337350.0 338237.0 338264.0 59973.4 61211.7 75493.0 76595.2 77069.6 71099.0 71291.3 71686.6 71694.0 71751.0 18505.2 18864.5 18891.7 18901.8 18904.0 13217.7 13397.7 13400.4 13458.1 13482.5 22031.7 22196.8 22394.8 22430.8 22438.7 8634.6 8880.6 8922.2 8951.0 9014.1 1035.4 1104.1 1104.5 1130.7 1154.4 31049.7 31625.1 31738.7 32534.2 32768.8 1144.3 1155.4 1178.2 1212.6 1262.9 13775.9 14876.3 15200.9 15279.3 15983.4 287667.0 291694.0 292279.0 293225.0 293620.0 52602.0 52868.0 52909.7 52914.0 53098.2 1392.5 1396.9 1470.7 1477.5 1637.3 28503.0 28783.7 29291.7 29336.4 29651.6 46731.0 47114.3 47120.0 47172.0 47220.1 234920.0 235729.0 237938.0 237980.0 238120.0 34871.7 35730.3 35879.0 36166.3 36199.8 47949.5 48770.3 48875.0 49387.8 49468.5 40289.8 40617.8 40799.0 41266.4 41416.0 40773.8 41098.4 41296.2 41545.4 41923.8 40428.4 40913.8 41202.0 41411.2 41555.2 35747.5 36863.3 37408.4 37876.2 37890.0 27851.0 27852.2 28058.3 28117.3 28208.9 16:45:58 INFO - 2016-01-14 16:45:58,432 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,433 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32257.46 Median:5276.22 stddev:107141.64 (2030.7%) stddev-sans-first:107415.44 16:45:58 INFO - 2016-01-14 16:45:58,434 DEBUG : BROWSER_OUTPUT: Values: 368.5 376.3 377.0 388.5 394.8 15930.3 17955.6 17997.4 19206.0 19237.7 15535.5 17887.1 18215.3 18768.8 18836.4 5612.2 5748.0 5748.5 5754.1 5799.0 5750.5 5771.0 5779.8 5786.6 5789.8 5645.5 5789.0 5789.7 5789.9 5792.0 5625.6 5733.2 5761.6 5775.7 5776.9 5265.5 5266.4 5269.9 5273.5 5278.9 24223.4 24285.2 24314.6 24766.1 24831.8 2286.1 2340.6 2346.0 2380.9 2436.0 10371.6 10767.6 14903.4 15238.5 15413.8 1743.3 1858.5 1866.0 1871.3 1881.5 71062.3 71627.6 72288.6 72352.9 73533.3 8010.3 8016.8 8067.4 8128.4 8134.1 9751.6 9753.9 9756.7 9763.8 9765.5 6443.3 6506.1 6581.8 6584.7 6598.1 6720.7 6733.8 6757.4 6775.0 6826.8 3538.2 3599.4 3644.5 3726.1 3740.0 1294.2 1306.5 1312.1 1331.9 1352.7 463.7 513.3 540.1 544.9 569.6 12675.4 12912.0 13137.2 13268.1 13304.9 539.6 553.2 564.1 564.4 576.4 6032.8 6173.9 7245.6 7329.3 7409.4 641802.0 645199.0 657704.0 660847.0 662521.0 88110.6 91372.0 92267.0 92462.5 94657.5 596.5 605.2 610.4 610.4 611.0 11602.5 11826.3 12075.8 12139.3 12207.4 38008.6 38047.0 38976.8 38988.0 39739.4 53609.0 53636.0 54082.2 55165.7 55199.0 6787.1 7518.0 7561.2 7603.5 7639.7 10814.4 12965.3 13034.4 13252.7 13281.0 11250.3 11375.8 11387.3 11478.2 11598.6 11316.5 11317.2 11384.8 11516.8 11839.3 11135.2 11254.8 11359.3 11983.2 12008.0 7653.5 7680.3 7747.0 7761.3 7774.0 12033.2 12057.4 12106.2 12115.1 12194.8 16:45:58 INFO - 2016-01-14 16:45:58,434 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,434 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5583.19 Median:6365.43 stddev:2801.21 (44.0%) stddev-sans-first:2792.52 16:45:58 INFO - 2016-01-14 16:45:58,434 DEBUG : BROWSER_OUTPUT: Values: 1531.8 1543.8 1763.9 1832.0 1862.0 6485.5 6634.5 6660.1 6662.0 6666.0 7125.6 7289.6 7823.4 8027.3 8046.1 2402.7 2413.2 2435.1 2435.3 2438.5 2177.3 2377.3 2406.3 2423.8 2425.4 2144.0 2354.0 2409.9 2410.4 2412.1 2316.5 2349.4 2351.0 2373.1 2376.5 2346.4 2351.6 2372.5 2373.9 2397.2 7945.3 8003.5 8005.2 8005.2 8013.2 4813.0 4868.3 4870.1 4877.1 4881.6 3212.9 3235.1 3273.2 3337.5 3347.1 1163.6 1172.5 1186.8 1196.9 1201.8 10533.6 10535.6 10574.7 10608.9 10628.3 4292.3 4304.8 4359.0 4368.5 4381.8 9051.6 9054.5 9072.0 9095.6 9106.7 4841.4 4852.2 4853.1 4853.2 4858.1 4386.0 4389.7 4394.7 4397.2 4398.2 3766.2 3796.1 3836.8 3839.3 3844.4 1149.2 1199.6 1200.6 1217.7 1243.4 1222.5 1242.2 1263.9 1521.6 1522.9 7442.6 7563.4 7619.3 7651.0 7665.2 1235.5 1244.8 1246.1 1247.2 1247.7 6142.0 6163.8 6228.8 6242.2 6245.4 9331.3 9527.0 9533.0 9574.1 9577.2 9259.2 9396.8 9423.3 9424.2 9435.4 7304.3 7361.5 7378.6 7379.7 7385.3 7321.5 7343.1 7399.0 7427.7 7430.6 8437.3 8443.2 8540.1 8615.3 8620.1 8500.4 8587.7 8634.9 8656.0 8658.7 7205.8 7216.5 7251.6 7257.8 7264.2 7749.5 7783.3 7842.6 7867.5 7868.7 5933.6 7393.6 7426.6 7473.4 7590.8 7208.8 7254.3 7258.0 7338.6 7347.9 7098.2 7331.3 7363.2 7422.1 7431.7 6668.8 6705.0 6709.3 6714.8 6726.1 6826.9 6835.9 6866.7 6873.7 6881.2 16:45:58 INFO - 2016-01-14 16:45:58,434 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,434 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5373.28 Median:6059.89 stddev:1573.18 (26.0%) stddev-sans-first:1531.88 16:45:58 INFO - 2016-01-14 16:45:58,435 DEBUG : BROWSER_OUTPUT: Values: 357.3 364.6 365.6 367.1 369.5 5871.2 5996.1 6024.6 6047.7 6048.6 5802.4 5911.4 5927.9 5952.7 5963.9 4657.3 4658.9 4667.6 4667.8 4672.0 4522.3 4573.7 4611.8 4654.8 4655.6 4608.0 4625.6 4634.6 4635.6 4642.3 4600.1 4600.7 4604.6 4619.9 4624.9 4063.4 4076.9 4079.6 4080.5 4085.8 5648.2 5740.7 5741.5 5766.4 5772.0 5447.1 5450.6 5451.1 5453.0 5453.0 5965.8 6358.5 6367.4 6368.0 6370.3 4441.3 4560.2 4608.6 4611.2 4617.4 6493.4 6514.0 6543.9 6544.4 6553.1 4718.2 4726.0 4729.1 4729.7 4732.1 4723.7 4747.5 4784.3 4791.2 4832.3 5467.9 5470.9 5473.6 5474.4 5476.6 6431.5 6432.3 6437.1 6440.9 6446.5 1731.2 1760.7 1761.4 1762.2 1762.4 1148.9 1149.3 1163.4 1174.7 1223.5 6079.5 6091.3 6092.0 6096.5 6096.5 6398.0 6416.7 6421.0 6421.4 6425.5 6071.2 6078.1 6080.0 6081.3 6126.5 3599.2 3641.1 3641.8 3645.0 3645.4 6779.4 6803.0 6807.0 6814.8 6814.9 6653.0 6785.6 6793.7 6795.1 6796.3 6766.1 6792.4 6796.1 6804.2 6807.0 6338.4 6440.7 6472.4 6530.9 6560.8 6488.6 6489.1 6505.1 6511.5 6519.5 6725.4 6763.9 6770.5 6775.0 6777.6 4511.7 4592.7 4637.8 4638.5 4654.1 6520.4 6684.2 6690.3 6692.4 6697.3 6506.7 6510.3 6511.3 6512.7 6513.5 6365.6 6440.9 6446.4 6491.4 6498.1 6334.3 6369.2 6376.8 6377.2 6382.7 6451.6 6462.3 6473.3 6474.4 6478.1 6211.2 6298.0 6300.0 6306.8 6313.2 16:45:58 INFO - 2016-01-14 16:45:58,435 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,435 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15450.27 Median:499.11 stddev:19359.21 (3878.7%) stddev-sans-first:19382.46 16:45:58 INFO - 2016-01-14 16:45:58,436 DEBUG : BROWSER_OUTPUT: Values: 847.3 944.8 945.1 947.5 961.5 13512.3 13843.1 13853.1 13871.9 13874.5 13369.0 14426.0 14473.8 14494.9 14510.2 769.5 910.0 929.1 932.0 932.2 921.2 923.0 925.3 927.8 929.3 807.0 818.5 974.0 979.5 981.2 956.2 966.3 968.8 972.6 972.6 714.0 721.0 723.7 726.0 726.6 13308.5 13462.8 13474.6 13480.2 13495.7 12508.4 13664.9 14317.4 14380.4 14402.6 10602.4 10646.6 10723.5 10790.0 10809.0 3868.8 3953.7 3958.4 3967.8 3967.9 53261.5 53690.0 54237.7 54794.5 54903.5 8940.7 8982.2 10376.1 10379.0 10502.6 13996.5 14115.6 14118.7 14133.3 14156.6 7105.3 7131.3 7380.5 7391.8 7407.1 4675.4 4736.0 4737.6 4758.5 4779.0 3149.9 3151.1 3154.6 3181.4 3182.4 487.0 491.1 496.2 498.5 499.7 685.9 697.1 718.1 769.9 783.3 8802.7 9147.4 9274.1 9309.6 9329.3 647.5 680.7 730.4 732.4 770.2 4289.7 4307.8 4351.3 4356.6 4457.5 69685.0 71073.0 71133.6 71265.0 71361.0 61215.4 61253.0 61383.4 62996.4 64619.7 55181.4 55329.0 55423.7 55735.7 55857.0 8758.4 8787.0 9009.3 9115.1 9330.8 40066.8 40367.2 41046.6 41590.4 43716.1 54217.5 54450.7 54875.5 55006.2 56275.4 8538.4 8573.7 8968.7 9134.4 9146.8 11236.0 11550.0 11782.4 12068.0 12492.8 11300.2 11321.4 11788.5 11845.7 11909.7 12538.0 12563.2 12651.7 12928.0 13090.0 11709.2 11879.0 11922.1 12112.3 12226.4 10590.5 10595.2 10751.6 10924.7 11203.0 9957.5 10224.7 10226.5 10287.1 10296.4 16:45:58 INFO - 2016-01-14 16:45:58,436 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:45:58 INFO - 2016-01-14 16:45:58,436 DEBUG : BROWSER_OUTPUT: 16:45:58 INFO - 2016-01-14 16:45:58,965 INFO : Browser exited with error code: 0 16:45:58 INFO - 2016-01-14 16:45:58,986 INFO : Completed test dromaeo_css (00:18:28) 16:45:58 INFO - 2016-01-14 16:45:58,986 INFO : Starting test kraken 16:45:58 INFO - 2016-01-14 16:45:58,986 DEBUG : operating with platform_type : linux_ 16:45:58 INFO - 2016-01-14 16:45:58,986 INFO : Initialising browser for kraken test... 16:45:58 INFO - 2016-01-14 16:45:58,992 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXqyvXu/profile http://localhost:40043/getInfo.html 16:46:02 INFO - 2016-01-14 16:46:02,340 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:46:02 INFO - 2016-01-14 16:46:02,340 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:46:02 INFO - 2016-01-14 16:46:02,340 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:46:02 INFO - 2016-01-14 16:46:02,341 DEBUG : BROWSER_OUTPUT: __metrics 16:46:02 INFO - 2016-01-14 16:46:02,526 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] 16:46:03 INFO - 2016-01-14 16:46:03,617 INFO : Browser initialized. 16:46:03 INFO - 2016-01-14 16:46:03,618 INFO : Running cycle 1/1 for kraken test... 16:46:03 INFO - 2016-01-14 16:46:03,618 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXqyvXu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:46:15 INFO - 2016-01-14 16:46:15,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 128712704 16:46:15 INFO - 2016-01-14 16:46:15,221 DEBUG : BROWSER_OUTPUT: 16:46:17 INFO - 2016-01-14 16:46:17,647 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40043/tests/kraken/driver.html?testName=audio-beat-detection) 16:46:18 INFO - 2016-01-14 16:46:18,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 161587200 16:46:18 INFO - 2016-01-14 16:46:18,221 DEBUG : BROWSER_OUTPUT: 16:46:20 INFO - 2016-01-14 16:46:20,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40043/tests/kraken/driver.html?testName=audio-dft) 16:46:21 INFO - 2016-01-14 16:46:21,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 158867456 16:46:21 INFO - 2016-01-14 16:46:21,365 DEBUG : BROWSER_OUTPUT: 16:46:24 INFO - 2016-01-14 16:46:24,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40043/tests/kraken/driver.html?testName=audio-fft) 16:46:24 INFO - 2016-01-14 16:46:24,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 157683712 16:46:24 INFO - 2016-01-14 16:46:24,703 DEBUG : BROWSER_OUTPUT: 16:46:26 INFO - 2016-01-14 16:46:26,639 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40043/tests/kraken/driver.html?testName=audio-oscillator) 16:46:27 INFO - 2016-01-14 16:46:27,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 157995008 16:46:27 INFO - 2016-01-14 16:46:27,205 DEBUG : BROWSER_OUTPUT: 16:46:29 INFO - 2016-01-14 16:46:29,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40043/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:46:29 INFO - 2016-01-14 16:46:29,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 156704768 16:46:29 INFO - 2016-01-14 16:46:29,912 DEBUG : BROWSER_OUTPUT: 16:46:32 INFO - 2016-01-14 16:46:32,833 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40043/tests/kraken/driver.html?testName=imaging-darkroom) 16:46:33 INFO - 2016-01-14 16:46:33,393 DEBUG : BROWSER_OUTPUT: RSS: Main: 154722304 16:46:33 INFO - 2016-01-14 16:46:33,393 DEBUG : BROWSER_OUTPUT: 16:46:36 INFO - 2016-01-14 16:46:36,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40043/tests/kraken/driver.html?testName=imaging-desaturate) 16:46:37 INFO - 2016-01-14 16:46:37,283 DEBUG : BROWSER_OUTPUT: RSS: Main: 156286976 16:46:37 INFO - 2016-01-14 16:46:37,283 DEBUG : BROWSER_OUTPUT: 16:46:39 INFO - 2016-01-14 16:46:39,980 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40043/tests/kraken/driver.html?testName=json-parse-financial) 16:46:40 INFO - 2016-01-14 16:46:40,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 157622272 16:46:40 INFO - 2016-01-14 16:46:40,546 DEBUG : BROWSER_OUTPUT: 16:46:42 INFO - 2016-01-14 16:46:42,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40043/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:46:42 INFO - 2016-01-14 16:46:42,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 161869824 16:46:42 INFO - 2016-01-14 16:46:42,608 DEBUG : BROWSER_OUTPUT: 16:46:44 INFO - 2016-01-14 16:46:44,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:46:44 INFO - 2016-01-14 16:46:44,951 DEBUG : BROWSER_OUTPUT: RSS: Main: 160673792 16:46:44 INFO - 2016-01-14 16:46:44,951 DEBUG : BROWSER_OUTPUT: 16:46:46 INFO - 2016-01-14 16:46:46,882 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:46:47 INFO - 2016-01-14 16:46:47,448 DEBUG : BROWSER_OUTPUT: RSS: Main: 166227968 16:46:47 INFO - 2016-01-14 16:46:47,448 DEBUG : BROWSER_OUTPUT: 16:46:49 INFO - 2016-01-14 16:46:49,762 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:46:50 INFO - 2016-01-14 16:46:50,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 168910848 16:46:50 INFO - 2016-01-14 16:46:50,335 DEBUG : BROWSER_OUTPUT: 16:46:52 INFO - 2016-01-14 16:46:52,769 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:46:53 INFO - 2016-01-14 16:46:53,336 DEBUG : BROWSER_OUTPUT: RSS: Main: 168050688 16:46:53 INFO - 2016-01-14 16:46:53,337 DEBUG : BROWSER_OUTPUT: 16:46:54 INFO - 2016-01-14 16:46:54,935 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40043/tests/kraken/driver.html?testName=ai-astar) 16:46:55 INFO - 2016-01-14 16:46:55,186 DEBUG : BROWSER_OUTPUT: RSS: Main: 228712448 16:46:55 INFO - 2016-01-14 16:46:55,186 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,187 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:46:55 INFO - 2016-01-14 16:46:55,187 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:46:55 INFO - 2016-01-14 16:46:55,188 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:46:55 INFO - 2016-01-14 16:46:55,188 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:46:55 INFO - 2016-01-14 16:46:55,188 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;105;112;110;112;98;109;100;109 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;147;121;151;159;124;151;153;131;170;123 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;160;159;159;161;158;160;160;159 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;70;147;71;71;71;71;151;71 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;131;89;90;91;131;89;90;136 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;109;109;109;110;109;109;110 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;145;145;145;145;146;147;145 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;96;95;95;96;96;96;95 16:46:55 INFO - 2016-01-14 16:46:55,189 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;57;56;56;56;55;55;57;53 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;64;63;64;63;63;63;63;63 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;76;79;78;78;79;77;77;77;78 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;123;111;120;106;116;109;111;115;108;108 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;138;138;139;137;135;138;135;135;137;137 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;61;62;63;64;61;68;61;60;61;61 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,864 16:46:55 INFO - 2016-01-14 16:46:55,190 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:46:55 INFO - 2016-01-14 16:46:55,191 DEBUG : BROWSER_OUTPUT: __startTimestamp1452818815186__endTimestamp 16:46:55 INFO - 2016-01-14 16:46:55,191 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:110.00 stddev:4.83 (4.4%) stddev-sans-first:5.12 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 105.0 112.0 110.0 112.0 98.0 109.0 100.0 109.0 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,192 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.00 Median:151.00 stddev:17.06 (11.3%) stddev-sans-first:18.03 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: Values: 147.0 121.0 151.0 159.0 124.0 151.0 153.0 131.0 170.0 123.0 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.50 Median:160.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 160.0 159.0 159.0 161.0 158.0 160.0 160.0 159.0 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:86.50 Median:71.00 stddev:32.96 (46.4%) stddev-sans-first:34.47 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 70.0 147.0 71.0 71.0 71.0 71.0 151.0 71.0 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,193 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.70 Median:90.00 stddev:20.73 (23.0%) stddev-sans-first:21.47 16:46:55 INFO - 2016-01-14 16:46:55,194 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 131.0 89.0 90.0 91.0 131.0 89.0 90.0 136.0 16:46:55 INFO - 2016-01-14 16:46:55,194 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,194 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 16:46:55 INFO - 2016-01-14 16:46:55,194 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 109.0 109.0 109.0 110.0 109.0 109.0 110.0 16:46:55 INFO - 2016-01-14 16:46:55,195 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,195 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 16:46:55 INFO - 2016-01-14 16:46:55,195 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 145.0 145.0 145.0 145.0 146.0 147.0 145.0 16:46:55 INFO - 2016-01-14 16:46:55,195 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,196 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:46:55 INFO - 2016-01-14 16:46:55,196 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 95.0 16:46:55 INFO - 2016-01-14 16:46:55,196 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,196 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.17 (2.1%) stddev-sans-first:1.22 16:46:55 INFO - 2016-01-14 16:46:55,196 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 57.0 56.0 56.0 56.0 55.0 55.0 57.0 53.0 16:46:55 INFO - 2016-01-14 16:46:55,197 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,197 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 16:46:55 INFO - 2016-01-14 16:46:55,197 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 64.0 63.0 64.0 63.0 63.0 63.0 63.0 63.0 16:46:55 INFO - 2016-01-14 16:46:55,197 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,198 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.60 Median:78.00 stddev:0.97 (1.2%) stddev-sans-first:1.00 16:46:55 INFO - 2016-01-14 16:46:55,198 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 79.0 78.0 78.0 79.0 77.0 77.0 77.0 78.0 16:46:55 INFO - 2016-01-14 16:46:55,198 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,198 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:112.70 Median:113.00 stddev:5.62 (5.0%) stddev-sans-first:4.56 16:46:55 INFO - 2016-01-14 16:46:55,198 DEBUG : BROWSER_OUTPUT: Values: 123.0 111.0 120.0 106.0 116.0 109.0 111.0 115.0 108.0 108.0 16:46:55 INFO - 2016-01-14 16:46:55,199 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,199 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.90 Median:137.50 stddev:1.45 (1.1%) stddev-sans-first:1.48 16:46:55 INFO - 2016-01-14 16:46:55,199 DEBUG : BROWSER_OUTPUT: Values: 138.0 138.0 139.0 137.0 135.0 138.0 135.0 135.0 137.0 137.0 16:46:55 INFO - 2016-01-14 16:46:55,199 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,200 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:61.50 stddev:2.35 (3.8%) stddev-sans-first:2.45 16:46:55 INFO - 2016-01-14 16:46:55,200 DEBUG : BROWSER_OUTPUT: Values: 61.0 62.0 63.0 64.0 61.0 68.0 61.0 60.0 61.0 61.0 16:46:55 INFO - 2016-01-14 16:46:55,200 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:46:55 INFO - 2016-01-14 16:46:55,200 DEBUG : BROWSER_OUTPUT: 16:46:55 INFO - 2016-01-14 16:46:55,841 INFO : Browser exited with error code: 0 16:46:55 INFO - 2016-01-14 16:46:55,890 INFO : Completed test kraken (00:00:56) 16:46:55 INFO - 2016-01-14 16:46:55,890 INFO : Starting test v8_7 16:46:55 INFO - 2016-01-14 16:46:55,890 DEBUG : operating with platform_type : linux_ 16:46:55 INFO - 2016-01-14 16:46:55,890 INFO : Initialising browser for v8_7 test... 16:46:55 INFO - 2016-01-14 16:46:55,897 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEcxGac/profile http://localhost:40043/getInfo.html 16:46:59 INFO - 2016-01-14 16:46:59,177 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:46:59 INFO - 2016-01-14 16:46:59,177 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:46:59 INFO - 2016-01-14 16:46:59,177 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:46:59 INFO - 2016-01-14 16:46:59,177 DEBUG : BROWSER_OUTPUT: __metrics 16:46:59 INFO - 2016-01-14 16:46:59,334 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] 16:47:00 INFO - 2016-01-14 16:47:00,413 INFO : Browser initialized. 16:47:00 INFO - 2016-01-14 16:47:00,414 INFO : Running cycle 1/1 for v8_7 test... 16:47:00 INFO - 2016-01-14 16:47:00,414 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEcxGac/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:47:12 INFO - 2016-01-14 16:47:12,032 DEBUG : BROWSER_OUTPUT: RSS: Main: 128974848 16:47:12 INFO - 2016-01-14 16:47:12,032 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,418 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:40043/tests/v8_7/run.html (next: http://localhost:40043/tests/v8_7/run.html) 16:47:33 INFO - 2016-01-14 16:47:33,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 428998656 16:47:33 INFO - 2016-01-14 16:47:33,669 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,669 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:47:33 INFO - 2016-01-14 16:47:33,669 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:47:33 INFO - 2016-01-14 16:47:33,669 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:47:33 INFO - 2016-01-14 16:47:33,669 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:47:33 INFO - 2016-01-14 16:47:33,669 DEBUG : BROWSER_OUTPUT: |0;Richards;164.0150893882237 16:47:33 INFO - 2016-01-14 16:47:33,670 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.7067258449983 16:47:33 INFO - 2016-01-14 16:47:33,670 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.42624789680315 16:47:33 INFO - 2016-01-14 16:47:33,670 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4990.049751243781 16:47:33 INFO - 2016-01-14 16:47:33,670 DEBUG : BROWSER_OUTPUT: |4;RayTrace;952.3809523809524 16:47:33 INFO - 2016-01-14 16:47:33,670 DEBUG : BROWSER_OUTPUT: |5;Earley;556.4830272676684 16:47:33 INFO - 2016-01-14 16:47:33,671 DEBUG : BROWSER_OUTPUT: |6;Boyer;10437.5 16:47:33 INFO - 2016-01-14 16:47:33,671 DEBUG : BROWSER_OUTPUT: |7;RegExp;23465.116279069767 16:47:33 INFO - 2016-01-14 16:47:33,671 DEBUG : BROWSER_OUTPUT: |8;Splay;536.1930294906166 16:47:33 INFO - 2016-01-14 16:47:33,671 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4812.5 16:47:33 INFO - 2016-01-14 16:47:33,671 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:47:33 INFO - 2016-01-14 16:47:33,671 DEBUG : BROWSER_OUTPUT: __startTimestamp1452818853667__endTimestamp 16:47:33 INFO - 2016-01-14 16:47:33,672 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,672 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:47:33 INFO - 2016-01-14 16:47:33,672 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:47:33 INFO - 2016-01-14 16:47:33,672 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,672 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.02 Median:164.02 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: Values: 164.0 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.71 Median:170.71 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: Values: 170.7 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.43 Median:280.43 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: Values: 280.4 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4990.05 Median:4990.05 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,673 DEBUG : BROWSER_OUTPUT: Values: 4990.0 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:952.38 Median:952.38 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: Values: 952.4 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:556.48 Median:556.48 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: Values: 556.5 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10437.50 Median:10437.50 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: Values: 10437.5 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23465.12 Median:23465.12 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,674 DEBUG : BROWSER_OUTPUT: Values: 23465.1 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:536.19 Median:536.19 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: Values: 536.2 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4812.50 Median:4812.50 stddev:0.00 (0.0%) 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: Values: 4812.5 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:47:33 INFO - 2016-01-14 16:47:33,675 DEBUG : BROWSER_OUTPUT: 16:47:34 INFO - 2016-01-14 16:47:34,365 INFO : Browser exited with error code: 0 16:47:34 INFO - 2016-01-14 16:47:34,382 INFO : Completed test v8_7 (00:00:38) 16:47:34 INFO - 2016-01-14 16:47:34,701 INFO : Completed test suite (00:20:04) 16:47:34 INFO - 2016-01-14 16:47:34,702 DEBUG : Working with test: dromaeo_css 16:47:34 INFO - 2016-01-14 16:47:34,702 DEBUG : Generating results file: dromaeo_css 16:47:34 INFO - 2016-01-14 16:47:34,703 DEBUG : Working with test: kraken 16:47:34 INFO - 2016-01-14 16:47:34,703 DEBUG : Generating results file: kraken 16:47:34 INFO - 2016-01-14 16:47:34,703 INFO : javascript benchmark 16:47:34 INFO - 2016-01-14 16:47:34,703 DEBUG : Working with test: v8_7 16:47:34 INFO - 2016-01-14 16:47:34,703 DEBUG : Generating results file: v8_7 16:47:34 INFO - 2016-01-14 16:47:34,704 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:47:34 INFO - 2016-01-14 16:47:34,752 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:47:34 INFO - 2016-01-14 16:47:34,752 DEBUG : process_Request line: dromaeo_css 7213.33 graph.html#tests=[[72,53,35]] 16:47:34 INFO - 2016-01-14 16:47:34,752 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:47:34 INFO - 2016-01-14 16:47:34,780 DEBUG : process_Request line: kraken 1457.80 graph.html#tests=[[232,53,35]] 16:47:34 INFO - 2016-01-14 16:47:34,780 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:47:34 INFO - 2016-01-14 16:47:34,808 DEBUG : process_Request line: v8_7 23309.38 graph.html#tests=[[230,53,35]] 16:47:34 INFO - 2016-01-14 16:47:34,809 INFO : javascript benchmark 16:47:34 INFO - 2016-01-14 16:47:34,813 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3261.445554255776, 3371.393149233137, 3402.4726167444055, 3407.1822762537, 3425.8972230833074, 5523.585731070176, 5548.084566046603, 5554.833354999351, 5577.944220557793, 5600.887982240352, 5499.862503437416, 5534.114541673333, 5537.6954267515375, 5542.0301447246775, 5544.833654990357, 5030.421501527317, 5078.7968481260705, 5085.694858308495, 5175.741212939357, 5180.844574662766, 5029.421616514092, 5121.462246464123, 5143.971205758845, 5149.227615857614, 5151.278820965068, 5089.312942752718, 5162.1224391853475, 5169.741512924357, 5172.09488339541, 5174.637775355726, 5129.897402051956, 5136.922946155811, 5150.124478838607, 5158.535731784139, 5172.689638621695, 4911.287863259808, 5025.020121076392, 5055.545000949932, 5078.974605126988, 5126.102931986925, 5404.243405923174, 5427.375851777057, 5444.564434845203, 5447.373552041527, 5480.095784195642, 6049.304330002018, 6052.182955301022, 6053.122297266872, 6056.182415373956, 6058.545609079292, 5913.379095194986, 6032.547558933054, 6064.545159113084, 6107.4808641265545, 6117.949202904687, 5698.943010569924, 5702.857428564277, 5706.1440783882335, 5706.4864162225595, 5717.771289148429, 6182.1345011698395, 6191.1641928339095, 6201.503879689614, 6202.81391558254, 6210.130581718563, 5749.252597162342, 5794.072948328248, 5830.912536311958, 5868.911966320508, 5870.324912635102, 5994.0409534474275, 6019.608725432833, 6023.036314189709, 6026.638401695912, 6028.849278767978, 5865.530757539386, 5982.49148822355, 5986.191864098291, 5986.551008674367, 5989.431004054608, 5883.911741323884, 5936.406359364116, 5965.075413310857, 5968.432998865101, 5969.04495280753, 4619.930701039487, 4646.047560250141, 4653.53464653532, 4653.906921861544, 4671.696339737906, 4081.469408976873, 4160.105577300895, 4167.020750123718, 4169.249535083714, 4202.915941681149, 5646.407127251661, 5727.284089488814, 5755.309362876398, 5764.855878603068, 5780.1907732917425, 6013.037913933749, 6018.969905150446, 6024.4879185268865, 6025.698715064229, 6025.9096113558335, 5419.3225846769155, 5625.409332020161, 5636.520895723827, 5701.657900525899, 5707.029804933171, 5211.869703257449, 5212.3223980883, 5217.034848553029, 5219.060569097599, 5271.314729085195, 6189.412005859436, 6192.783252586228, 6193.0, 6198.132261483396, 6199.380061993855, 5944.375840536682, 5990.460858522754, 6002.069679199731, 6071.2714474263375, 6123.050927106306, 6223.626582405071, 6224.37756224374, 6232.0, 6238.719257633326, 6240.750369985331, 5971.701414929323, 5973.163757073926, 5975.342712301556, 5982.521398288213, 6019.909701354571, 5923.526117910471, 5925.111233315038, 6052.425019623129, 6059.212302400836, 6064.272287325549, 5828.038373668297, 5830.82507524775, 5834.27071616048, 5839.649621022752, 5846.064629659234, 5688.288963879515, 5826.970865145647, 5827.796027139199, 5831.650100993954, 5835.416458354131, 5668.886622267449, 5728.3985181557, 5754.280714910637, 5761.798337058183, 5772.884542309214, 5849.151872978479, 5898.233229680114, 5909.793157239476, 5915.171875937286, 5927.288725352985, 5556.666600004012, 5618.578606604603, 5794.246747922912, 5799.0, 5804.303483581997], "unit": "ms", "name": "dojo.html", "value": 5538.708646445157}, {"lowerIsBetter": false, "replicates": [589.3016775121479, 614.7172300741659, 632.3960617610181, 681.8806708825954, 712.7398499547667, 35789.46315805262, 44118.77940610296, 44782.55217447829, 44793.0, 44831.327530086994, 48743.268850967266, 49419.258711119364, 49711.75144124274, 49809.50190498094, 49822.252666210035, 8250.628721707508, 8359.83280334393, 9190.21883139934, 9397.81204375912, 10669.946650266776, 10792.514336854822, 10913.290636108628, 10929.672109836718, 10931.617393391232, 10932.125429965583, 10870.673879783579, 10895.073918716918, 10975.50610222542, 10977.39624320662, 10988.066014388787, 10824.567017319338, 10862.565497380096, 10896.564137434454, 10903.0, 10912.454377281105, 10737.194710396752, 10876.782464350668, 10892.074173695246, 10894.0, 10908.0, 45795.08409831818, 46067.30899036517, 46224.0, 46300.536994630296, 46303.0, 3797.7531460454984, 3906.2187562487334, 3948.565657777642, 4072.165206132736, 4607.815687372501, 34071.82964085164, 34551.82724086389, 34629.65370346289, 34647.30705385903, 34665.48001779975, 3785.2240290740333, 3791.696664266852, 3946.743461674982, 3957.7823219722904, 3964.5392884058456, 335481.0, 337222.0, 337350.0, 338236.99999999756, 338264.0, 59973.400265997654, 61211.69394153001, 75493.0, 76595.23404765992, 77069.6146519275, 71099.0, 71291.2870871295, 71686.64156679183, 71694.0, 71751.0, 18505.167267472996, 18864.528386790167, 18891.716624250646, 18901.81098189028, 18904.0, 13217.73564528704, 13397.727215914472, 13400.396982135828, 13458.125221860548, 13482.528111516245, 22031.66952495714, 22196.77803221947, 22394.776052239595, 22430.775692243194, 22438.663420048713, 8634.568271586395, 8880.555972201364, 8922.152395522415, 8951.0, 9014.143656352637, 1035.414990530348, 1104.089126470665, 1104.5471356743697, 1130.711668524521, 1154.3939431798206, 31049.68950310468, 31625.051248462583, 31738.682613174034, 32534.186645334055, 32768.836155819066, 1144.3267112568747, 1155.3703231738723, 1178.2341478039173, 1212.5574165429555, 1262.8673989231, 13775.862241377657, 14876.330565124596, 15200.923995379952, 15279.312430940634, 15983.418239935978, 287667.0, 291694.0, 292279.0, 293225.0, 293620.0, 52602.0, 52868.0, 52909.7354513225, 52914.0, 53098.20352694712, 1392.5195807446617, 1396.9161850289015, 1470.6690994526145, 1477.549347449017, 1637.3286952349479, 28503.0, 28783.712162878102, 29291.70708292975, 29336.413271734018, 29651.627869769934, 46731.0, 47114.293285600055, 47120.0, 47172.0, 47220.05559888714, 234920.0, 235729.0, 237938.0, 237980.0, 238120.0, 34871.67224613447, 35730.28539429249, 35879.0, 36166.27667446689, 36199.81900090483, 47949.520504794506, 48770.26844597405, 48875.0, 49387.753061235904, 49468.50531494639, 40289.79855100706, 40617.796911015255, 40799.0, 41266.38100428556, 41416.0, 40773.79613101915, 41098.383524246565, 41296.1740765189, 41545.39639387562, 41923.7903810479, 40428.393574097, 40913.79543102384, 41202.0, 41411.1717765649, 41555.168896622505, 35747.46378804372, 36863.262734745695, 37408.43887341637, 37876.24247514979, 37890.0, 27851.0, 27852.16443506698, 28058.2985425366, 28117.297067573476, 28208.85895570509], "unit": "ms", "name": "ext.html", "value": 20664.72524996753}, {"lowerIsBetter": false, "replicates": [368.52276302188665, 376.25792580352964, 376.9736118471708, 388.50271652285085, 394.7513066767938, 15930.283137258823, 17955.64088718225, 17997.37009204678, 19206.0, 19237.711434328477, 15535.456259030936, 17887.105644717743, 18215.27138914445, 18768.812311876878, 18836.434906952778, 5612.15817627355, 5747.971260143693, 5748.511376532979, 5754.136879468092, 5798.971005144968, 5750.539956803445, 5771.047777116771, 5779.768809247625, 5786.594938354317, 5789.768409263625, 5645.520130788888, 5789.0, 5789.652620842742, 5789.913151302734, 5792.044312688422, 5625.634333768333, 5733.197352370671, 5761.567882408836, 5775.711214439282, 5776.884462310752, 5265.526102650741, 5266.447023062561, 5269.9460107978175, 5273.499017593323, 5278.947210527922, 24223.394415139584, 24285.150019749224, 24314.635280470808, 24766.133185338607, 24831.751682483304, 2286.1084177170915, 2340.566995105911, 2346.0263990443946, 2380.9285721428387, 2435.952540407624, 10371.636992705218, 10767.623133190378, 14903.403863845542, 15238.542843714706, 15413.768793468107, 1743.3462451580658, 1858.4610462965725, 1865.9813401865943, 1871.3450292397688, 1881.5484283771796, 71062.2893771066, 71627.64186179088, 72288.6385568074, 72352.91470627945, 73533.26466735317, 8010.319122874621, 8016.759497215093, 8067.354611631055, 8128.374325134997, 8134.145914678874, 9751.586020027056, 9753.927068022513, 9756.707298781048, 9763.755906102404, 9765.462899540451, 6443.323451037573, 6506.121673574102, 6581.83545411359, 6584.703688334036, 6598.076269322299, 6720.731170753213, 6733.831654208684, 6757.358050985148, 6775.0, 6826.795196144124, 3538.1862171700136, 3599.4240921452965, 3644.4715516249994, 3726.087108658396, 3740.0462881964927, 1294.1717300927414, 1306.4643496166525, 1312.1274352555633, 1331.9134256273505, 1352.7294541091821, 463.71944973291346, 513.3019094032113, 540.1384791257938, 544.8855740294582, 569.5500554561933, 12675.429605667767, 12912.0, 13137.211767293993, 13268.071235013456, 13304.933475332562, 539.5575627985014, 553.1591980190088, 564.1284215886466, 564.4101913500402, 576.3516044449993, 6032.819015429544, 6173.938260617426, 7245.5652660840515, 7329.340359367576, 7409.37020353276, 641802.0, 645199.0, 657704.0, 660847.0, 662521.0, 88110.55944720235, 91372.0, 92267.0, 92462.53768731114, 94657.526712366, 596.5287422935833, 605.1830029460193, 610.4079043327964, 610.4322979628988, 610.9725062372204, 11602.477888495207, 11826.34955077479, 12075.75848483043, 12139.271643701406, 12207.38963051826, 38008.61991380051, 38047.0, 38976.80511597424, 38988.02529936774, 39739.40390894081, 53609.0, 53636.0, 54082.18876716774, 55165.72417137888, 55199.0, 6787.083743694538, 7517.962410188133, 7561.243875612395, 7603.505772124794, 7639.732609358646, 10814.404542909802, 12965.286909219707, 13034.413451394521, 13252.668683282995, 13281.0, 11250.268732532359, 11375.829362559403, 11387.259828111146, 11478.196526243417, 11598.594049208237, 11316.547338106384, 11317.20779545457, 11384.772304553697, 11516.827247591125, 11839.28964262147, 11135.164862635173, 11254.7749045017, 11359.318440893572, 11983.161178717755, 12008.0, 7653.540787552764, 7680.308772210527, 7746.961265193863, 7761.301482866569, 7773.989381380385, 12033.217840840318, 12057.397130143283, 12106.213096148722, 12115.091368147248, 12194.75610487803], "unit": "ms", "name": "jquery.html", "value": 8043.252918955733}, {"lowerIsBetter": false, "replicates": [1531.7625768005962, 1543.791588135602, 1763.902985335806, 1831.981680183198, 1861.9852180561593, 6485.48116150708, 6634.5024123190715, 6660.067590537328, 6661.966690166549, 6666.033425153356, 7125.643717814101, 7289.599072051035, 7823.374130069595, 8027.317677997377, 8046.114927357986, 2402.663627092204, 2413.239829453725, 2435.147698305597, 2435.3302841718582, 2438.5001074779716, 2177.2706143441887, 2377.2511658827416, 2406.2660888429023, 2423.8303318767694, 2425.357280320716, 2143.978560214401, 2354.0466111224982, 2409.9036038558525, 2410.433548116191, 2412.1436889904094, 2316.4787922717423, 2349.3539276698875, 2351.0360752091733, 2373.1100837186054, 2376.5009348036933, 2346.366481050107, 2351.6002279612508, 2372.5373552157334, 2373.8813059347135, 2397.184957114572, 7945.284924356778, 8003.4797738146835, 8005.159458256858, 8005.159458256916, 8013.158618345106, 4812.951870481285, 4868.269759536062, 4870.09903167915, 4877.073356062372, 4881.633877459205, 3212.903612891617, 3235.094173631387, 3273.165342837585, 3337.5494308268558, 3347.1464776482085, 1163.5927425401042, 1172.5251273234353, 1186.7567148734488, 1196.8623608284984, 1201.7536405037033, 10533.631322903662, 10535.578576856917, 10574.682759517227, 10608.893911060868, 10628.256022078458, 4292.291771857608, 4304.827806887752, 4358.956410435919, 4368.475782906072, 4381.846635367747, 9051.637934482678, 9054.547272636342, 9071.954640226757, 9095.636174553076, 9106.681266155652, 4841.443234028062, 4852.2479015752615, 4853.12643724126, 4853.150698627726, 4858.05267972749, 4385.978070109629, 4389.714668546534, 4394.692371533962, 4397.1865204937185, 4398.164348773723, 3766.1714422826935, 3796.126890815128, 3836.76979381238, 3839.289731399644, 3844.4233364995475, 1149.2185313986402, 1199.6401079676061, 1200.6158029430499, 1217.7320989382322, 1243.4031664800946, 1222.529326209398, 1242.236024844714, 1263.860975292717, 1521.6195951012246, 1522.9010114342532, 7442.590657513781, 7563.394928405714, 7619.276168763959, 7651.043619547557, 7665.233476652402, 1235.5366737473448, 1244.8195011723249, 1246.1214843535286, 1247.2391840976998, 1247.6631309546372, 6142.04798256271, 6163.84590385244, 6228.750849966042, 6242.15730876335, 6245.375462453719, 9331.253499720005, 9527.047295270557, 9533.0, 9574.090461406155, 9577.185939195107, 9259.166674999282, 9396.812063758687, 9423.293253006052, 9424.198943089914, 9435.433873967446, 7304.306090921354, 7361.48469607133, 7378.631068446556, 7379.6716591013055, 7385.261473852464, 7321.450891183076, 7343.082114735658, 7398.96300518494, 7427.665755040928, 7430.591317477591, 8437.282830959437, 8443.155684431507, 8540.14598540166, 8615.310775137848, 8620.13798620133, 8500.44747091437, 8587.699430519902, 8634.913650863662, 8656.004559475741, 8658.696945606873, 7205.819854503679, 7216.494845360984, 7251.56490610565, 7257.818554536178, 7264.23725508814, 7749.535027898344, 7783.338416234457, 7842.568658723825, 7867.488613240121, 7868.685252589832, 5933.643981361132, 7393.55638661682, 7426.554406735613, 7473.43949203801, 7590.8102297443, 7208.819779505554, 7254.310840470147, 7258.0, 7338.559686418832, 7347.926520734724, 7098.219195888345, 7331.266873312839, 7363.190049094488, 7422.072240969879, 7431.70273189088, 6668.799936001928, 6705.0, 6709.295523970108, 6714.798556043326, 6726.125603671491, 6826.941824017285, 6835.89746153818, 6866.6566671667215, 6873.690683919136, 6881.174259088942], "unit": "ms", "name": "mootools.html", "value": 4656.908783226909}, {"lowerIsBetter": false, "replicates": [357.3139572021718, 364.5607043512568, 365.58323511197233, 367.1134210880723, 369.5122438381336, 5871.178035075082, 5996.100584912266, 6024.6385216886965, 6047.727852246643, 6048.606840555361, 5802.39074897136, 5911.438413350736, 5927.911081333784, 5952.70236488176, 5963.940360596393, 4657.347971284023, 4658.883527911796, 4667.579917807397, 4667.789949452266, 4672.018876036036, 4522.3216517522305, 4573.702709323883, 4611.838585649503, 4654.790534425942, 4655.580997710205, 4608.032313214212, 4625.560571745696, 4634.559716826896, 4635.605973492257, 4642.257238841786, 4600.148972440109, 4600.746958917275, 4604.562566556172, 4619.884502887421, 4624.884377890563, 4063.3904914262803, 4076.8776936691947, 4079.6124368185274, 4080.510338759368, 4085.816138273785, 5648.181013753024, 5740.741666625012, 5741.511971482429, 5766.365699773021, 5772.047612144033, 5447.073997420448, 5450.56395488364, 5451.100568406208, 5453.0, 5453.045716999509, 5965.791197308074, 6358.523110766711, 6367.363263673642, 6367.968160159216, 6370.267419246799, 4441.267190913495, 4560.224761790503, 4608.608268297199, 4611.239145541015, 4617.422822147231, 6493.448056915215, 6513.967430162819, 6543.93456065438, 6544.443722283611, 6553.14809074822, 4718.245080787057, 4726.0, 4729.12511185425, 4729.722974796786, 4732.148213321635, 4723.7401942893475, 4747.525247475225, 4784.258439941815, 4791.209450440708, 4832.251001094836, 5467.917981230285, 5470.863228419242, 5473.562115030788, 5474.42518535556, 5476.616636835463, 6431.5497915146425, 6432.2602900667025, 6437.066625339299, 6440.871182576322, 6446.484281257488, 1731.2382551677474, 1760.6654735600196, 1761.4451447793904, 1762.2422358386018, 1762.4007837335112, 1148.9023433008288, 1149.3276433286453, 1163.4299193395175, 1174.6887074925153, 1223.5228260978226, 6079.452849243589, 6091.269047714302, 6092.025275955826, 6096.451319381188, 6096.481799047042, 6397.968010160012, 6416.679166041679, 6421.036844473273, 6421.422072013448, 6425.518086143558, 6071.180390647206, 6078.149059131725, 6080.0, 6081.270247570232, 6126.540509461808, 3599.1541987632886, 3641.0715267606593, 3641.7632853864416, 3645.0, 3645.4167333227088, 6779.35596118368, 6803.047573339736, 6807.047013418027, 6814.8296292592095, 6814.897776533356, 6652.966735166293, 6785.626790526569, 6793.72825086991, 6795.082663840417, 6796.286389929085, 6766.086578311865, 6792.422644075309, 6796.116504854436, 6804.183497980274, 6807.047013418125, 6338.366163383625, 6440.710168042543, 6472.35276472349, 6530.934690653223, 6560.803175904731, 6488.610683359014, 6489.123968264224, 6505.089287499659, 6511.511636627367, 6519.511036672174, 6725.428338591275, 6763.86472270542, 6770.492213084137, 6775.0, 6777.559458635173, 4511.684182107221, 4592.7014744041535, 4637.768111594474, 4638.489766125656, 4654.092451971833, 6520.380563846427, 6684.231313398827, 6690.29751876046, 6692.431143352675, 6697.330266973264, 6506.674666266762, 6510.283868774336, 6511.34886511345, 6512.73949042033, 6513.54405191632, 6365.64988925595, 6440.903386449113, 6446.355364463516, 6491.415772580491, 6498.057781621733, 6334.271558770801, 6369.20384951881, 6376.776812811529, 6377.17096777416, 6382.712777924911, 6451.580647257913, 6462.256840463218, 6473.255575608677, 6474.352564743488, 6478.125453063964, 6211.223597050369, 6297.992321228581, 6299.96850015747, 6306.779262725782, 6313.210848643919], "unit": "ms", "name": "prototype.html", "value": 4877.504336135024}, {"lowerIsBetter": false, "replicates": [847.3221422861709, 944.7874228298633, 945.106874004066, 947.459947829737, 961.5480724059689, 13512.256825874572, 13843.100198487104, 13853.099548529339, 13871.861281387182, 13874.514391996283, 13369.0, 14426.0, 14473.78289325661, 14494.855051449482, 14510.201938893357, 769.4613770360742, 909.9863502047441, 929.1451864284841, 932.0306880843914, 932.2122806228726, 921.2261700171854, 923.0308176414771, 925.3430064654103, 927.7587827164919, 929.2565947242213, 806.9677212911506, 818.5416166946529, 973.9513024348789, 979.4564016970578, 981.224832382417, 956.2063487305554, 966.3042609321312, 968.7626531499792, 972.6060945317158, 972.6304004478253, 713.9785806425815, 720.9567425954459, 723.7286017743346, 725.9927400725978, 726.625787719325, 13308.467661293536, 13462.798058029137, 13474.595762127054, 13480.191188528719, 13495.66260843477, 12508.3745812709, 13664.931675341659, 14317.42730290787, 14380.424783008668, 14402.567922962224, 10602.416867072308, 10646.627368042158, 10723.46382680863, 10790.0, 10809.0, 3868.825902834379, 3953.743006704584, 3958.386450100239, 3967.8016099195215, 3967.920641587181, 53261.46738532604, 53690.0, 54237.72881135569, 54794.452055479735, 54903.450965490636, 8940.68707595244, 8982.191602755784, 10376.066154046172, 10379.0, 10502.579896804195, 13996.510122145675, 14115.576532704035, 14118.71762564743, 14133.293335333397, 14156.646083847987, 7105.32499412555, 7131.251218622074, 7380.483366164317, 7391.778246652501, 7407.148177959496, 4675.4155730533685, 4735.95264047362, 4737.573618374363, 4758.452777930583, 4779.01652398962, 3149.889753858604, 3151.101935948244, 3154.6372167200607, 3181.44324743169, 3182.3635272945503, 486.98052077917197, 491.0865789631283, 496.22588761532126, 498.53138050232667, 499.69019208091214, 685.917689877208, 697.0902971621981, 718.1346477494607, 769.8922150898878, 783.3498196496894, 8802.691905783395, 9147.359684821999, 9274.118958698913, 9309.627614895327, 9329.253659707207, 647.5143642268299, 680.7038938061959, 730.3718801830422, 732.3921145449265, 770.2220757035435, 4289.699721019561, 4307.827686892552, 4351.325544540601, 4356.607905288539, 4457.465104187519, 69685.0, 71073.0, 71133.64433177904, 71265.0, 71361.0, 61215.38784612186, 61253.0, 61383.38616613777, 62996.37003629905, 64619.67690161519, 55181.448185518435, 55329.0, 55423.722881386144, 55735.72132139313, 55857.0, 8758.430702004349, 8786.956065219632, 9009.324300677345, 9115.134062264075, 9330.813383732424, 40066.79966600148, 40367.1926561473, 41046.58953410428, 41590.37614435725, 43716.065554100285, 54217.45782542124, 54450.727746361015, 54875.451245487035, 55006.17490737562, 56275.43724562866, 8538.402311838112, 8573.74278771638, 8968.68609598661, 9134.406263593111, 9146.77133071652, 11236.0, 11550.0, 11782.351970641697, 12068.0, 12492.750144997231, 11300.208985370946, 11321.433928303388, 11788.528458861892, 11845.703857403634, 11909.702257443632, 12538.0, 12563.246205227715, 12651.746965060831, 12928.0, 13090.01824863158, 11709.238899471507, 11879.0, 11922.080510163343, 12112.333821639895, 12226.44980975876, 10590.470476475992, 10595.205359598214, 10751.623693171014, 10924.672259832218, 11203.0, 9957.502124893581, 10224.693259202237, 10226.488675566043, 10287.125594324567, 10296.382217067], "unit": "ms", "name": "yui.html", "value": 6736.1181174583135}], "name": "dromaeo_css", "value": 7213.327519647846}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 105.0, 112.0, 110.0, 112.0, 98.0, 109.0, 100.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [147.0, 121.0, 151.0, 159.0, 124.0, 151.0, 153.0, 131.0, 170.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.0}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 160.0, 159.0, 159.0, 161.0, 158.0, 160.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 159.5}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 70.0, 147.0, 71.0, 71.0, 71.0, 71.0, 151.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 86.5}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 131.0, 89.0, 90.0, 91.0, 131.0, 89.0, 90.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 102.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 147.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.6}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 57.0, 56.0, 56.0, 56.0, 55.0, 55.0, 57.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.6}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 64.0, 63.0, 64.0, 63.0, 63.0, 63.0, 63.0, 63.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.2}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 79.0, 78.0, 78.0, 79.0, 77.0, 77.0, 77.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.6}, {"lowerIsBetter": true, "replicates": [123.0, 111.0, 120.0, 106.0, 116.0, 109.0, 111.0, 115.0, 108.0, 108.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 112.7}, {"lowerIsBetter": true, "replicates": [138.0, 138.0, 139.0, 137.0, 135.0, 138.0, 135.0, 135.0, 137.0, 137.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 136.9}, {"lowerIsBetter": true, "replicates": [61.0, 62.0, 63.0, 64.0, 61.0, 68.0, 61.0, 60.0, 61.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.2}], "name": "kraken", "value": 1457.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.0150893882237], "unit": "score", "name": "Richards", "value": 215.23629400000002}, {"lowerIsBetter": false, "replicates": [170.7067258449983], "unit": "score", "name": "DeltaBlue", "value": 387.31924399999986}, {"lowerIsBetter": false, "replicates": [280.42624789680315], "unit": "score", "name": "Encrypt", "value": 949.2014460000001}, {"lowerIsBetter": false, "replicates": [4990.049751243781], "unit": "score", "name": "Decrypt", "value": 53.34235393818545}, {"lowerIsBetter": false, "replicates": [952.3809523809524], "unit": "score", "name": "RayTrace", "value": 776.9884500000003}, {"lowerIsBetter": false, "replicates": [556.4830272676684], "unit": "score", "name": "Earley", "value": 1197.6340109999996}, {"lowerIsBetter": false, "replicates": [10437.5], "unit": "score", "name": "Boyer", "value": 63.85274251497008}, {"lowerIsBetter": false, "replicates": [23465.116279069767], "unit": "score", "name": "RegExp", "value": 38.82294846382556}, {"lowerIsBetter": false, "replicates": [536.1930294906166], "unit": "score", "name": "Splay", "value": 151.980715}, {"lowerIsBetter": false, "replicates": [4812.5], "unit": "score", "name": "NavierStokes", "value": 308.3636363636364}], "name": "v8_7", "value": 23309.37987155354}]} 16:47:34 INFO - RETURN: dromaeo_css: 7213.33 16:47:34 INFO - RETURN: kraken: 1457.80 16:47:34 INFO - RETURN: v8_7: 23309.38 16:47:34 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23309.38"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1457.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7213.33"}}} 16:47:34 INFO - Return code: 0 16:47:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:47:34 INFO - # TBPL SUCCESS # 16:47:34 INFO - Running post-action listener: _resource_record_post_action 16:47:34 INFO - Running post-run listener: _resource_record_post_run 16:47:35 INFO - Total resource usage - Wall time: 1220s; CPU: 13.0%; Read bytes: 315392; Write bytes: 502427648; Read time: 76; Write time: 583520 16:47:35 INFO - install - Wall time: 14s; CPU: 15.0%; Read bytes: 0; Write bytes: 234643456; Read time: 0; Write time: 403084 16:47:36 INFO - run-tests - Wall time: 1206s; CPU: 12.0%; Read bytes: 315392; Write bytes: 263790592; Read time: 76; Write time: 179604 16:47:36 INFO - Running post-run listener: _upload_blobber_files 16:47:36 INFO - Blob upload gear active. 16:47:36 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:47:36 INFO - Copying logs to upload dir... 16:47:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.849232 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-01-14 16:47:35.862969) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 16:47:35.866886) ========= 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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010103 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452809011/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-14 16:47:35.918053) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:47:35.918419) ========= 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=62c568580acbfdaac3eec7be000001dc-1452817598.491399-603002638 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 16:47:35.968771) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 16:47:35.969115) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 16:47:35.969468) ========= ========= Total master_lag: 0.23 =========