builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1452036329.66 results: success (0) buildid: 20160105130527 builduid: 900838ee248a4ed89165cdb935677e9c revision: aa03498e8961 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.662110) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.662823) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.663349) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005280 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.688523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.688977) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.705248) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.705701) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004818 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.820838) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.821121) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.821491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.821767) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-05 15:25:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-01-05 15:25:30 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151313 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.988527) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:29.988829) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014573 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.038405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.038784) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev aa03498e8961 --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 aa03498e8961 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-05 15:25:30,379 Setting DEBUG logging. 2016-01-05 15:25:30,379 attempt 1/10 2016-01-05 15:25:30,379 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/aa03498e8961?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-05 15:25:30,964 unpacking tar archive at: mozilla-beta-aa03498e8961/testing/mozharness/ program finished with exit code 0 elapsedTime=0.876622 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.928449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.928767) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.935932) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.936274) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 15:25:30.936746) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-01-05 15:25:30.937039) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:25:31 INFO - MultiFileLogger online at 20160105 15:25:31 in /builds/slave/test-pgo 15:25:31 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 15:25:31 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:25:31 INFO - {'append_to_log': False, 15:25:31 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:25:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:25:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:25:31 INFO - 'branch': 'Mozilla-Beta', 15:25:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:25:31 INFO - 'config_files': ('talos/linux_config.py',), 15:25:31 INFO - 'default_actions': ('clobber', 15:25:31 INFO - 'read-buildbot-config', 15:25:31 INFO - 'download-and-extract', 15:25:31 INFO - 'populate-webroot', 15:25:31 INFO - 'create-virtualenv', 15:25:31 INFO - 'install', 15:25:31 INFO - 'run-tests'), 15:25:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:25:31 INFO - 'download_minidump_stackwalk': True, 15:25:31 INFO - 'download_symbols': 'ondemand', 15:25:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:25:31 INFO - 'tooltool.py': '/tools/tooltool.py', 15:25:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:25:31 INFO - '/tools/misc-python/virtualenv.py')}, 15:25:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:25:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:25:31 INFO - 'installer_path': 'installer.exe', 15:25:31 INFO - 'log_level': 'info', 15:25:31 INFO - 'log_name': 'talos', 15:25:31 INFO - 'log_to_console': True, 15:25:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:25:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:25:31 INFO - 'opt_config_files': (), 15:25:31 INFO - 'pip_index': False, 15:25:31 INFO - 'sps_profile': False, 15:25:31 INFO - 'sps_profile_interval': 0, 15:25:31 INFO - 'suite': 'dromaeojs', 15:25:31 INFO - 'system_bits': '32', 15:25:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:25:31 INFO - 'title': 'talos-linux64-ix-014', 15:25:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:25:31 INFO - 'use_talos_json': True, 15:25:31 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:25:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:25:31 INFO - 'work_dir': 'build'} 15:25:31 INFO - ##### 15:25:31 INFO - ##### Running clobber step. 15:25:31 INFO - ##### 15:25:31 INFO - Running pre-action listener: _resource_record_pre_action 15:25:31 INFO - Running main action method: clobber 15:25:31 INFO - rmtree: /builds/slave/test-pgo/build 15:25:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:25:31 INFO - Running post-action listener: _resource_record_post_action 15:25:31 INFO - ##### 15:25:31 INFO - ##### Running read-buildbot-config step. 15:25:31 INFO - ##### 15:25:31 INFO - Running pre-action listener: _resource_record_pre_action 15:25:31 INFO - Running main action method: read_buildbot_config 15:25:31 INFO - Using buildbot properties: 15:25:31 INFO - { 15:25:31 INFO - "properties": { 15:25:31 INFO - "buildnumber": 65, 15:25:31 INFO - "product": "firefox", 15:25:31 INFO - "script_repo_revision": "production", 15:25:31 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:25:31 INFO - "repository": "", 15:25:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:25:31 INFO - "buildid": "20160105130527", 15:25:31 INFO - "slavename": "talos-linux64-ix-014", 15:25:31 INFO - "pgo_build": "True", 15:25:31 INFO - "basedir": "/builds/slave/test-pgo", 15:25:31 INFO - "project": "", 15:25:31 INFO - "platform": "ubuntu64_hw", 15:25:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:25:31 INFO - "slavebuilddir": "test", 15:25:31 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:25:31 INFO - "branch": "mozilla-beta", 15:25:31 INFO - "repo_path": "releases/mozilla-beta", 15:25:31 INFO - "stage_platform": "linux64-pgo", 15:25:31 INFO - "builduid": "900838ee248a4ed89165cdb935677e9c", 15:25:31 INFO - "revision": "aa03498e8961" 15:25:31 INFO - }, 15:25:31 INFO - "sourcestamp": { 15:25:31 INFO - "repository": "", 15:25:31 INFO - "hasPatch": false, 15:25:31 INFO - "project": "", 15:25:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:25:31 INFO - "changes": [ 15:25:31 INFO - { 15:25:31 INFO - "category": null, 15:25:31 INFO - "files": [ 15:25:31 INFO - { 15:25:31 INFO - "url": null, 15:25:31 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2" 15:25:31 INFO - } 15:25:31 INFO - ], 15:25:31 INFO - "repository": "", 15:25:31 INFO - "rev": "aa03498e8961", 15:25:31 INFO - "who": "sendchange", 15:25:31 INFO - "when": 1452036299, 15:25:31 INFO - "number": 7420701, 15:25:31 INFO - "comments": "Bug 1234054 - Enable Push and service worker notifications on Desktop release builds. r=ehsan a=ritu", 15:25:31 INFO - "project": "", 15:25:31 INFO - "at": "Tue 05 Jan 2016 15:24:59", 15:25:31 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:25:31 INFO - "revlink": "", 15:25:31 INFO - "properties": [ 15:25:31 INFO - [ 15:25:31 INFO - "buildid", 15:25:31 INFO - "20160105130527", 15:25:31 INFO - "Change" 15:25:31 INFO - ], 15:25:31 INFO - [ 15:25:31 INFO - "builduid", 15:25:31 INFO - "900838ee248a4ed89165cdb935677e9c", 15:25:31 INFO - "Change" 15:25:31 INFO - ], 15:25:31 INFO - [ 15:25:31 INFO - "pgo_build", 15:25:31 INFO - "True", 15:25:31 INFO - "Change" 15:25:31 INFO - ] 15:25:31 INFO - ], 15:25:31 INFO - "revision": "aa03498e8961" 15:25:31 INFO - } 15:25:31 INFO - ], 15:25:31 INFO - "revision": "aa03498e8961" 15:25:31 INFO - } 15:25:31 INFO - } 15:25:31 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2. 15:25:31 INFO - Running post-action listener: _resource_record_post_action 15:25:31 INFO - ##### 15:25:31 INFO - ##### Running download-and-extract step. 15:25:31 INFO - ##### 15:25:31 INFO - Running pre-action listener: _resource_record_pre_action 15:25:31 INFO - Running main action method: download_and_extract 15:25:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/test_packages.json 15:25:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:25:31 INFO - Downloaded 1270 bytes. 15:25:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:25:31 INFO - Using the following test package requirements: 15:25:31 INFO - {u'common': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:25:31 INFO - u'cppunittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'firefox-44.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:25:31 INFO - u'jittest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'jsshell-linux-x86_64.zip'], 15:25:31 INFO - u'mochitest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'firefox-44.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:25:31 INFO - u'mozbase': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:25:31 INFO - u'reftest': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'firefox-44.0.en-US.linux-x86_64.reftest.tests.zip'], 15:25:31 INFO - u'talos': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'], 15:25:31 INFO - u'web-platform': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'firefox-44.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:25:31 INFO - u'webapprt': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'], 15:25:31 INFO - u'xpcshell': [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', 15:25:31 INFO - u'firefox-44.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:25:31 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:25:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:36 INFO - Downloaded 22572230 bytes. 15:25:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:25:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:25:36 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:25:37 INFO - Return code: 0 15:25:37 INFO - Downloading packages: [u'firefox-44.0.en-US.linux-x86_64.common.tests.zip', u'firefox-44.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:25:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:38 INFO - Downloaded 22572230 bytes. 15:25:38 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 15:25:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.common.tests.zip 15:25:38 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 15:25:39 INFO - Return code: 0 15:25:39 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')]} 15:25:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:25:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:41 INFO - Downloaded 11131970 bytes. 15:25:41 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 15:25:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-44.0.en-US.linux-x86_64.talos.tests.zip 15:25:41 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 15:25:41 INFO - Return code: 0 15:25: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')]} 15:25:41 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:25:41 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/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 15:25:49 INFO - Downloaded 56626351 bytes. 15:25:49 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:25:49 INFO - mkdir: /builds/slave/test-pgo/properties 15:25:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:25:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:25:49 INFO - Contents: 15:25:49 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2 15:25:49 INFO - Running post-action listener: _resource_record_post_action 15:25:49 INFO - Running post-action listener: set_extra_try_arguments 15:25:49 INFO - ##### 15:25:49 INFO - ##### Running populate-webroot step. 15:25:49 INFO - ##### 15:25:49 INFO - Running pre-action listener: _resource_record_pre_action 15:25:49 INFO - Running main action method: populate_webroot 15:25:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:25:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:25:49 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 15:25:49 INFO - '../fennec_ids.txt'], 15:25:49 INFO - 'tests': ['tcheck2']}, 15:25:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:25:49 INFO - '--tppagecycles', 15:25:49 INFO - '7'], 15:25:49 INFO - 'tests': ['tsvgm']}}, 15:25:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:25:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:25:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:25:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:25:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:25:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'tests': ['damp', 'tps']}, 15:25:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['damp', 'tps']}, 15:25:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:25:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:25:49 INFO - 'other': {'tests': ['a11yr', 15:25:49 INFO - 'ts_paint', 15:25:49 INFO - 'tpaint', 15:25:49 INFO - 'sessionrestore', 15:25:49 INFO - 'sessionrestore_no_auto_restore']}, 15:25:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['a11yr', 15:25:49 INFO - 'ts_paint', 15:25:49 INFO - 'tpaint', 15:25:49 INFO - 'sessionrestore', 15:25:49 INFO - 'sessionrestore_no_auto_restore']}, 15:25:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['a11yr', 15:25:49 INFO - 'ts_paint', 15:25:49 INFO - 'tpaint', 15:25:49 INFO - 'sessionrestore', 15:25:49 INFO - 'sessionrestore_no_auto_restore']}, 15:25:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['a11yr', 15:25:49 INFO - 'ts_paint', 15:25:49 INFO - 'tpaint', 15:25:49 INFO - 'sessionrestore', 15:25:49 INFO - 'sessionrestore_no_auto_restore']}, 15:25:49 INFO - 'other_l64': {'tests': ['a11yr', 15:25:49 INFO - 'ts_paint', 15:25:49 INFO - 'tpaint', 15:25:49 INFO - 'sessionrestore', 15:25:49 INFO - 'sessionrestore_no_auto_restore']}, 15:25:49 INFO - 'other_nol64': {'tests': ['a11yr', 15:25:49 INFO - 'ts_paint', 15:25:49 INFO - 'tpaint', 15:25:49 INFO - 'sessionrestore', 15:25:49 INFO - 'sessionrestore_no_auto_restore']}, 15:25:49 INFO - 'svgr': {'tests': ['tsvgx', 15:25:49 INFO - 'tsvgr_opacity', 15:25:49 INFO - 'tart', 15:25:49 INFO - 'tscrollx', 15:25:49 INFO - 'cart']}, 15:25:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['tsvgx', 15:25:49 INFO - 'tsvgr_opacity', 15:25:49 INFO - 'tart', 15:25:49 INFO - 'tscrollx', 15:25:49 INFO - 'cart']}, 15:25:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'tests': ['tp5o']}, 15:25:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'talos_options': ['--e10s'], 15:25:49 INFO - 'tests': ['tp5o']}, 15:25:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'talos_options': ['--xperf_path', 15:25:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:49 INFO - 'tests': ['tp5n']}, 15:25:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:25:49 INFO - 'talos_options': ['--e10s', 15:25:49 INFO - '--xperf_path', 15:25:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:25:49 INFO - 'tests': ['tp5n']}}, 15:25:49 INFO - 'talos.zip': {'path': '', 15:25:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:25:49 INFO - Running post-action listener: _resource_record_post_action 15:25:49 INFO - ##### 15:25:49 INFO - ##### Running create-virtualenv step. 15:25:49 INFO - ##### 15:25:49 INFO - Running pre-action listener: _resource_record_pre_action 15:25:49 INFO - Running main action method: create_virtualenv 15:25:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:25:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 15:25:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:25:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:25:49 INFO - Using real prefix '/usr' 15:25:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:25:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:25:51 INFO - Installing pip.................done. 15:25:51 INFO - Return code: 0 15:25:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:25:51 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')]} 15:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:51 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')]} 15:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:51 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 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:25:51 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 15:25:51 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 15:25:51 INFO - Using env: {'DISPLAY': ':0', 15:25:51 INFO - 'HOME': '/home/cltbld', 15:25:51 INFO - 'LANG': 'en_US.UTF-8', 15:25:51 INFO - 'LANGUAGE': 'en_US:en', 15:25:51 INFO - 'LOGNAME': 'cltbld', 15:25:51 INFO - 'MAIL': '/var/mail/cltbld', 15:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:51 INFO - 'MOZ_NO_REMOTE': '1', 15:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:51 INFO - 'NO_EM_RESTART': '1', 15:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:25:51 INFO - 'PWD': '/builds/slave/test-pgo', 15:25:51 INFO - 'SHELL': '/bin/bash', 15:25:51 INFO - 'SHLVL': '1', 15:25:51 INFO - 'TERM': 'linux', 15:25:51 INFO - 'TMOUT': '86400', 15:25:51 INFO - 'USER': 'cltbld', 15:25:51 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:51 INFO - '_': '/tools/buildbot/bin/python'} 15:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:25:52 INFO - Cleaning up... 15:25:52 INFO - Return code: 0 15:25:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:25:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 15:25:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:25:52 INFO - Using env: {'DISPLAY': ':0', 15:25:52 INFO - 'HOME': '/home/cltbld', 15:25:52 INFO - 'LANG': 'en_US.UTF-8', 15:25:52 INFO - 'LANGUAGE': 'en_US:en', 15:25:52 INFO - 'LOGNAME': 'cltbld', 15:25:52 INFO - 'MAIL': '/var/mail/cltbld', 15:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:52 INFO - 'MOZ_NO_REMOTE': '1', 15:25:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:52 INFO - 'NO_EM_RESTART': '1', 15:25:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:25:52 INFO - 'PWD': '/builds/slave/test-pgo', 15:25:52 INFO - 'SHELL': '/bin/bash', 15:25:52 INFO - 'SHLVL': '1', 15:25:52 INFO - 'TERM': 'linux', 15:25:52 INFO - 'TMOUT': '86400', 15:25:52 INFO - 'USER': 'cltbld', 15:25:52 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:52 INFO - '_': '/tools/buildbot/bin/python'} 15:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:52 INFO - Downloading/unpacking psutil>=0.7.1 15:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:54 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:25:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:25:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:54 INFO - Installing collected packages: psutil 15:25:54 INFO - Running setup.py install for psutil 15:25:54 INFO - building 'psutil._psutil_linux' extension 15:25:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:25:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:25:54 INFO - building 'psutil._psutil_posix' extension 15:25:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:25:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:25:54 INFO - Successfully installed psutil 15:25:54 INFO - Cleaning up... 15:25:54 INFO - Return code: 0 15:25:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:25: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')]} 15:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:25:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 15:25:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:25:54 INFO - Using env: {'DISPLAY': ':0', 15:25:54 INFO - 'HOME': '/home/cltbld', 15:25:54 INFO - 'LANG': 'en_US.UTF-8', 15:25:54 INFO - 'LANGUAGE': 'en_US:en', 15:25:54 INFO - 'LOGNAME': 'cltbld', 15:25:54 INFO - 'MAIL': '/var/mail/cltbld', 15:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:54 INFO - 'MOZ_NO_REMOTE': '1', 15:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:54 INFO - 'NO_EM_RESTART': '1', 15:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:25:54 INFO - 'PWD': '/builds/slave/test-pgo', 15:25:54 INFO - 'SHELL': '/bin/bash', 15:25:54 INFO - 'SHLVL': '1', 15:25:54 INFO - 'TERM': 'linux', 15:25:54 INFO - 'TMOUT': '86400', 15:25:54 INFO - 'USER': 'cltbld', 15:25:54 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:54 INFO - '_': '/tools/buildbot/bin/python'} 15:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:25:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:25:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:25:56 INFO - Installing collected packages: mozsystemmonitor 15:25:56 INFO - Running setup.py install for mozsystemmonitor 15:25:56 INFO - Successfully installed mozsystemmonitor 15:25:56 INFO - Cleaning up... 15:25:56 INFO - Return code: 0 15:25:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:25: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')]} 15:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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', '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 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:25: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', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 15:25: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 blobuploader==1.2.4 15:25:56 INFO - Using env: {'DISPLAY': ':0', 15:25:56 INFO - 'HOME': '/home/cltbld', 15:25:56 INFO - 'LANG': 'en_US.UTF-8', 15:25:56 INFO - 'LANGUAGE': 'en_US:en', 15:25:56 INFO - 'LOGNAME': 'cltbld', 15:25:56 INFO - 'MAIL': '/var/mail/cltbld', 15:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:56 INFO - 'MOZ_NO_REMOTE': '1', 15:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:25:56 INFO - 'NO_EM_RESTART': '1', 15:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:25:56 INFO - 'PWD': '/builds/slave/test-pgo', 15:25:56 INFO - 'SHELL': '/bin/bash', 15:25:56 INFO - 'SHLVL': '1', 15:25:56 INFO - 'TERM': 'linux', 15:25:56 INFO - 'TMOUT': '86400', 15:25:56 INFO - 'USER': 'cltbld', 15:25:56 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:56 INFO - '_': '/tools/buildbot/bin/python'} 15:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:56 INFO - Downloading/unpacking blobuploader==1.2.4 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25:58 INFO - Downloading blobuploader-1.2.4.tar.gz 15:25:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:25:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:25:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:25:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:25:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:59 INFO - Downloading docopt-0.6.1.tar.gz 15:25:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:25:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:25:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:25:59 INFO - Running setup.py install for blobuploader 15:25:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:25:59 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:25:59 INFO - Running setup.py install for requests 15:25:59 INFO - Running setup.py install for docopt 15:25:59 INFO - Successfully installed blobuploader requests docopt 15:25:59 INFO - Cleaning up... 15:26:00 INFO - Return code: 0 15:26:00 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:26:00 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')]} 15:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:00 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')]} 15:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:00 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 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:26:00 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 15:26:00 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 15:26:00 INFO - Using env: {'DISPLAY': ':0', 15:26:00 INFO - 'HOME': '/home/cltbld', 15:26:00 INFO - 'LANG': 'en_US.UTF-8', 15:26:00 INFO - 'LANGUAGE': 'en_US:en', 15:26:00 INFO - 'LOGNAME': 'cltbld', 15:26:00 INFO - 'MAIL': '/var/mail/cltbld', 15:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:00 INFO - 'MOZ_NO_REMOTE': '1', 15:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:00 INFO - 'NO_EM_RESTART': '1', 15:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:00 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:00 INFO - 'SHELL': '/bin/bash', 15:26:00 INFO - 'SHLVL': '1', 15:26:00 INFO - 'TERM': 'linux', 15:26:00 INFO - 'TMOUT': '86400', 15:26:00 INFO - 'USER': 'cltbld', 15:26:00 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:00 INFO - '_': '/tools/buildbot/bin/python'} 15:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:26:00 INFO - Running setup.py (path:/tmp/pip-VrzVb8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:26:00 INFO - Running setup.py (path:/tmp/pip-ZKXgDJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:26:00 INFO - Running setup.py (path:/tmp/pip-fVr7qw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:26:00 INFO - Running setup.py (path:/tmp/pip-mkw4pM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:26:00 INFO - Running setup.py (path:/tmp/pip-S9SeAF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:26:00 INFO - Running setup.py (path:/tmp/pip-v8_TJD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:26:00 INFO - Running setup.py (path:/tmp/pip-Br_f6b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:26:00 INFO - Running setup.py (path:/tmp/pip-B2Zae7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:26:00 INFO - Running setup.py (path:/tmp/pip-fGIyvn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:26:00 INFO - Running setup.py (path:/tmp/pip-_xJNkx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:26:00 INFO - Running setup.py (path:/tmp/pip-qK5rCZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:26:01 INFO - Running setup.py (path:/tmp/pip-HlcvEm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:26:01 INFO - Running setup.py (path:/tmp/pip-rqWfCQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:26:01 INFO - Running setup.py (path:/tmp/pip-UToojr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:26:01 INFO - Running setup.py (path:/tmp/pip-9if4Qx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:26:01 INFO - Running setup.py (path:/tmp/pip-a3g2KT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:26:01 INFO - Running setup.py (path:/tmp/pip-TY91Uj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:26:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:26:01 INFO - Running setup.py install for manifestparser 15:26:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:26:01 INFO - Running setup.py install for mozcrash 15:26:01 INFO - Running setup.py install for mozdebug 15:26:01 INFO - Running setup.py install for mozdevice 15:26:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:26:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:26:01 INFO - Running setup.py install for mozfile 15:26:01 INFO - Running setup.py install for mozhttpd 15:26:02 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for mozinfo 15:26:02 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for mozInstall 15:26:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for mozleak 15:26:02 INFO - Running setup.py install for mozlog 15:26:02 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for moznetwork 15:26:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for mozprocess 15:26:02 INFO - Running setup.py install for mozprofile 15:26:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for mozrunner 15:26:02 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:26:02 INFO - Running setup.py install for mozscreenshot 15:26:02 INFO - Running setup.py install for moztest 15:26:03 INFO - Running setup.py install for mozversion 15:26:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:26:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:26:03 INFO - Cleaning up... 15:26:03 INFO - Return code: 0 15:26:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:03 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 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:26:03 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 15:26:03 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 15:26:03 INFO - Using env: {'DISPLAY': ':0', 15:26:03 INFO - 'HOME': '/home/cltbld', 15:26:03 INFO - 'LANG': 'en_US.UTF-8', 15:26:03 INFO - 'LANGUAGE': 'en_US:en', 15:26:03 INFO - 'LOGNAME': 'cltbld', 15:26:03 INFO - 'MAIL': '/var/mail/cltbld', 15:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:03 INFO - 'MOZ_NO_REMOTE': '1', 15:26:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:03 INFO - 'NO_EM_RESTART': '1', 15:26:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:03 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:03 INFO - 'SHELL': '/bin/bash', 15:26:03 INFO - 'SHLVL': '1', 15:26:03 INFO - 'TERM': 'linux', 15:26:03 INFO - 'TMOUT': '86400', 15:26:03 INFO - 'USER': 'cltbld', 15:26:03 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:03 INFO - '_': '/tools/buildbot/bin/python'} 15:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:26:03 INFO - Running setup.py (path:/tmp/pip-pp_WPb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:26:03 INFO - Running setup.py (path:/tmp/pip-vnn7DF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:26:03 INFO - Running setup.py (path:/tmp/pip-P8gMFT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:26:03 INFO - Running setup.py (path:/tmp/pip-2QW8DP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:26:03 INFO - Running setup.py (path:/tmp/pip-yBE0Rl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:26:03 INFO - Running setup.py (path:/tmp/pip-eOvdN3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:26:03 INFO - Running setup.py (path:/tmp/pip-LoEj89-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:26:03 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)) 15:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:26:03 INFO - Running setup.py (path:/tmp/pip-DqAC3c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:26:04 INFO - Running setup.py (path:/tmp/pip-QzZ7BA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:26:04 INFO - Running setup.py (path:/tmp/pip-CVRiSb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:26:04 INFO - Running setup.py (path:/tmp/pip-x2GPcM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:26:04 INFO - Running setup.py (path:/tmp/pip-_BPBSD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:26:04 INFO - Running setup.py (path:/tmp/pip-dWDMmp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:26:04 INFO - Running setup.py (path:/tmp/pip-l9q0KU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:26:04 INFO - Running setup.py (path:/tmp/pip-QWuZiB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:26:04 INFO - Running setup.py (path:/tmp/pip-1gN93t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:26:04 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)) 15:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:26:04 INFO - Running setup.py (path:/tmp/pip-REUNSA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:26:04 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)) 15:26:04 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)) 15:26:04 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)) 15:26:04 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)) 15:26:04 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)) 15:26:04 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)) 15:26:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:06 INFO - Downloading blessings-1.5.1.tar.gz 15:26:06 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 15:26:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:26:06 INFO - Installing collected packages: blessings 15:26:06 INFO - Running setup.py install for blessings 15:26:06 INFO - Successfully installed blessings 15:26:06 INFO - Cleaning up... 15:26:06 INFO - Return code: 0 15:26:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:26:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:26:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:26:06 INFO - Reading from file tmpfile_stdout 15:26:06 INFO - Current package versions: 15:26:06 INFO - argparse == 1.2.1 15:26:06 INFO - blessings == 1.5.1 15:26:06 INFO - blobuploader == 1.2.4 15:26:06 INFO - docopt == 0.6.1 15:26:06 INFO - manifestparser == 1.1 15:26:06 INFO - mozInstall == 1.12 15:26:06 INFO - mozcrash == 0.16 15:26:06 INFO - mozdebug == 0.1 15:26:06 INFO - mozdevice == 0.46 15:26:06 INFO - mozfile == 1.2 15:26:06 INFO - mozhttpd == 0.7 15:26:06 INFO - mozinfo == 0.8 15:26:06 INFO - mozleak == 0.1 15:26:06 INFO - mozlog == 3.0 15:26:06 INFO - moznetwork == 0.27 15:26:06 INFO - mozprocess == 0.22 15:26:06 INFO - mozprofile == 0.27 15:26:06 INFO - mozrunner == 6.11 15:26:06 INFO - mozscreenshot == 0.1 15:26:06 INFO - mozsystemmonitor == 0.0 15:26:06 INFO - moztest == 0.7 15:26:06 INFO - mozversion == 1.4 15:26:06 INFO - psutil == 3.1.1 15:26:06 INFO - requests == 1.2.3 15:26:06 INFO - wsgiref == 0.1.2 15:26:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:06 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 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:26:06 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 15:26:06 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 15:26:06 INFO - Using env: {'DISPLAY': ':0', 15:26:06 INFO - 'HOME': '/home/cltbld', 15:26:06 INFO - 'LANG': 'en_US.UTF-8', 15:26:06 INFO - 'LANGUAGE': 'en_US:en', 15:26:06 INFO - 'LOGNAME': 'cltbld', 15:26:06 INFO - 'MAIL': '/var/mail/cltbld', 15:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:06 INFO - 'MOZ_NO_REMOTE': '1', 15:26:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:06 INFO - 'NO_EM_RESTART': '1', 15:26:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:06 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:06 INFO - 'SHELL': '/bin/bash', 15:26:06 INFO - 'SHLVL': '1', 15:26:06 INFO - 'TERM': 'linux', 15:26:06 INFO - 'TMOUT': '86400', 15:26:06 INFO - 'USER': 'cltbld', 15:26:06 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:06 INFO - '_': '/tools/buildbot/bin/python'} 15:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 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)) 15:26:07 INFO - Cleaning up... 15:26:07 INFO - Return code: 0 15:26:07 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:26:07 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 15:26:07 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 15:26:07 INFO - Using env: {'DISPLAY': ':0', 15:26:07 INFO - 'HOME': '/home/cltbld', 15:26:07 INFO - 'LANG': 'en_US.UTF-8', 15:26:07 INFO - 'LANGUAGE': 'en_US:en', 15:26:07 INFO - 'LOGNAME': 'cltbld', 15:26:07 INFO - 'MAIL': '/var/mail/cltbld', 15:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:07 INFO - 'MOZ_NO_REMOTE': '1', 15:26:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:07 INFO - 'NO_EM_RESTART': '1', 15:26:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:07 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:07 INFO - 'SHELL': '/bin/bash', 15:26:07 INFO - 'SHLVL': '1', 15:26:07 INFO - 'TERM': 'linux', 15:26:07 INFO - 'TMOUT': '86400', 15:26:07 INFO - 'USER': 'cltbld', 15:26:07 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:07 INFO - '_': '/tools/buildbot/bin/python'} 15:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:07 INFO - Downloading/unpacking jsonschema 15:26:07 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 15:26:07 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 15:26:07 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 15:26:07 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 15:26:07 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 15:26:07 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 15:26:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:26:08 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 15:26:08 INFO - Downloading/unpacking functools32 (from jsonschema) 15:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:09 INFO - Downloading functools32-3.2.3-2.tar.gz 15:26:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 15:26:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:26:09 INFO - warning: no files found matching '*.txt' 15:26:09 INFO - no previously-included directories found matching 'build' 15:26:09 INFO - no previously-included directories found matching 'dist' 15:26:09 INFO - no previously-included directories found matching '.git*' 15:26:09 INFO - Installing collected packages: jsonschema, functools32 15:26:09 INFO - Running setup.py install for functools32 15:26:09 INFO - warning: no files found matching '*.txt' 15:26:09 INFO - no previously-included directories found matching 'build' 15:26:09 INFO - no previously-included directories found matching 'dist' 15:26:09 INFO - no previously-included directories found matching '.git*' 15:26:09 INFO - Successfully installed jsonschema functools32 15:26:09 INFO - Cleaning up... 15:26:09 INFO - Return code: 0 15:26:09 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:26: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')]} 15:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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')]} 15:26:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:26: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f50033c9f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1eaa600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', '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 15:26: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', 'flake8'] in /builds/slave/test-pgo/build 15:26: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 flake8 15:26:09 INFO - Using env: {'DISPLAY': ':0', 15:26:09 INFO - 'HOME': '/home/cltbld', 15:26:09 INFO - 'LANG': 'en_US.UTF-8', 15:26:09 INFO - 'LANGUAGE': 'en_US:en', 15:26:09 INFO - 'LOGNAME': 'cltbld', 15:26:09 INFO - 'MAIL': '/var/mail/cltbld', 15:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:09 INFO - 'MOZ_NO_REMOTE': '1', 15:26:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:09 INFO - 'NO_EM_RESTART': '1', 15:26:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:09 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:09 INFO - 'SHELL': '/bin/bash', 15:26:09 INFO - 'SHLVL': '1', 15:26:09 INFO - 'TERM': 'linux', 15:26:09 INFO - 'TMOUT': '86400', 15:26:09 INFO - 'USER': 'cltbld', 15:26:09 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:09 INFO - '_': '/tools/buildbot/bin/python'} 15:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:09 INFO - Downloading/unpacking flake8 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:26:11 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 15:26:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:26:11 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 15:26:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26:11 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 15:26:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26: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 15:26:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 15:26:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:26:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:26:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:26:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:26:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:26:12 INFO - no previously-included directories found matching 'docs/_build' 15:26:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:26:12 INFO - Running setup.py install for pep8 15:26:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:26:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:26:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:26:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:26:12 INFO - no previously-included directories found matching 'docs/_build' 15:26:12 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:26:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:26:12 INFO - Cleaning up... 15:26:12 INFO - Return code: 0 15:26:12 INFO - Running post-action listener: _resource_record_post_action 15:26:12 INFO - Running post-action listener: _start_resource_monitoring 15:26:12 INFO - Starting resource monitoring. 15:26:12 INFO - ##### 15:26:12 INFO - ##### Running install step. 15:26:12 INFO - ##### 15:26:12 INFO - Running pre-action listener: _resource_record_pre_action 15:26:12 INFO - Running main action method: install 15:26:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:26:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:26:12 INFO - Reading from file tmpfile_stdout 15:26:12 INFO - Detecting whether we're running mozinstall >=1.0... 15:26:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:26:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:26:12 INFO - Reading from file tmpfile_stdout 15:26:12 INFO - Output received: 15:26:12 INFO - Usage: mozinstall [options] installer 15:26:12 INFO - Options: 15:26:12 INFO - -h, --help show this help message and exit 15:26:12 INFO - -d DEST, --destination=DEST 15:26:12 INFO - Directory to install application into. [default: 15:26:12 INFO - "/builds/slave/test-pgo"] 15:26:12 INFO - --app=APP Application being installed. [default: firefox] 15:26:12 INFO - mkdir: /builds/slave/test-pgo/build/application 15:26:12 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'] 15:26:12 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 15:26:26 INFO - Reading from file tmpfile_stdout 15:26:26 INFO - Output received: 15:26:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:26:26 INFO - Running post-action listener: _resource_record_post_action 15:26:26 INFO - ##### 15:26:26 INFO - ##### Running run-tests step. 15:26:26 INFO - ##### 15:26:26 INFO - Running pre-action listener: _resource_record_pre_action 15:26:26 INFO - Running main action method: run_tests 15:26:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:26:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:26:26 INFO - Python 2.7.3 15:26:26 INFO - Return code: 0 15:26:26 INFO - grabbing minidump binary from tooltool 15:26:26 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')]} 15:26:26 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 0x1f39350>, '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 0x1f3a250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f3a6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:26:26 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 15:26:26 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 15:26:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:26:26 INFO - Return code: 0 15:26:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:26:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:26:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:26:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:26:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:26:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:26:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:26:27 INFO - Return code: 0 15:26:27 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/1452027927/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:26:27 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/1452027927/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 15:26:27 INFO - Using env: {'DISPLAY': ':0', 15:26:27 INFO - 'HOME': '/home/cltbld', 15:26:27 INFO - 'LANG': 'en_US.UTF-8', 15:26:27 INFO - 'LANGUAGE': 'en_US:en', 15:26:27 INFO - 'LOGNAME': 'cltbld', 15:26:27 INFO - 'MAIL': '/var/mail/cltbld', 15:26:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:26:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:27 INFO - 'MOZ_NO_REMOTE': '1', 15:26:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:26:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:27 INFO - 'NO_EM_RESTART': '1', 15:26:27 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:26:27 INFO - 'PWD': '/builds/slave/test-pgo', 15:26:27 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:26:27 INFO - 'SHELL': '/bin/bash', 15:26:27 INFO - 'SHLVL': '1', 15:26:27 INFO - 'TERM': 'linux', 15:26:27 INFO - 'TMOUT': '86400', 15:26:27 INFO - 'USER': 'cltbld', 15:26:27 INFO - 'XDG_SESSION_COOKIE': 'fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167', 15:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:27 INFO - '_': '/tools/buildbot/bin/python'} 15:26:27 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/1452027927/firefox-44.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 15:26:28 INFO - mozversion INFO | application_buildid: 20160105130527 15:26:28 INFO - mozversion INFO | application_changeset: aa03498e89618f7a9e0449862bd98de52520d9f9 15:26:28 INFO - mozversion INFO | application_display_name: Nightly 15:26:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:26:28 INFO - mozversion INFO | application_name: Firefox 15:26:28 INFO - mozversion INFO | application_remotingname: firefox 15:26:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:26:28 INFO - mozversion INFO | application_vendor: Mozilla 15:26:28 INFO - mozversion INFO | application_version: 44.0 15:26:28 INFO - mozversion INFO | platform_buildid: 20160105130527 15:26:28 INFO - mozversion INFO | platform_changeset: aa03498e89618f7a9e0449862bd98de52520d9f9 15:26:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:26:28 INFO - mozversion INFO | platform_version: 44.0 15:26:28 INFO - 2016-01-05 15:26:28,100 DEBUG : using testdate: 1452036388 15:26:28 INFO - 2016-01-05 15:26:28,100 DEBUG : actual date: 1452036388 15:26:28 INFO - 2016-01-05 15:26:28,111 INFO : starting webserver on 'localhost:49500' 15:26:28 INFO - 2016-01-05 15:26:28,112 INFO : Starting test suite talos-linux64-ix-014 15:26:28 INFO - 2016-01-05 15:26:28,112 INFO : Starting test dromaeo_css 15:26:28 INFO - 2016-01-05 15:26:28,112 DEBUG : operating with platform_type : linux_ 15:26:28 INFO - 2016-01-05 15:26:28,113 INFO : Initialising browser for dromaeo_css test... 15:26:28 INFO - 2016-01-05 15:26:28,127 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuVIp27/profile http://localhost:49500/getInfo.html 15:26:31 INFO - 2016-01-05 15:26:31,631 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:26:31 INFO - 2016-01-05 15:26:31,631 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:26:31 INFO - 2016-01-05 15:26:31,631 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:26:31 INFO - 2016-01-05 15:26:31,631 DEBUG : BROWSER_OUTPUT: __metrics 15:26:31 INFO - 2016-01-05 15:26:31,699 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] 15:26:32 INFO - 2016-01-05 15:26:32,905 INFO : Browser initialized. 15:26:32 INFO - 2016-01-05 15:26:32,906 INFO : Running cycle 1/1 for dromaeo_css test... 15:26:32 INFO - 2016-01-05 15:26:32,906 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuVIp27/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:26:44 INFO - 2016-01-05 15:26:44,496 DEBUG : BROWSER_OUTPUT: RSS: Main: 128442368 15:26:44 INFO - 2016-01-05 15:26:44,496 DEBUG : BROWSER_OUTPUT: 15:27:35 INFO - 2016-01-05 15:27:35,307 DEBUG : BROWSER_OUTPUT: 1452036455300 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] 15:29:43 INFO - 2016-01-05 15:29:43,461 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49500/tests/dromaeo/cssquery-ext.html) 15:29:44 INFO - 2016-01-05 15:29:44,494 DEBUG : BROWSER_OUTPUT: RSS: Main: 234561536 15:29:44 INFO - 2016-01-05 15:29:44,494 DEBUG : BROWSER_OUTPUT: 15:32:46 INFO - 2016-01-05 15:32:46,525 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/dromaeo/cssquery-ext.html (next: http://localhost:49500/tests/dromaeo/cssquery-jquery.html) 15:32:47 INFO - 2016-01-05 15:32:47,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 206278656 15:32:47 INFO - 2016-01-05 15:32:47,086 DEBUG : BROWSER_OUTPUT: 15:35:48 INFO - 2016-01-05 15:35:48,817 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49500/tests/dromaeo/cssquery-mootools.html) 15:35:49 INFO - 2016-01-05 15:35:49,379 DEBUG : BROWSER_OUTPUT: RSS: Main: 205611008 15:35:49 INFO - 2016-01-05 15:35:49,379 DEBUG : BROWSER_OUTPUT: 15:38:51 INFO - 2016-01-05 15:38:51,098 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49500/tests/dromaeo/cssquery-prototype.html) 15:38:51 INFO - 2016-01-05 15:38:51,665 DEBUG : BROWSER_OUTPUT: RSS: Main: 191123456 15:38:51 INFO - 2016-01-05 15:38:51,665 DEBUG : BROWSER_OUTPUT: 15:41:53 INFO - 2016-01-05 15:41:53,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49500/tests/dromaeo/cssquery-yui.html) 15:41:54 INFO - 2016-01-05 15:41:54,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 191975424 15:41:54 INFO - 2016-01-05 15:41:54,551 DEBUG : BROWSER_OUTPUT: 15:44:56 INFO - 2016-01-05 15:44:56,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/dromaeo/cssquery-yui.html (next: http://localhost:49500/tests/dromaeo/cssquery-dojo.html) 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: RSS: Main: 211972096 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:44:57 INFO - 2016-01-05 15:44:57,069 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3293.3084052349004;3396.6603339666035;3404.097914052778;3436.84534195961;3448.0000799768077;5597.244372009777;5610.523105536029;5611.915821262674;5619.0166720823845;5619.662820230784;5637.154426835977;5638.633488823224;5649.774009039634;5650.49145576898;5654.434556544354;4997.725125118117;5096.974515127419;5113.386393632769;5161.9741901290445;5222.138347172712;4959.70241785494;5090.872728181788;5157.329547158864;5170.638055336128;5181.974090129563;5034.647574669774;5143.562797162245;5157.432682404932;5166.767495462695;5169.276301317818;5145.974270128644;5167.302414174076;5237.083510385667;5240.633155679104;5258.842234732945;4962.280469331927;4968.950310496885;5036.043151801146;5064.139096353629;5136.794528218867;5412.864678383033;5424.620276580641;5436.565074794007;5440.91838622421;5454.481824226692;6053.909191362133;6054.4550990410635;6058.757649694007;6062.242219722535;6068.089786532011;5915.083162109837;5977.252843394575;6040.939590604082;6089.343648614924;6091.695415229221;5762.481376676077;5767.33675627304;5769.134629805562;5772.365039845614;5774.682392468455;6044.9395506044375;6288.622682639057;6304.558680892385;6310.1481300024825;6310.558260921692;5925.703714814242;5928.7332070056955;6047.0627052806885;6089.391060893964;6119.908201376983;6143.139960405547;6147.815565533041;6156.722947467375;6158.784442544489;6170.81487555374;5904.232449781501;6059.1820104286235;6120.051392034242;6148.4773794227995;6150.04674275488;6008.489278411384;6131.0412681606695;6131.675332581543;6132.661461321608;6135.729779981435;4668.836590719308;4670.602998745145;4676.95323046772;4684.250519916797;4698.389209402824;4070.7964601769795;4114.033202197481;4153.605407486284;4195.7692326922315;4213.494380674278;5688.089905615082;5742.59801813869;5765.279340082489;5776.913346299809;5794.971025144847;6091.7563297467605;6096.847578810565;6107.144999700045;6111.388861113853;6125;5595.944040559623;5598.440155984369;5696.943030569724;5698.629589076697;5776.220210271559;5290.709011004355;5312.62811603184;5313.654612450178;5314.5216930476445;5320.17537281722;6282.968585157045;6283.214598175228;6285.937140628626;6286.18279623646;6287.654179020106;5944.2569678791015;6041.848953776191;6066.393360663898;6142.539309551712;6152;6272.435480806749;6279.152314437492;6285.277193122849;6319;6325.810225693236;5863.061910094364;6027.035674292092;6033.577649564489;6034.306054803578;6041.154238406539;5918.053111502139;6014.8195554133445;6018.21763170785;6033.396660333931;6064.514838812973;5800.24596802413;5918.911216331845;5926.436988486087;5928.614640048383;5929.1995580597795;5720.08478643415;5809.651420914759;5901.852453688693;5910.1725758392995;5917.792877249276;5804.2744656917885;5930.199423077829;5942.168096466412;5946.43508866657;5947.583669143119;5983.850403739942;6005.039193728982;6016.217891674054;6038.154658347923;6041.335453100243;5607.102863541977;5717.828465146053;5729.828105156852;5873.4126587340925;5912.142739302863 15:44:57 INFO - 2016-01-05 15:44:57,071 DEBUG : BROWSER_OUTPUT: |1;ext.html;588.802751078389;631.198378059864;636.3604577399714;649.1982401733854;666.9966650166746;42692;43330.56669433301;43414;43424;43438.13123737524;46335.76832115843;46874;46905.76547117268;46914.06171876569;46960;8088.514689118642;8273.710420135296;9130.04134565871;9309;10651.573937042509;10782.245242833005;10856.891431085667;10881.782364352708;10895.727606809853;10903;10989.340639561611;11079.390633515153;11108.555657773679;11112.833307500354;11116.221864469491;10954.287971281881;11002.559897604166;11053.834192487118;11065.446727663584;11070.059044981186;10757.83863242052;10871.239013269074;10880.455977201109;10890.945545272301;10901.073408760265;43094.78452607717;43234;43358.78320608408;43420.565794341965;43482.565174348485;3525.876594319212;3623.565172179329;3677.669009789132;3917.138229589514;4186.455760751113;33626;33685.83157084223;33744.83127584371;33807.15482112967;34732;3834.9233015339537;3873.2447172801317;3876.108495046155;3886.222755448893;3935.5277366716177;333898;334475.32762336277;336472;336730;337610;59428.405715943154;60612;71759;72248.27751722415;72381.6380918092;70238.64880675667;70542.29457705357;70798;70803.6459817708;70835.64582177057;17403.129843507773;17525;17569.385071522436;17575.20911558983;17584;13633.386497607631;13677.384517696728;13715.657108572366;13953.232572208626;13964.301784910715;22384.55230895373;22556.66165007526;22590.774092259195;22636.886815566148;22713;8615.439996400213;8715.215630593277;8852.247558957431;8903.955480222687;8948.284137269133;1035.6323505155713;1042.358949246208;1058.2539309786737;1130.174972270231;1143.9247107718718;29646;29984;30095.69904300973;30385.696143038727;30498.695013049586;1187.1274613159321;1195.8368628849785;1230.993845030769;1232.9568465103857;1280.737448822989;14756.48352307664;15653.608659783597;15804.130772807606;16189.02865828054;17087.401940932006;465986;467424;470763;471314;471638;62300;62653.373466264755;62757.372426276066;62812.37187628157;62901.74196516044;1318.4264844792547;1366.5285476510587;1407.092425385641;1419.425132821209;1445.4796273341587;30558.84720576383;30559.694403055684;30643.387132256783;31345;31506.527402089447;46691.29963050625;47106.293405598255;47143;47150.76424617855;47174.29238561493;310988;312794;314205;314699;317719;35170.47244291389;35732.285354293286;36099;36163.0959226011;36254.637453625124;45971;46412.535874640824;47465.05069898652;47720;55164;39557;40560.79719601383;40817;40988;41164.38253426261;40073.79963100166;40122.19755604813;40481.39277910892;41025;41056.38415423831;40434.595654043085;41211;41232.79383603063;41638.791806040776;41640.167196656505;35735.82132089323;35798.8210058948;35853.936481197255;36516;36905.44641830429;27670;27883.302917427227;27948.30129246785;27961;28081.43837123287 15:44:57 INFO - 2016-01-05 15:44:57,071 DEBUG : BROWSER_OUTPUT: |2;jquery.html;395.34570286176387;396.35592162735554;397.82694527880363;398.41234179585115;400.67945643485206;17834.732479012808;18765.530861728465;19519.512012199702;19524;20028.499287517818;14424.062435941683;15549.378024879019;15839.287232074556;15866.12736299503;16127.838721612781;5580.553555715544;5599.468050535193;5681.403452637475;5691.743871525791;5695.231143795597;5609.859753506154;5673.6879471629045;5675.829725108253;5682.602217844753;5682.829515114553;5480.890382192374;5588.497035266825;5595.104783234683;5595.776168953237;5619.157126431027;5550.306211723535;5556.805511807088;5570.442955704438;5582.051051321265;5585.05054140793;4827.468978412372;4882;4883.536064073907;4897.681650692729;4930.704157750511;21945.561088778137;21961.23135690259;21970.89014554917;22263.220787272367;22364.55270894573;2251.110811229555;2263.456770375098;2263.7328635521994;2361.8700971446565;2389.3991026012613;14433.278336083154;14485.493007744679;14579.271036448241;14653.706925861423;14871.776923346159;1979.9208031678716;1992.2529051605648;2039.3677959832498;2050.1389416445127;2054.9486262843398;63606.3639363605;63750.68124659347;63909.68045159744;64830;65356.3464365355;10054.748631284276;10581.947090264499;11039.668809935714;11064.502097405637;11118.888811111947;14185.716285674434;14325.212113333866;14325.426982920775;14337.283135843167;14343.85656143446;7560.659770310349;7752;8059.395545334123;8398.370122240856;8498.830023399621;8213;8462.280706139923;8494.617742201615;8514.48913065218;8521;6449.6130232186215;6534.575252608565;6539.967300163564;6547.181602299713;6572.047053177263;1904.3715573860593;1904.7142928560827;1908.1699460734626;1910.1499832574648;1926.0947354743248;923.1829830599968;973.7273563402259;999.260547195085;1028.7633844215873;1099.9275706186563;12478.313692746986;13080.476780928846;13081.607551773272;13127.934360328138;18563.90718046401;555.6666000399728;564.8079652917945;568.366271607155;570.7945139749687;577.650521434526;6999.825004374931;7162;7285;7340.44946629005;7431.962840185764;543106;549241;570596;576212;577881;70629.64685176541;71175.64412177906;71809.2819071813;76818;80569;561.6994907724348;574.9568782341424;596.7881402102191;606.4329851588778;607.3622696169004;11582.884171158517;11584.94207528957;11815.881841181477;11924.880751192377;11943.343116128697;33821.3235735279;34207.43444241742;34645;34811.47782783311;35582.466263006594;43304.566954330054;43863;45054.54945450635;45418.09163816771;46079;7462.888056679263;7573.091229052549;7576.772696819105;7588.241175882367;7649.388048955959;12734.68163295888;13078;13157.868421315665;13396.598102056954;13415.262160581262;11602.129840262183;11697.356645384585;11742.412879356169;11841.171118021655;11848.407579620813;11215.383153926612;11505.137114716597;11700.70748231301;11738.70653233676;11799.17405781587;11598.652040438801;11647.184697071123;12061.216020958609;12265.448054837376;12363.196392234477;7642.541447513167;7649.88525172134;7716.498427602188;7717.652705628372;7724.884126738216;11695.298282103102;11746.941265293619;11951.342676152895;11973.281603103842;11976.341301228516 15:44:57 INFO - 2016-01-05 15:44:57,073 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1568.3491351089297;1618.90286582805;1619.044763589482;1763.7795275590552;1861.7393564900908;7060.646967651623;7238.963805180967;7926.1281259061525;8264.91735082649;8268.131846156157;7945.960270198655;8061.637226324815;8150.103488616262;8151.510909345443;8209.91790082099;2357.7288611809604;2386.3079706884982;2390.641403789437;2392.605220138675;2392.85642861428;2016.4757163137617;2064.6799746039387;2308.0998410619873;2323.500447403808;2365.656979737937;2276.726792784868;2277.3054218463362;2277.635578307471;2286.6112760830615;2308;2361.456864921069;2364.113457453455;2382.8212884033765;2388.6775285336516;2391.330427480308;2309.110992267972;2332.603457412244;2334.0780391745334;2335.346103091137;2338.1933233034574;7908.683652653887;7973.760787176394;7990.720324788663;8037.196280371974;8045.551800675876;4772.80908763649;4834;4835.032993401299;4851.490593487668;4852.053849499349;3262.673732626742;3280.3767284215924;3313.685199906005;3321.568196134487;3340.3820293245826;1087.5649740104022;1101.2236373356764;1111.9777604447947;1129.1362107987395;2115.312523429876;11483.770324593545;11619.360935148563;11624.244424112405;11635.709107272301;11645.941770291094;4424.734515929054;4525.411696479437;4589.357489951342;4592;4594.678372513892;9468.242540596735;9471.10024547667;9476.241900647932;9476.336656433983;9488.146066854017;5067.822626208139;5075.543201111917;5084.567811736044;5085.084684756779;5086.440491545927;4628.74541900192;4634.096351211549;4636.582707556337;4637.652176086807;4640.095181439654;3659.451082337671;3687.041369244016;3692.7230457715777;3693.2059607184583;3727.4781530585738;1049.286485190078;1054.6045233037612;1099.961501347449;1153.1293873125737;1162.5582278734028;1085.3704851186294;1136.5567428702811;1159.976800463986;1164.301419148504;1205.1443475132578;7959.124496305401;8043.356531477468;8119.675212991532;8137.715179968791;8226.177382261725;1112.9220954533105;1144.9255798373079;1196.1387800783423;1202.8255902894032;1223.3577371879835;6350.206224221973;6471.029345598198;6511.772087976898;6516.185476815398;6519;9975.102240798364;10159.390436573829;10164.237682173867;10167.949160254151;10183.439910804977;9852.16256618181;10058.647947321808;10102.747431314276;10105.44420056904;10107.646232381978;7529.2470752925365;7577.734779282809;7597.658105385271;7598.544087354777;7605.201453847377;7515.286047825666;7597.468177227541;7620.542767433972;7630.198829122862;7646.579438130733;8865.157810008039;8947.955260223658;8974.506402147945;8995.235404990388;9087;9068.319876009195;9145.954270228873;9157.404768690014;9171.266298695955;9294.674686385944;7083.291670832876;7188.101487314086;7190;7190.316919892602;7190.424766018601;7082.716691332289;7880.881786773318;7909.406794490321;7930.762077137695;8000.4399692021;6873.553219040747;7199.06412166415;7483.738069167553;7564.886526702214;7649.158592554703;7159.498835081495;7242.167150777728;7257.201707812242;7309.195988441374;7436.776896693108;7418.18399976013;7422.925770742223;7444.441666874898;7452.552846829208;7481;6802.387785099365;6805.319468053155;6811.761588344384;6856.382925536725;6903.827404314932;6910.274421185703;6952.991816186725;6954.547954382949;6964.338387853146;6966.965165174344 15:44:57 INFO - 2016-01-05 15:44:57,074 DEBUG : BROWSER_OUTPUT: |4;prototype.html;379.6393426245067;380.2205478768524;382.0544153220779;383.17425947084035;387.4865802811276;5743.597948143632;5831.446012628804;5834.649921004738;5842.737076831542;5854.853628659281;5687.17535957287;5789.82630521084;5800.709964501779;5816.214811000514;5822.854428639275;4565.4749703784155;4568.611668008223;4568.977155114219;4569.817207311704;4574.794134263958;4572.337011133383;4572.451305843287;4572.451305843303;4573.862784116465;4576;4424.535423780505;4520.796564154604;4527.411436513249;4549.408576885;4551.13528429599;4496.9325460118125;4506.053728716974;4509.368688383629;4509.842155524574;4526.185286648402;3871.4192871069563;4004.599540045972;4006.9198616027516;4008.378701301303;4018.357062869956;5405.540529055035;5414.458554144593;5498.780048798043;5508.338999320107;5512.531434828044;5218.321618189649;5230.712310822903;5234.476552344773;5234.816781412632;5236.162214045772;6139.754409823602;6141.048137538689;6141.815745527641;6146.078088286748;6146.35463276354;4351.543087975749;4408.074304396081;4417.558244175589;4436.179306828247;4439.822407103712;6423.454006409461;6500.512461565401;6505.186851643544;6510.772122975721;6513.902291465632;4526.569975852289;4577.771111444415;4580.564846339593;4581.633469322446;4586.243269860503;4635.397398338195;4644.628429725613;4653.302004699254;4653.371794807657;4654.488006319302;5219.217117432339;5278.577713782888;5292.92060619091;5293.682379057269;5297.788088476418;6217.564770466024;6257.937420625826;6258.843528911813;6261.154744109578;6263.342349053375;1663.4261179892917;1680.739485379768;1681.2686481380638;1682;1683.3014299065997;1131.5700033987198;1139.3562637110006;1142.8228624563205;1152.3086148310947;1166.166191173298;6115.266168059861;6128.080787881853;6129;6130.417610327012;6133.325334213233;6392.20097487814;6419.550631455847;6424.164858568356;6425.935740642627;6427.614343139427;5940.079287710412;6081.665508397081;6083.057126145454;6086;6087.726052327655;3505;3545.0960005199026;3545.1314427965312;3549.6982756465472;3549.8047607381327;6483.513736469689;6527.934720652827;6593.538452308292;6627.469802415795;6634.568753031038;6509.251436084816;6529.314421985623;6533.085368048477;6541.34586541342;6542.018697195459;6544.901826472512;6550.312217217219;6552.475801935833;6566.343365663395;6567.080608714784;6321.936780632135;6327.3988971047675;6384.233891932998;6418.935810641834;6438.163038804925;6343.492520598434;6347.90478142819;6359.90460143089;6366.808995730135;6371.840703982438;6622.337766223339;6625.569337993015;6625.972974189009;6635.8009259722285;6643.501737369813;4492.0342126441665;4575.679702420799;4576.542345765397;4611.123886461562;4704.694194877322;6371.044343348535;6373.330800265905;6382.840428989127;6383.553151279366;6384.872302554016;6171.043488259327;6178.598391104564;6201.10084037821;6201.131841542278;6213.161223234805;6064.666443345658;6186.752529898754;6191.690415479118;6194.318624951342;6198.473129784019;6137.35557766446;6142.754289828536;6152.876942461036;6177.166082578974;6183.474404675653;6265.373462653698;6270.028145637433;6293.465055470337;6313.589616675085;6317.494600432046;6031.4270144336215;6147.600405973598;6149.477294429845;6150.815475535741;6157.538184636081 15:44:57 INFO - 2016-01-05 15:44:57,075 DEBUG : BROWSER_OUTPUT: |5;yui.html;824.6618886256634;873.7466134820904;935.3359115028329;950.2018304624114;959.1128206409074;13574.592762217137;14143.85856141437;14163.150210987347;14176.149431034119;14178.149311041345;14439.855601443982;14469.421223151061;14479;14494;14559.126452412862;755.3617193471508;759.3469616130124;891.7681402835277;892.4957399069505;892.5314210039717;897.2866571076011;899.8335307968014;902.3187493442442;904.5658084119626;904.8733177355175;800.0958916424432;801.4870482890955;944.72130721437;949.5821838391121;951.5813042261383;929.7629104578323;942.3121121581285;943.8584212368131;944.5418971798672;944.7307517357589;693.4244577001077;701.0815831261065;702.785650376635;702.8734827731057;703.2545501768142;13658;13715.451381944711;13736.244506552135;13778.793318100237;13786.172829630254;13782.65543361414;13962.650933726636;14020.22888741119;14283.642908927257;14589.416423343053;10760.085392741626;10862.185336099825;10977.890221097767;11028.889711102947;11055.391953442557;3832.6550610444915;3886.0867696091395;3906.1211227473877;3924;3928.135810121769;51355.74322128403;51920.48079519194;53342.466575334525;53416.73291633556;53509;8443.746687599381;8469.534175620276;8701;8732.170443807829;11470.655880323604;12484.063695222894;12601.747965040648;12617.242965422103;12624.053196010153;12634.431450584745;6913.10129683148;6973.79078627642;6974.093367862247;7063.470239732041;7418.183999760022;4579.358889755437;4774.594159496412;4822.855314340575;4869.756512174377;4964.726940018334;2910.767138628905;2935.7944943854154;2944.219781757836;2946.071987323986;2946.3665311958034;458.2919389543097;462.76167773596626;466.1981392005745;467.47876118128045;475.4651017605194;557.8856334451427;581.9854503637443;599.9850003749941;622.439804176245;694.9270326615632;8434.32525397979;8752.44873161899;8950.731478055668;8994.550272486349;9118.772030699285;532.5659587436227;684.5310961991051;694.7533625562953;751.5828715063193;752.1951511882233;4083.7549747015273;4286.635635970915;4596.448426188878;4756.643251756063;4805.6155507559315;64558.35441645524;65344;65444;74396.62801685957;74580.62709686416;56935.71532142313;58030;58125.41874581284;61268.08097878535;61444.38555614387;57987;58868.4113158863;59122.704386477795;59417.10874336888;60483.39516604866;9607.471589062668;9672.177864881563;9706.9029309706;9720.708378748648;9794.162643301108;41796;44944.55055449404;45558;46024.539754603364;46615.30077048779;58133.70933145307;58229.70885145547;58287.125693115486;58852.41147588469;59559.404405955385;12700.110992230455;12840;12917.09580329368;13278.269695166859;13340.86659133435;13633.795493067413;13672.453101875813;13902.37439315213;14029;14049.859501405264;12598.685032873886;13305.933470332586;13545.390457429636;13841.515546955809;13946.860531394555;12921.676958076123;12990.596485635673;13155.210687358789;13313.600591982256;13409.128406653535;11997.880021200026;12086.274823510617;12790.61628151157;12829.230246184885;13013.349332533524;10811.783764324828;10975.451227438756;10997.560097596006;11051.447427628425;11060.944695276472;10704.89295107039;11062.017039825703;11151.553937842395;11181.552737890719;11187.10503159729 15:44:57 INFO - 2016-01-05 15:44:57,075 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:44:57 INFO - 2016-01-05 15:44:57,075 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:44:57 INFO - 2016-01-05 15:44:57,075 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,776 15:44:57 INFO - 2016-01-05 15:44:57,075 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:44:57 INFO - 2016-01-05 15:44:57,075 DEBUG : BROWSER_OUTPUT: __startTimestamp1452037497066__endTimestamp 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5634.74 Median:5863.06 stddev:634.49 (10.8%) stddev-sans-first:609.27 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: Values: 3293.3 3396.7 3404.1 3436.8 3448.0 5597.2 5610.5 5611.9 5619.0 5619.7 5637.2 5638.6 5649.8 5650.5 5654.4 4997.7 5097.0 5113.4 5162.0 5222.1 4959.7 5090.9 5157.3 5170.6 5182.0 5034.6 5143.6 5157.4 5166.8 5169.3 5146.0 5167.3 5237.1 5240.6 5258.8 4962.3 4969.0 5036.0 5064.1 5136.8 5412.9 5424.6 5436.6 5440.9 5454.5 6053.9 6054.5 6058.8 6062.2 6068.1 5915.1 5977.3 6040.9 6089.3 6091.7 5762.5 5767.3 5769.1 5772.4 5774.7 6044.9 6288.6 6304.6 6310.1 6310.6 5925.7 5928.7 6047.1 6089.4 6119.9 6143.1 6147.8 6156.7 6158.8 6170.8 5904.2 6059.2 6120.1 6148.5 6150.0 6008.5 6131.0 6131.7 6132.7 6135.7 4668.8 4670.6 4677.0 4684.3 4698.4 4070.8 4114.0 4153.6 4195.8 4213.5 5688.1 5742.6 5765.3 5776.9 5795.0 6091.8 6096.8 6107.1 6111.4 6125.0 5595.9 5598.4 5696.9 5698.6 5776.2 5290.7 5312.6 5313.7 5314.5 5320.2 6283.0 6283.2 6285.9 6286.2 6287.7 5944.3 6041.8 6066.4 6142.5 6152.0 6272.4 6279.2 6285.3 6319.0 6325.8 5863.1 6027.0 6033.6 6034.3 6041.2 5918.1 6014.8 6018.2 6033.4 6064.5 5800.2 5918.9 5926.4 5928.6 5929.2 5720.1 5809.7 5901.9 5910.2 5917.8 5804.3 5930.2 5942.2 5946.4 5947.6 5983.9 6005.0 6016.2 6038.2 6041.3 5607.1 5717.8 5729.8 5873.4 5912.1 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,076 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:55827.22 Median:35826.38 stddev:99850.24 (278.7%) stddev-sans-first:100044.22 15:44:57 INFO - 2016-01-05 15:44:57,077 DEBUG : BROWSER_OUTPUT: Values: 588.8 631.2 636.4 649.2 667.0 42692.0 43330.6 43414.0 43424.0 43438.1 46335.8 46874.0 46905.8 46914.1 46960.0 8088.5 8273.7 9130.0 9309.0 10651.6 10782.2 10856.9 10881.8 10895.7 10903.0 10989.3 11079.4 11108.6 11112.8 11116.2 10954.3 11002.6 11053.8 11065.4 11070.1 10757.8 10871.2 10880.5 10890.9 10901.1 43094.8 43234.0 43358.8 43420.6 43482.6 3525.9 3623.6 3677.7 3917.1 4186.5 33626.0 33685.8 33744.8 33807.2 34732.0 3834.9 3873.2 3876.1 3886.2 3935.5 333898.0 334475.3 336472.0 336730.0 337610.0 59428.4 60612.0 71759.0 72248.3 72381.6 70238.6 70542.3 70798.0 70803.6 70835.6 17403.1 17525.0 17569.4 17575.2 17584.0 13633.4 13677.4 13715.7 13953.2 13964.3 22384.6 22556.7 22590.8 22636.9 22713.0 8615.4 8715.2 8852.2 8904.0 8948.3 1035.6 1042.4 1058.3 1130.2 1143.9 29646.0 29984.0 30095.7 30385.7 30498.7 1187.1 1195.8 1231.0 1233.0 1280.7 14756.5 15653.6 15804.1 16189.0 17087.4 465986.0 467424.0 470763.0 471314.0 471638.0 62300.0 62653.4 62757.4 62812.4 62901.7 1318.4 1366.5 1407.1 1419.4 1445.5 30558.8 30559.7 30643.4 31345.0 31506.5 46691.3 47106.3 47143.0 47150.8 47174.3 310988.0 312794.0 314205.0 314699.0 317719.0 35170.5 35732.3 36099.0 36163.1 36254.6 45971.0 46412.5 47465.1 47720.0 55164.0 39557.0 40560.8 40817.0 40988.0 41164.4 40073.8 40122.2 40481.4 41025.0 41056.4 40434.6 41211.0 41232.8 41638.8 41640.2 35735.8 35798.8 35853.9 36516.0 36905.4 27670.0 27883.3 27948.3 27961.0 28081.4 15:44:57 INFO - 2016-01-05 15:44:57,077 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,077 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29044.19 Median:399.55 stddev:92049.78 (23038.6%) stddev-sans-first:92282.86 15:44:57 INFO - 2016-01-05 15:44:57,078 DEBUG : BROWSER_OUTPUT: Values: 395.3 396.4 397.8 398.4 400.7 17834.7 18765.5 19519.5 19524.0 20028.5 14424.1 15549.4 15839.3 15866.1 16127.8 5580.6 5599.5 5681.4 5691.7 5695.2 5609.9 5673.7 5675.8 5682.6 5682.8 5480.9 5588.5 5595.1 5595.8 5619.2 5550.3 5556.8 5570.4 5582.1 5585.1 4827.5 4882.0 4883.5 4897.7 4930.7 21945.6 21961.2 21970.9 22263.2 22364.6 2251.1 2263.5 2263.7 2361.9 2389.4 14433.3 14485.5 14579.3 14653.7 14871.8 1979.9 1992.3 2039.4 2050.1 2054.9 63606.4 63750.7 63909.7 64830.0 65356.3 10054.7 10581.9 11039.7 11064.5 11118.9 14185.7 14325.2 14325.4 14337.3 14343.9 7560.7 7752.0 8059.4 8398.4 8498.8 8213.0 8462.3 8494.6 8514.5 8521.0 6449.6 6534.6 6540.0 6547.2 6572.0 1904.4 1904.7 1908.2 1910.1 1926.1 923.2 973.7 999.3 1028.8 1099.9 12478.3 13080.5 13081.6 13127.9 18563.9 555.7 564.8 568.4 570.8 577.7 6999.8 7162.0 7285.0 7340.4 7432.0 543106.0 549241.0 570596.0 576212.0 577881.0 70629.6 71175.6 71809.3 76818.0 80569.0 561.7 575.0 596.8 606.4 607.4 11582.9 11584.9 11815.9 11924.9 11943.3 33821.3 34207.4 34645.0 34811.5 35582.5 43304.6 43863.0 45054.5 45418.1 46079.0 7462.9 7573.1 7576.8 7588.2 7649.4 12734.7 13078.0 13157.9 13396.6 13415.3 11602.1 11697.4 11742.4 11841.2 11848.4 11215.4 11505.1 11700.7 11738.7 11799.2 11598.7 11647.2 12061.2 12265.4 12363.2 7642.5 7649.9 7716.5 7717.7 7724.9 11695.3 11746.9 11951.3 11973.3 11976.3 15:44:57 INFO - 2016-01-05 15:44:57,078 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,078 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5774.82 Median:5084.83 stddev:3014.87 (59.3%) stddev-sans-first:3006.75 15:44:57 INFO - 2016-01-05 15:44:57,079 DEBUG : BROWSER_OUTPUT: Values: 1568.3 1618.9 1619.0 1763.8 1861.7 7060.6 7239.0 7926.1 8264.9 8268.1 7946.0 8061.6 8150.1 8151.5 8209.9 2357.7 2386.3 2390.6 2392.6 2392.9 2016.5 2064.7 2308.1 2323.5 2365.7 2276.7 2277.3 2277.6 2286.6 2308.0 2361.5 2364.1 2382.8 2388.7 2391.3 2309.1 2332.6 2334.1 2335.3 2338.2 7908.7 7973.8 7990.7 8037.2 8045.6 4772.8 4834.0 4835.0 4851.5 4852.1 3262.7 3280.4 3313.7 3321.6 3340.4 1087.6 1101.2 1112.0 1129.1 2115.3 11483.8 11619.4 11624.2 11635.7 11645.9 4424.7 4525.4 4589.4 4592.0 4594.7 9468.2 9471.1 9476.2 9476.3 9488.1 5067.8 5075.5 5084.6 5085.1 5086.4 4628.7 4634.1 4636.6 4637.7 4640.1 3659.5 3687.0 3692.7 3693.2 3727.5 1049.3 1054.6 1100.0 1153.1 1162.6 1085.4 1136.6 1160.0 1164.3 1205.1 7959.1 8043.4 8119.7 8137.7 8226.2 1112.9 1144.9 1196.1 1202.8 1223.4 6350.2 6471.0 6511.8 6516.2 6519.0 9975.1 10159.4 10164.2 10167.9 10183.4 9852.2 10058.6 10102.7 10105.4 10107.6 7529.2 7577.7 7597.7 7598.5 7605.2 7515.3 7597.5 7620.5 7630.2 7646.6 8865.2 8948.0 8974.5 8995.2 9087.0 9068.3 9146.0 9157.4 9171.3 9294.7 7083.3 7188.1 7190.0 7190.3 7190.4 7082.7 7880.9 7909.4 7930.8 8000.4 6873.6 7199.1 7483.7 7564.9 7649.2 7159.5 7242.2 7257.2 7309.2 7436.8 7418.2 7422.9 7444.4 7452.6 7481.0 6802.4 6805.3 6811.8 6856.4 6903.8 6910.3 6953.0 6954.5 6964.3 6967.0 15:44:57 INFO - 2016-01-05 15:44:57,079 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,079 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5236.89 Median:5985.75 stddev:1529.97 (25.6%) stddev-sans-first:1490.19 15:44:57 INFO - 2016-01-05 15:44:57,079 DEBUG : BROWSER_OUTPUT: Values: 379.6 380.2 382.1 383.2 387.5 5743.6 5831.4 5834.6 5842.7 5854.9 5687.2 5789.8 5800.7 5816.2 5822.9 4565.5 4568.6 4569.0 4569.8 4574.8 4572.3 4572.5 4572.5 4573.9 4576.0 4424.5 4520.8 4527.4 4549.4 4551.1 4496.9 4506.1 4509.4 4509.8 4526.2 3871.4 4004.6 4006.9 4008.4 4018.4 5405.5 5414.5 5498.8 5508.3 5512.5 5218.3 5230.7 5234.5 5234.8 5236.2 6139.8 6141.0 6141.8 6146.1 6146.4 4351.5 4408.1 4417.6 4436.2 4439.8 6423.5 6500.5 6505.2 6510.8 6513.9 4526.6 4577.8 4580.6 4581.6 4586.2 4635.4 4644.6 4653.3 4653.4 4654.5 5219.2 5278.6 5292.9 5293.7 5297.8 6217.6 6257.9 6258.8 6261.2 6263.3 1663.4 1680.7 1681.3 1682.0 1683.3 1131.6 1139.4 1142.8 1152.3 1166.2 6115.3 6128.1 6129.0 6130.4 6133.3 6392.2 6419.6 6424.2 6425.9 6427.6 5940.1 6081.7 6083.1 6086.0 6087.7 3505.0 3545.1 3545.1 3549.7 3549.8 6483.5 6527.9 6593.5 6627.5 6634.6 6509.3 6529.3 6533.1 6541.3 6542.0 6544.9 6550.3 6552.5 6566.3 6567.1 6321.9 6327.4 6384.2 6418.9 6438.2 6343.5 6347.9 6359.9 6366.8 6371.8 6622.3 6625.6 6626.0 6635.8 6643.5 4492.0 4575.7 4576.5 4611.1 4704.7 6371.0 6373.3 6382.8 6383.6 6384.9 6171.0 6178.6 6201.1 6201.1 6213.2 6064.7 6186.8 6191.7 6194.3 6198.5 6137.4 6142.8 6152.9 6177.2 6183.5 6265.4 6270.0 6293.5 6313.6 6317.5 6031.4 6147.6 6149.5 6150.8 6157.5 15:44:57 INFO - 2016-01-05 15:44:57,079 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,079 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15815.54 Median:4765.62 stddev:19473.99 (408.6%) stddev-sans-first:19496.09 15:44:57 INFO - 2016-01-05 15:44:57,080 DEBUG : BROWSER_OUTPUT: Values: 824.7 873.7 935.3 950.2 959.1 13574.6 14143.9 14163.2 14176.1 14178.1 14439.9 14469.4 14479.0 14494.0 14559.1 755.4 759.3 891.8 892.5 892.5 897.3 899.8 902.3 904.6 904.9 800.1 801.5 944.7 949.6 951.6 929.8 942.3 943.9 944.5 944.7 693.4 701.1 702.8 702.9 703.3 13658.0 13715.5 13736.2 13778.8 13786.2 13782.7 13962.7 14020.2 14283.6 14589.4 10760.1 10862.2 10977.9 11028.9 11055.4 3832.7 3886.1 3906.1 3924.0 3928.1 51355.7 51920.5 53342.5 53416.7 53509.0 8443.7 8469.5 8701.0 8732.2 11470.7 12484.1 12601.7 12617.2 12624.1 12634.4 6913.1 6973.8 6974.1 7063.5 7418.2 4579.4 4774.6 4822.9 4869.8 4964.7 2910.8 2935.8 2944.2 2946.1 2946.4 458.3 462.8 466.2 467.5 475.5 557.9 582.0 600.0 622.4 694.9 8434.3 8752.4 8950.7 8994.6 9118.8 532.6 684.5 694.8 751.6 752.2 4083.8 4286.6 4596.4 4756.6 4805.6 64558.4 65344.0 65444.0 74396.6 74580.6 56935.7 58030.0 58125.4 61268.1 61444.4 57987.0 58868.4 59122.7 59417.1 60483.4 9607.5 9672.2 9706.9 9720.7 9794.2 41796.0 44944.6 45558.0 46024.5 46615.3 58133.7 58229.7 58287.1 58852.4 59559.4 12700.1 12840.0 12917.1 13278.3 13340.9 13633.8 13672.5 13902.4 14029.0 14049.9 12598.7 13305.9 13545.4 13841.5 13946.9 12921.7 12990.6 13155.2 13313.6 13409.1 11997.9 12086.3 12790.6 12829.2 13013.3 10811.8 10975.5 10997.6 11051.4 11060.9 10704.9 11062.0 11151.6 11181.6 11187.1 15:44:57 INFO - 2016-01-05 15:44:57,080 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:44:57 INFO - 2016-01-05 15:44:57,080 DEBUG : BROWSER_OUTPUT: 15:44:57 INFO - 2016-01-05 15:44:57,626 INFO : Browser exited with error code: 0 15:44:57 INFO - 2016-01-05 15:44:57,642 INFO : Completed test dromaeo_css (00:18:29) 15:44:57 INFO - 2016-01-05 15:44:57,642 INFO : Starting test kraken 15:44:57 INFO - 2016-01-05 15:44:57,642 DEBUG : operating with platform_type : linux_ 15:44:57 INFO - 2016-01-05 15:44:57,642 INFO : Initialising browser for kraken test... 15:44:57 INFO - 2016-01-05 15:44:57,648 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLqtwrf/profile http://localhost:49500/getInfo.html 15:45:01 INFO - 2016-01-05 15:45:01,076 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:45:01 INFO - 2016-01-05 15:45:01,076 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:45:01 INFO - 2016-01-05 15:45:01,076 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:45:01 INFO - 2016-01-05 15:45:01,077 DEBUG : BROWSER_OUTPUT: __metrics 15:45:01 INFO - 2016-01-05 15:45:01,185 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] 15:45:02 INFO - 2016-01-05 15:45:02,409 INFO : Browser initialized. 15:45:02 INFO - 2016-01-05 15:45:02,410 INFO : Running cycle 1/1 for kraken test... 15:45:02 INFO - 2016-01-05 15:45:02,410 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLqtwrf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:45:13 INFO - 2016-01-05 15:45:13,869 DEBUG : BROWSER_OUTPUT: RSS: Main: 129187840 15:45:13 INFO - 2016-01-05 15:45:13,869 DEBUG : BROWSER_OUTPUT: 15:45:16 INFO - 2016-01-05 15:45:16,297 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49500/tests/kraken/driver.html?testName=audio-beat-detection) 15:45:16 INFO - 2016-01-05 15:45:16,863 DEBUG : BROWSER_OUTPUT: RSS: Main: 161050624 15:45:16 INFO - 2016-01-05 15:45:16,864 DEBUG : BROWSER_OUTPUT: 15:45:19 INFO - 2016-01-05 15:45:19,372 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49500/tests/kraken/driver.html?testName=audio-dft) 15:45:19 INFO - 2016-01-05 15:45:19,934 DEBUG : BROWSER_OUTPUT: RSS: Main: 161361920 15:45:19 INFO - 2016-01-05 15:45:19,934 DEBUG : BROWSER_OUTPUT: 15:45:22 INFO - 2016-01-05 15:45:22,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49500/tests/kraken/driver.html?testName=audio-fft) 15:45:23 INFO - 2016-01-05 15:45:23,314 DEBUG : BROWSER_OUTPUT: RSS: Main: 158486528 15:45:23 INFO - 2016-01-05 15:45:23,314 DEBUG : BROWSER_OUTPUT: 15:45:25 INFO - 2016-01-05 15:45:25,280 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49500/tests/kraken/driver.html?testName=audio-oscillator) 15:45:25 INFO - 2016-01-05 15:45:25,850 DEBUG : BROWSER_OUTPUT: RSS: Main: 157900800 15:45:25 INFO - 2016-01-05 15:45:25,851 DEBUG : BROWSER_OUTPUT: 15:45:27 INFO - 2016-01-05 15:45:27,964 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49500/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:45:28 INFO - 2016-01-05 15:45:28,517 DEBUG : BROWSER_OUTPUT: RSS: Main: 157421568 15:45:28 INFO - 2016-01-05 15:45:28,518 DEBUG : BROWSER_OUTPUT: 15:45:31 INFO - 2016-01-05 15:45:31,384 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49500/tests/kraken/driver.html?testName=imaging-darkroom) 15:45:31 INFO - 2016-01-05 15:45:31,951 DEBUG : BROWSER_OUTPUT: RSS: Main: 156950528 15:45:31 INFO - 2016-01-05 15:45:31,951 DEBUG : BROWSER_OUTPUT: 15:45:35 INFO - 2016-01-05 15:45:35,293 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49500/tests/kraken/driver.html?testName=imaging-desaturate) 15:45:35 INFO - 2016-01-05 15:45:35,859 DEBUG : BROWSER_OUTPUT: RSS: Main: 157614080 15:45:35 INFO - 2016-01-05 15:45:35,859 DEBUG : BROWSER_OUTPUT: 15:45:38 INFO - 2016-01-05 15:45:38,567 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49500/tests/kraken/driver.html?testName=json-parse-financial) 15:45:39 INFO - 2016-01-05 15:45:39,136 DEBUG : BROWSER_OUTPUT: RSS: Main: 155684864 15:45:39 INFO - 2016-01-05 15:45:39,136 DEBUG : BROWSER_OUTPUT: 15:45:40 INFO - 2016-01-05 15:45:40,637 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49500/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:45:41 INFO - 2016-01-05 15:45:41,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 163106816 15:45:41 INFO - 2016-01-05 15:45:41,191 DEBUG : BROWSER_OUTPUT: 15:45:42 INFO - 2016-01-05 15:45:42,934 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:45:43 INFO - 2016-01-05 15:45:43,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 161730560 15:45:43 INFO - 2016-01-05 15:45:43,529 DEBUG : BROWSER_OUTPUT: 15:45:45 INFO - 2016-01-05 15:45:45,473 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:45:46 INFO - 2016-01-05 15:45:46,039 DEBUG : BROWSER_OUTPUT: RSS: Main: 167153664 15:45:46 INFO - 2016-01-05 15:45:46,039 DEBUG : BROWSER_OUTPUT: 15:45:48 INFO - 2016-01-05 15:45:48,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:45:48 INFO - 2016-01-05 15:45:48,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 171274240 15:45:48 INFO - 2016-01-05 15:45:48,960 DEBUG : BROWSER_OUTPUT: 15:45:51 INFO - 2016-01-05 15:45:51,394 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:45:51 INFO - 2016-01-05 15:45:51,957 DEBUG : BROWSER_OUTPUT: RSS: Main: 167817216 15:45:51 INFO - 2016-01-05 15:45:51,957 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49500/tests/kraken/driver.html?testName=ai-astar) 15:45:53 INFO - 2016-01-05 15:45:53,803 DEBUG : BROWSER_OUTPUT: RSS: Main: 228827136 15:45:53 INFO - 2016-01-05 15:45:53,803 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,804 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:45:53 INFO - 2016-01-05 15:45:53,804 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:45:53 INFO - 2016-01-05 15:45:53,805 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:45:53 INFO - 2016-01-05 15:45:53,805 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:45:53 INFO - 2016-01-05 15:45:53,805 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;109;102;108;109;109;100;110;110;105 15:45:53 INFO - 2016-01-05 15:45:53,805 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;122;153;153;124;144;144;121;157;124 15:45:53 INFO - 2016-01-05 15:45:53,805 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;161;160;161;158;162;160;159 15:45:53 INFO - 2016-01-05 15:45:53,805 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;73;160;71;72;72;71;149;71 15:45:53 INFO - 2016-01-05 15:45:53,806 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;89;89;130;88;90;89;131;89;89;137 15:45:53 INFO - 2016-01-05 15:45:53,806 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 15:45:53 INFO - 2016-01-05 15:45:53,807 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;146;145;146;146;145;145;145 15:45:53 INFO - 2016-01-05 15:45:53,807 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;95;95;95;95;96;96 15:45:53 INFO - 2016-01-05 15:45:53,807 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;55;56;56;56;57;56;55;57;53 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;64;64;65;64;65;64;64;64;64 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;76;77;77;79;77;79;78;78;76;77 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;122;107;116;113;117;116;120;108;114 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;139;138;137;149;136;130;141;139;136 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;60;60;61;62;60;60;60;61;62;64 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:45:53 INFO - 2016-01-05 15:45:53,808 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:45:53 INFO - 2016-01-05 15:45:53,809 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,860 15:45:53 INFO - 2016-01-05 15:45:53,809 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:45:53 INFO - 2016-01-05 15:45:53,809 DEBUG : BROWSER_OUTPUT: __startTimestamp1452037553804__endTimestamp 15:45:53 INFO - 2016-01-05 15:45:53,809 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,813 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:45:53 INFO - 2016-01-05 15:45:53,813 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:45:53 INFO - 2016-01-05 15:45:53,813 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,813 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.90 Median:109.00 stddev:3.48 (3.2%) stddev-sans-first:3.69 15:45:53 INFO - 2016-01-05 15:45:53,813 DEBUG : BROWSER_OUTPUT: Values: 107.0 109.0 102.0 108.0 109.0 109.0 100.0 110.0 110.0 105.0 15:45:53 INFO - 2016-01-05 15:45:53,813 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,814 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.60 Median:148.50 stddev:15.09 (10.2%) stddev-sans-first:15.08 15:45:53 INFO - 2016-01-05 15:45:53,814 DEBUG : BROWSER_OUTPUT: Values: 154.0 122.0 153.0 153.0 124.0 144.0 144.0 121.0 157.0 124.0 15:45:53 INFO - 2016-01-05 15:45:53,814 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,814 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:1.15 (0.7%) stddev-sans-first:1.22 15:45:53 INFO - 2016-01-05 15:45:53,814 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 161.0 160.0 161.0 158.0 162.0 160.0 159.0 15:45:53 INFO - 2016-01-05 15:45:53,814 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,815 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.90 Median:71.00 stddev:35.21 (49.6%) stddev-sans-first:36.74 15:45:53 INFO - 2016-01-05 15:45:53,815 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 73.0 160.0 71.0 72.0 72.0 71.0 149.0 71.0 15:45:53 INFO - 2016-01-05 15:45:53,815 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,815 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.10 Median:89.00 stddev:21.17 (23.8%) stddev-sans-first:21.92 15:45:53 INFO - 2016-01-05 15:45:53,815 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 130.0 88.0 90.0 89.0 131.0 89.0 89.0 137.0 15:45:53 INFO - 2016-01-05 15:45:53,815 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 15:45:53 INFO - 2016-01-05 15:45:53,816 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,817 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 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: Values: 55.0 55.0 56.0 56.0 56.0 57.0 56.0 55.0 57.0 53.0 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:64.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 65.0 64.0 65.0 64.0 64.0 64.0 64.0 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:77.40 Median:77.50 stddev:1.07 (1.4%) stddev-sans-first:1.01 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: Values: 76.0 77.0 77.0 79.0 77.0 79.0 78.0 78.0 76.0 77.0 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,817 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.90 Median:116.50 stddev:5.88 (5.0%) stddev-sans-first:4.97 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: Values: 126.0 122.0 107.0 116.0 113.0 117.0 116.0 120.0 108.0 114.0 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.80 Median:139.00 stddev:4.98 (3.6%) stddev-sans-first:5.05 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: Values: 143.0 139.0 138.0 137.0 149.0 136.0 130.0 141.0 139.0 136.0 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:1.33 (2.2%) stddev-sans-first:1.36 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: Values: 60.0 60.0 61.0 62.0 60.0 60.0 60.0 61.0 62.0 64.0 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:45:53 INFO - 2016-01-05 15:45:53,818 DEBUG : BROWSER_OUTPUT: 15:45:54 INFO - 2016-01-05 15:45:54,502 INFO : Browser exited with error code: 0 15:45:54 INFO - 2016-01-05 15:45:54,552 INFO : Completed test kraken (00:00:56) 15:45:54 INFO - 2016-01-05 15:45:54,553 INFO : Starting test v8_7 15:45:54 INFO - 2016-01-05 15:45:54,553 DEBUG : operating with platform_type : linux_ 15:45:54 INFO - 2016-01-05 15:45:54,553 INFO : Initialising browser for v8_7 test... 15:45:54 INFO - 2016-01-05 15:45:54,559 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMVoY3A/profile http://localhost:49500/getInfo.html 15:45:58 INFO - 2016-01-05 15:45:58,036 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:45:58 INFO - 2016-01-05 15:45:58,036 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:45:58 INFO - 2016-01-05 15:45:58,036 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:45:58 INFO - 2016-01-05 15:45:58,036 DEBUG : BROWSER_OUTPUT: __metrics 15:45:58 INFO - 2016-01-05 15:45:58,132 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] 15:45:59 INFO - 2016-01-05 15:45:59,381 INFO : Browser initialized. 15:45:59 INFO - 2016-01-05 15:45:59,382 INFO : Running cycle 1/1 for v8_7 test... 15:45:59 INFO - 2016-01-05 15:45:59,383 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpMVoY3A/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:46:10 INFO - 2016-01-05 15:46:10,971 DEBUG : BROWSER_OUTPUT: RSS: Main: 129241088 15:46:10 INFO - 2016-01-05 15:46:10,972 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,294 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49500/tests/v8_7/run.html (next: http://localhost:49500/tests/v8_7/run.html) 15:46:32 INFO - 2016-01-05 15:46:32,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 417689600 15:46:32 INFO - 2016-01-05 15:46:32,545 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,546 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:46:32 INFO - 2016-01-05 15:46:32,546 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:46:32 INFO - 2016-01-05 15:46:32,546 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:46:32 INFO - 2016-01-05 15:46:32,546 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |0;Richards;161.4465611882467 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;170.32873445750297 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.72027972027973 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5015 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |4;RayTrace;951.4747859181732 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |5;Earley;561.4823133071309 15:46:32 INFO - 2016-01-05 15:46:32,547 DEBUG : BROWSER_OUTPUT: |6;Boyer;10589.473684210527 15:46:32 INFO - 2016-01-05 15:46:32,548 DEBUG : BROWSER_OUTPUT: |7;RegExp;23904.761904761905 15:46:32 INFO - 2016-01-05 15:46:32,548 DEBUG : BROWSER_OUTPUT: |8;Splay;533.9028296849973 15:46:32 INFO - 2016-01-05 15:46:32,548 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 15:46:32 INFO - 2016-01-05 15:46:32,548 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:46:32 INFO - 2016-01-05 15:46:32,548 DEBUG : BROWSER_OUTPUT: __startTimestamp1452037592545__endTimestamp 15:46:32 INFO - 2016-01-05 15:46:32,548 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,549 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:46:32 INFO - 2016-01-05 15:46:32,549 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:46:32 INFO - 2016-01-05 15:46:32,549 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,550 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:161.45 Median:161.45 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: Values: 161.4 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:170.33 Median:170.33 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: Values: 170.3 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.72 Median:279.72 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,551 DEBUG : BROWSER_OUTPUT: Values: 279.7 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5015.00 Median:5015.00 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: Values: 5015.0 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:951.47 Median:951.47 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: Values: 951.5 15:46:32 INFO - 2016-01-05 15:46:32,552 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:561.48 Median:561.48 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: Values: 561.5 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10589.47 Median:10589.47 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: Values: 10589.5 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23904.76 Median:23904.76 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: Values: 23904.8 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,553 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:533.90 Median:533.90 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,554 DEBUG : BROWSER_OUTPUT: Values: 533.9 15:46:32 INFO - 2016-01-05 15:46:32,554 DEBUG : BROWSER_OUTPUT: 15:46:32 INFO - 2016-01-05 15:46:32,554 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 15:46:32 INFO - 2016-01-05 15:46:32,555 DEBUG : BROWSER_OUTPUT: Values: 4822.1 15:46:32 INFO - 2016-01-05 15:46:32,555 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:46:32 INFO - 2016-01-05 15:46:32,555 DEBUG : BROWSER_OUTPUT: 15:46:33 INFO - 2016-01-05 15:46:33,106 INFO : Browser exited with error code: 0 15:46:33 INFO - 2016-01-05 15:46:33,120 INFO : Completed test v8_7 (00:00:38) 15:46:33 INFO - 2016-01-05 15:46:33,137 INFO : Completed test suite (00:20:05) 15:46:33 INFO - 2016-01-05 15:46:33,138 DEBUG : Working with test: dromaeo_css 15:46:33 INFO - 2016-01-05 15:46:33,138 DEBUG : Generating results file: dromaeo_css 15:46:33 INFO - 2016-01-05 15:46:33,139 DEBUG : Working with test: kraken 15:46:33 INFO - 2016-01-05 15:46:33,139 DEBUG : Generating results file: kraken 15:46:33 INFO - 2016-01-05 15:46:33,139 INFO : javascript benchmark 15:46:33 INFO - 2016-01-05 15:46:33,139 DEBUG : Working with test: v8_7 15:46:33 INFO - 2016-01-05 15:46:33,140 DEBUG : Generating results file: v8_7 15:46:33 INFO - 2016-01-05 15:46:33,140 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:46:33 INFO - 2016-01-05 15:46:33,196 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:46:33 INFO - 2016-01-05 15:46:33,196 DEBUG : process_Request line: dromaeo_css 7314.62 graph.html#tests=[[72,53,35]] 15:46:33 INFO - 2016-01-05 15:46:33,196 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:46:33 INFO - 2016-01-05 15:46:33,229 DEBUG : process_Request line: kraken 1459.20 graph.html#tests=[[232,53,35]] 15:46:33 INFO - 2016-01-05 15:46:33,229 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:46:33 INFO - 2016-01-05 15:46:33,343 DEBUG : process_Request line: v8_7 23256.77 graph.html#tests=[[230,53,35]] 15:46:33 INFO - 2016-01-05 15:46:33,344 INFO : javascript benchmark 15:46:33 INFO - 2016-01-05 15:46:33,348 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3293.3084052349004, 3396.6603339666035, 3404.097914052778, 3436.84534195961, 3448.0000799768077, 5597.244372009777, 5610.523105536029, 5611.915821262674, 5619.0166720823845, 5619.662820230784, 5637.154426835977, 5638.633488823224, 5649.774009039634, 5650.49145576898, 5654.434556544354, 4997.725125118117, 5096.974515127419, 5113.386393632769, 5161.9741901290445, 5222.138347172712, 4959.70241785494, 5090.872728181788, 5157.329547158864, 5170.638055336128, 5181.974090129563, 5034.647574669774, 5143.562797162245, 5157.432682404932, 5166.767495462695, 5169.276301317818, 5145.974270128644, 5167.302414174076, 5237.083510385667, 5240.633155679104, 5258.842234732945, 4962.280469331927, 4968.950310496885, 5036.043151801146, 5064.139096353629, 5136.794528218867, 5412.864678383033, 5424.620276580641, 5436.565074794007, 5440.91838622421, 5454.481824226692, 6053.909191362133, 6054.4550990410635, 6058.757649694007, 6062.242219722535, 6068.089786532011, 5915.083162109837, 5977.252843394575, 6040.939590604082, 6089.343648614924, 6091.695415229221, 5762.481376676077, 5767.33675627304, 5769.134629805562, 5772.365039845614, 5774.682392468455, 6044.9395506044375, 6288.622682639057, 6304.558680892385, 6310.1481300024825, 6310.558260921692, 5925.703714814242, 5928.7332070056955, 6047.0627052806885, 6089.391060893964, 6119.908201376983, 6143.139960405547, 6147.815565533041, 6156.722947467375, 6158.784442544489, 6170.81487555374, 5904.232449781501, 6059.1820104286235, 6120.051392034242, 6148.4773794227995, 6150.04674275488, 6008.489278411384, 6131.0412681606695, 6131.675332581543, 6132.661461321608, 6135.729779981435, 4668.836590719308, 4670.602998745145, 4676.95323046772, 4684.250519916797, 4698.389209402824, 4070.7964601769795, 4114.033202197481, 4153.605407486284, 4195.7692326922315, 4213.494380674278, 5688.089905615082, 5742.59801813869, 5765.279340082489, 5776.913346299809, 5794.971025144847, 6091.7563297467605, 6096.847578810565, 6107.144999700045, 6111.388861113853, 6125.0, 5595.944040559623, 5598.440155984369, 5696.943030569724, 5698.629589076697, 5776.220210271559, 5290.709011004355, 5312.62811603184, 5313.654612450178, 5314.5216930476445, 5320.17537281722, 6282.968585157045, 6283.214598175228, 6285.937140628626, 6286.18279623646, 6287.654179020106, 5944.2569678791015, 6041.848953776191, 6066.393360663898, 6142.539309551712, 6152.0, 6272.435480806749, 6279.152314437492, 6285.277193122849, 6319.0, 6325.810225693236, 5863.061910094364, 6027.035674292092, 6033.577649564489, 6034.306054803578, 6041.154238406539, 5918.053111502139, 6014.8195554133445, 6018.21763170785, 6033.396660333931, 6064.514838812973, 5800.24596802413, 5918.911216331845, 5926.436988486087, 5928.614640048383, 5929.1995580597795, 5720.08478643415, 5809.651420914759, 5901.852453688693, 5910.1725758392995, 5917.792877249276, 5804.2744656917885, 5930.199423077829, 5942.168096466412, 5946.43508866657, 5947.583669143119, 5983.850403739942, 6005.039193728982, 6016.217891674054, 6038.154658347923, 6041.335453100243, 5607.102863541977, 5717.828465146053, 5729.828105156852, 5873.4126587340925, 5912.142739302863], "unit": "ms", "name": "dojo.html", "value": 5593.169966757374}, {"lowerIsBetter": false, "replicates": [588.802751078389, 631.198378059864, 636.3604577399714, 649.1982401733854, 666.9966650166746, 42692.0, 43330.56669433301, 43414.0, 43424.0, 43438.13123737524, 46335.76832115843, 46874.0, 46905.76547117268, 46914.06171876569, 46960.0, 8088.514689118642, 8273.710420135296, 9130.04134565871, 9309.0, 10651.573937042509, 10782.245242833005, 10856.891431085667, 10881.782364352708, 10895.727606809853, 10903.0, 10989.340639561611, 11079.390633515153, 11108.555657773679, 11112.833307500354, 11116.221864469491, 10954.287971281881, 11002.559897604166, 11053.834192487118, 11065.446727663584, 11070.059044981186, 10757.83863242052, 10871.239013269074, 10880.455977201109, 10890.945545272301, 10901.073408760265, 43094.78452607717, 43234.0, 43358.78320608408, 43420.565794341965, 43482.565174348485, 3525.876594319212, 3623.565172179329, 3677.669009789132, 3917.138229589514, 4186.455760751113, 33626.0, 33685.83157084223, 33744.83127584371, 33807.15482112967, 34732.0, 3834.9233015339537, 3873.2447172801317, 3876.108495046155, 3886.222755448893, 3935.5277366716177, 333898.0, 334475.32762336277, 336472.0, 336730.0, 337610.0, 59428.405715943154, 60612.0, 71759.0, 72248.27751722415, 72381.6380918092, 70238.64880675667, 70542.29457705357, 70798.0, 70803.6459817708, 70835.64582177057, 17403.129843507773, 17525.0, 17569.385071522436, 17575.20911558983, 17584.0, 13633.386497607631, 13677.384517696728, 13715.657108572366, 13953.232572208626, 13964.301784910715, 22384.55230895373, 22556.66165007526, 22590.774092259195, 22636.886815566148, 22713.0, 8615.439996400213, 8715.215630593277, 8852.247558957431, 8903.955480222687, 8948.284137269133, 1035.6323505155713, 1042.358949246208, 1058.2539309786737, 1130.174972270231, 1143.9247107718718, 29646.0, 29984.0, 30095.69904300973, 30385.696143038727, 30498.695013049586, 1187.1274613159321, 1195.8368628849785, 1230.993845030769, 1232.9568465103857, 1280.737448822989, 14756.48352307664, 15653.608659783597, 15804.130772807606, 16189.02865828054, 17087.401940932006, 465986.0, 467424.0, 470763.0, 471314.0, 471638.0, 62300.0, 62653.373466264755, 62757.372426276066, 62812.37187628157, 62901.74196516044, 1318.4264844792547, 1366.5285476510587, 1407.092425385641, 1419.425132821209, 1445.4796273341587, 30558.84720576383, 30559.694403055684, 30643.387132256783, 31345.0, 31506.527402089447, 46691.29963050625, 47106.293405598255, 47143.0, 47150.76424617855, 47174.29238561493, 310988.0, 312794.0, 314205.0, 314699.0, 317719.0, 35170.47244291389, 35732.285354293286, 36099.0, 36163.0959226011, 36254.637453625124, 45971.0, 46412.535874640824, 47465.05069898652, 47720.0, 55164.0, 39557.0, 40560.79719601383, 40817.0, 40988.0, 41164.38253426261, 40073.79963100166, 40122.19755604813, 40481.39277910892, 41025.0, 41056.38415423831, 40434.595654043085, 41211.0, 41232.79383603063, 41638.791806040776, 41640.167196656505, 35735.82132089323, 35798.8210058948, 35853.936481197255, 36516.0, 36905.44641830429, 27670.0, 27883.302917427227, 27948.30129246785, 27961.0, 28081.43837123287], "unit": "ms", "name": "ext.html", "value": 20999.07656716265}, {"lowerIsBetter": false, "replicates": [395.34570286176387, 396.35592162735554, 397.82694527880363, 398.41234179585115, 400.67945643485206, 17834.732479012808, 18765.530861728465, 19519.512012199702, 19524.0, 20028.499287517818, 14424.062435941683, 15549.378024879019, 15839.287232074556, 15866.12736299503, 16127.838721612781, 5580.553555715544, 5599.468050535193, 5681.403452637475, 5691.743871525791, 5695.231143795597, 5609.859753506154, 5673.6879471629045, 5675.829725108253, 5682.602217844753, 5682.829515114553, 5480.890382192374, 5588.497035266825, 5595.104783234683, 5595.776168953237, 5619.157126431027, 5550.306211723535, 5556.805511807088, 5570.442955704438, 5582.051051321265, 5585.05054140793, 4827.468978412372, 4882.0, 4883.536064073907, 4897.681650692729, 4930.704157750511, 21945.561088778137, 21961.23135690259, 21970.89014554917, 22263.220787272367, 22364.55270894573, 2251.110811229555, 2263.456770375098, 2263.7328635521994, 2361.8700971446565, 2389.3991026012613, 14433.278336083154, 14485.493007744679, 14579.271036448241, 14653.706925861423, 14871.776923346159, 1979.9208031678716, 1992.2529051605648, 2039.3677959832498, 2050.1389416445127, 2054.9486262843398, 63606.3639363605, 63750.68124659347, 63909.68045159744, 64830.0, 65356.3464365355, 10054.748631284276, 10581.947090264499, 11039.668809935714, 11064.502097405637, 11118.888811111947, 14185.716285674434, 14325.212113333866, 14325.426982920775, 14337.283135843167, 14343.85656143446, 7560.659770310349, 7752.0, 8059.395545334123, 8398.370122240856, 8498.830023399621, 8213.0, 8462.280706139923, 8494.617742201615, 8514.48913065218, 8521.0, 6449.6130232186215, 6534.575252608565, 6539.967300163564, 6547.181602299713, 6572.047053177263, 1904.3715573860593, 1904.7142928560827, 1908.1699460734626, 1910.1499832574648, 1926.0947354743248, 923.1829830599968, 973.7273563402259, 999.260547195085, 1028.7633844215873, 1099.9275706186563, 12478.313692746986, 13080.476780928846, 13081.607551773272, 13127.934360328138, 18563.90718046401, 555.6666000399728, 564.8079652917945, 568.366271607155, 570.7945139749687, 577.650521434526, 6999.825004374931, 7162.0, 7285.0, 7340.44946629005, 7431.962840185764, 543106.0, 549241.0, 570596.0, 576212.0, 577881.0, 70629.64685176541, 71175.64412177906, 71809.2819071813, 76818.0, 80569.0, 561.6994907724348, 574.9568782341424, 596.7881402102191, 606.4329851588778, 607.3622696169004, 11582.884171158517, 11584.94207528957, 11815.881841181477, 11924.880751192377, 11943.343116128697, 33821.3235735279, 34207.43444241742, 34645.0, 34811.47782783311, 35582.466263006594, 43304.566954330054, 43863.0, 45054.54945450635, 45418.09163816771, 46079.0, 7462.888056679263, 7573.091229052549, 7576.772696819105, 7588.241175882367, 7649.388048955959, 12734.68163295888, 13078.0, 13157.868421315665, 13396.598102056954, 13415.262160581262, 11602.129840262183, 11697.356645384585, 11742.412879356169, 11841.171118021655, 11848.407579620813, 11215.383153926612, 11505.137114716597, 11700.70748231301, 11738.70653233676, 11799.17405781587, 11598.652040438801, 11647.184697071123, 12061.216020958609, 12265.448054837376, 12363.196392234477, 7642.541447513167, 7649.88525172134, 7716.498427602188, 7717.652705628372, 7724.884126738216, 11695.298282103102, 11746.941265293619, 11951.342676152895, 11973.281603103842, 11976.341301228516], "unit": "ms", "name": "jquery.html", "value": 8496.541744875318}, {"lowerIsBetter": false, "replicates": [1568.3491351089297, 1618.90286582805, 1619.044763589482, 1763.7795275590552, 1861.7393564900908, 7060.646967651623, 7238.963805180967, 7926.1281259061525, 8264.91735082649, 8268.131846156157, 7945.960270198655, 8061.637226324815, 8150.103488616262, 8151.510909345443, 8209.91790082099, 2357.7288611809604, 2386.3079706884982, 2390.641403789437, 2392.605220138675, 2392.85642861428, 2016.4757163137617, 2064.6799746039387, 2308.0998410619873, 2323.500447403808, 2365.656979737937, 2276.726792784868, 2277.3054218463362, 2277.635578307471, 2286.6112760830615, 2308.0, 2361.456864921069, 2364.113457453455, 2382.8212884033765, 2388.6775285336516, 2391.330427480308, 2309.110992267972, 2332.603457412244, 2334.0780391745334, 2335.346103091137, 2338.1933233034574, 7908.683652653887, 7973.760787176394, 7990.720324788663, 8037.196280371974, 8045.551800675876, 4772.80908763649, 4834.0, 4835.032993401299, 4851.490593487668, 4852.053849499349, 3262.673732626742, 3280.3767284215924, 3313.685199906005, 3321.568196134487, 3340.3820293245826, 1087.5649740104022, 1101.2236373356764, 1111.9777604447947, 1129.1362107987395, 2115.312523429876, 11483.770324593545, 11619.360935148563, 11624.244424112405, 11635.709107272301, 11645.941770291094, 4424.734515929054, 4525.411696479437, 4589.357489951342, 4592.0, 4594.678372513892, 9468.242540596735, 9471.10024547667, 9476.241900647932, 9476.336656433983, 9488.146066854017, 5067.822626208139, 5075.543201111917, 5084.567811736044, 5085.084684756779, 5086.440491545927, 4628.74541900192, 4634.096351211549, 4636.582707556337, 4637.652176086807, 4640.095181439654, 3659.451082337671, 3687.041369244016, 3692.7230457715777, 3693.2059607184583, 3727.4781530585738, 1049.286485190078, 1054.6045233037612, 1099.961501347449, 1153.1293873125737, 1162.5582278734028, 1085.3704851186294, 1136.5567428702811, 1159.976800463986, 1164.301419148504, 1205.1443475132578, 7959.124496305401, 8043.356531477468, 8119.675212991532, 8137.715179968791, 8226.177382261725, 1112.9220954533105, 1144.9255798373079, 1196.1387800783423, 1202.8255902894032, 1223.3577371879835, 6350.206224221973, 6471.029345598198, 6511.772087976898, 6516.185476815398, 6519.0, 9975.102240798364, 10159.390436573829, 10164.237682173867, 10167.949160254151, 10183.439910804977, 9852.16256618181, 10058.647947321808, 10102.747431314276, 10105.44420056904, 10107.646232381978, 7529.2470752925365, 7577.734779282809, 7597.658105385271, 7598.544087354777, 7605.201453847377, 7515.286047825666, 7597.468177227541, 7620.542767433972, 7630.198829122862, 7646.579438130733, 8865.157810008039, 8947.955260223658, 8974.506402147945, 8995.235404990388, 9087.0, 9068.319876009195, 9145.954270228873, 9157.404768690014, 9171.266298695955, 9294.674686385944, 7083.291670832876, 7188.101487314086, 7190.0, 7190.316919892602, 7190.424766018601, 7082.716691332289, 7880.881786773318, 7909.406794490321, 7930.762077137695, 8000.4399692021, 6873.553219040747, 7199.06412166415, 7483.738069167553, 7564.886526702214, 7649.158592554703, 7159.498835081495, 7242.167150777728, 7257.201707812242, 7309.195988441374, 7436.776896693108, 7418.18399976013, 7422.925770742223, 7444.441666874898, 7452.552846829208, 7481.0, 6802.387785099365, 6805.319468053155, 6811.761588344384, 6856.382925536725, 6903.827404314932, 6910.274421185703, 6952.991816186725, 6954.547954382949, 6964.338387853146, 6966.965165174344], "unit": "ms", "name": "mootools.html", "value": 4734.978214389121}, {"lowerIsBetter": false, "replicates": [379.6393426245067, 380.2205478768524, 382.0544153220779, 383.17425947084035, 387.4865802811276, 5743.597948143632, 5831.446012628804, 5834.649921004738, 5842.737076831542, 5854.853628659281, 5687.17535957287, 5789.82630521084, 5800.709964501779, 5816.214811000514, 5822.854428639275, 4565.4749703784155, 4568.611668008223, 4568.977155114219, 4569.817207311704, 4574.794134263958, 4572.337011133383, 4572.451305843287, 4572.451305843303, 4573.862784116465, 4576.0, 4424.535423780505, 4520.796564154604, 4527.411436513249, 4549.408576885, 4551.13528429599, 4496.9325460118125, 4506.053728716974, 4509.368688383629, 4509.842155524574, 4526.185286648402, 3871.4192871069563, 4004.599540045972, 4006.9198616027516, 4008.378701301303, 4018.357062869956, 5405.540529055035, 5414.458554144593, 5498.780048798043, 5508.338999320107, 5512.531434828044, 5218.321618189649, 5230.712310822903, 5234.476552344773, 5234.816781412632, 5236.162214045772, 6139.754409823602, 6141.048137538689, 6141.815745527641, 6146.078088286748, 6146.35463276354, 4351.543087975749, 4408.074304396081, 4417.558244175589, 4436.179306828247, 4439.822407103712, 6423.454006409461, 6500.512461565401, 6505.186851643544, 6510.772122975721, 6513.902291465632, 4526.569975852289, 4577.771111444415, 4580.564846339593, 4581.633469322446, 4586.243269860503, 4635.397398338195, 4644.628429725613, 4653.302004699254, 4653.371794807657, 4654.488006319302, 5219.217117432339, 5278.577713782888, 5292.92060619091, 5293.682379057269, 5297.788088476418, 6217.564770466024, 6257.937420625826, 6258.843528911813, 6261.154744109578, 6263.342349053375, 1663.4261179892917, 1680.739485379768, 1681.2686481380638, 1682.0, 1683.3014299065997, 1131.5700033987198, 1139.3562637110006, 1142.8228624563205, 1152.3086148310947, 1166.166191173298, 6115.266168059861, 6128.080787881853, 6129.0, 6130.417610327012, 6133.325334213233, 6392.20097487814, 6419.550631455847, 6424.164858568356, 6425.935740642627, 6427.614343139427, 5940.079287710412, 6081.665508397081, 6083.057126145454, 6086.0, 6087.726052327655, 3505.0, 3545.0960005199026, 3545.1314427965312, 3549.6982756465472, 3549.8047607381327, 6483.513736469689, 6527.934720652827, 6593.538452308292, 6627.469802415795, 6634.568753031038, 6509.251436084816, 6529.314421985623, 6533.085368048477, 6541.34586541342, 6542.018697195459, 6544.901826472512, 6550.312217217219, 6552.475801935833, 6566.343365663395, 6567.080608714784, 6321.936780632135, 6327.3988971047675, 6384.233891932998, 6418.935810641834, 6438.163038804925, 6343.492520598434, 6347.90478142819, 6359.90460143089, 6366.808995730135, 6371.840703982438, 6622.337766223339, 6625.569337993015, 6625.972974189009, 6635.8009259722285, 6643.501737369813, 4492.0342126441665, 4575.679702420799, 4576.542345765397, 4611.123886461562, 4704.694194877322, 6371.044343348535, 6373.330800265905, 6382.840428989127, 6383.553151279366, 6384.872302554016, 6171.043488259327, 6178.598391104564, 6201.10084037821, 6201.131841542278, 6213.161223234805, 6064.666443345658, 6186.752529898754, 6191.690415479118, 6194.318624951342, 6198.473129784019, 6137.35557766446, 6142.754289828536, 6152.876942461036, 6177.166082578974, 6183.474404675653, 6265.373462653698, 6270.028145637433, 6293.465055470337, 6313.589616675085, 6317.494600432046, 6031.4270144336215, 6147.600405973598, 6149.477294429845, 6150.815475535741, 6157.538184636081], "unit": "ms", "name": "prototype.html", "value": 4761.966467111633}, {"lowerIsBetter": false, "replicates": [824.6618886256634, 873.7466134820904, 935.3359115028329, 950.2018304624114, 959.1128206409074, 13574.592762217137, 14143.85856141437, 14163.150210987347, 14176.149431034119, 14178.149311041345, 14439.855601443982, 14469.421223151061, 14479.0, 14494.0, 14559.126452412862, 755.3617193471508, 759.3469616130124, 891.7681402835277, 892.4957399069505, 892.5314210039717, 897.2866571076011, 899.8335307968014, 902.3187493442442, 904.5658084119626, 904.8733177355175, 800.0958916424432, 801.4870482890955, 944.72130721437, 949.5821838391121, 951.5813042261383, 929.7629104578323, 942.3121121581285, 943.8584212368131, 944.5418971798672, 944.7307517357589, 693.4244577001077, 701.0815831261065, 702.785650376635, 702.8734827731057, 703.2545501768142, 13658.0, 13715.451381944711, 13736.244506552135, 13778.793318100237, 13786.172829630254, 13782.65543361414, 13962.650933726636, 14020.22888741119, 14283.642908927257, 14589.416423343053, 10760.085392741626, 10862.185336099825, 10977.890221097767, 11028.889711102947, 11055.391953442557, 3832.6550610444915, 3886.0867696091395, 3906.1211227473877, 3924.0, 3928.135810121769, 51355.74322128403, 51920.48079519194, 53342.466575334525, 53416.73291633556, 53509.0, 8443.746687599381, 8469.534175620276, 8701.0, 8732.170443807829, 11470.655880323604, 12484.063695222894, 12601.747965040648, 12617.242965422103, 12624.053196010153, 12634.431450584745, 6913.10129683148, 6973.79078627642, 6974.093367862247, 7063.470239732041, 7418.183999760022, 4579.358889755437, 4774.594159496412, 4822.855314340575, 4869.756512174377, 4964.726940018334, 2910.767138628905, 2935.7944943854154, 2944.219781757836, 2946.071987323986, 2946.3665311958034, 458.2919389543097, 462.76167773596626, 466.1981392005745, 467.47876118128045, 475.4651017605194, 557.8856334451427, 581.9854503637443, 599.9850003749941, 622.439804176245, 694.9270326615632, 8434.32525397979, 8752.44873161899, 8950.731478055668, 8994.550272486349, 9118.772030699285, 532.5659587436227, 684.5310961991051, 694.7533625562953, 751.5828715063193, 752.1951511882233, 4083.7549747015273, 4286.635635970915, 4596.448426188878, 4756.643251756063, 4805.6155507559315, 64558.35441645524, 65344.0, 65444.0, 74396.62801685957, 74580.62709686416, 56935.71532142313, 58030.0, 58125.41874581284, 61268.08097878535, 61444.38555614387, 57987.0, 58868.4113158863, 59122.704386477795, 59417.10874336888, 60483.39516604866, 9607.471589062668, 9672.177864881563, 9706.9029309706, 9720.708378748648, 9794.162643301108, 41796.0, 44944.55055449404, 45558.0, 46024.539754603364, 46615.30077048779, 58133.70933145307, 58229.70885145547, 58287.125693115486, 58852.41147588469, 59559.404405955385, 12700.110992230455, 12840.0, 12917.09580329368, 13278.269695166859, 13340.86659133435, 13633.795493067413, 13672.453101875813, 13902.37439315213, 14029.0, 14049.859501405264, 12598.685032873886, 13305.933470332586, 13545.390457429636, 13841.515546955809, 13946.860531394555, 12921.676958076123, 12990.596485635673, 13155.210687358789, 13313.600591982256, 13409.128406653535, 11997.880021200026, 12086.274823510617, 12790.61628151157, 12829.230246184885, 13013.349332533524, 10811.783764324828, 10975.451227438756, 10997.560097596006, 11051.447427628425, 11060.944695276472, 10704.89295107039, 11062.017039825703, 11151.553937842395, 11181.552737890719, 11187.10503159729], "unit": "ms", "name": "yui.html", "value": 6806.217284925311}], "name": "dromaeo_css", "value": 7314.619562893723}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 109.0, 102.0, 108.0, 109.0, 109.0, 100.0, 110.0, 110.0, 105.0], "unit": "ms", "name": "ai-astar", "value": 106.9}, {"lowerIsBetter": true, "replicates": [154.0, 122.0, 153.0, 153.0, 124.0, 144.0, 144.0, 121.0, 157.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.6}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 161.0, 160.0, 161.0, 158.0, 162.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.0}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 73.0, 160.0, 71.0, 72.0, 72.0, 71.0, 149.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.9}, {"lowerIsBetter": true, "replicates": [89.0, 89.0, 130.0, 88.0, 90.0, 89.0, 131.0, 89.0, 89.0, 137.0], "unit": "ms", "name": "audio-oscillator", "value": 102.1}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 56.0, 56.0, 56.0, 57.0, 56.0, 55.0, 57.0, 53.0], "unit": "ms", "name": "json-parse-financial", "value": 55.6}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.2}, {"lowerIsBetter": true, "replicates": [76.0, 77.0, 77.0, 79.0, 77.0, 79.0, 78.0, 78.0, 76.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 77.4}, {"lowerIsBetter": true, "replicates": [126.0, 122.0, 107.0, 116.0, 113.0, 117.0, 116.0, 120.0, 108.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.9}, {"lowerIsBetter": true, "replicates": [143.0, 139.0, 138.0, 137.0, 149.0, 136.0, 130.0, 141.0, 139.0, 136.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.8}, {"lowerIsBetter": true, "replicates": [60.0, 60.0, 61.0, 62.0, 60.0, 60.0, 60.0, 61.0, 62.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 61.0}], "name": "kraken", "value": 1459.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [161.4465611882467], "unit": "score", "name": "Richards", "value": 218.66058800000005}, {"lowerIsBetter": false, "replicates": [170.32873445750297], "unit": "score", "name": "DeltaBlue", "value": 388.1787779999999}, {"lowerIsBetter": false, "replicates": [279.72027972027973], "unit": "score", "name": "Encrypt", "value": 951.5970750000004}, {"lowerIsBetter": false, "replicates": [5015.0], "unit": "score", "name": "Decrypt", "value": 53.07696909272185}, {"lowerIsBetter": false, "replicates": [951.4747859181732], "unit": "score", "name": "RayTrace", "value": 777.728439}, {"lowerIsBetter": false, "replicates": [561.4823133071309], "unit": "score", "name": "Earley", "value": 1186.970603}, {"lowerIsBetter": false, "replicates": [10589.473684210527], "unit": "score", "name": "Boyer", "value": 62.93636679920471}, {"lowerIsBetter": false, "replicates": [23904.761904761905], "unit": "score", "name": "RegExp", "value": 38.10893426294821}, {"lowerIsBetter": false, "replicates": [533.9028296849973], "unit": "score", "name": "Splay", "value": 152.63264299999997}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23256.76685835791}]} 15:46:33 INFO - RETURN: dromaeo_css: 7314.62 15:46:33 INFO - RETURN: kraken: 1459.20 15:46:33 INFO - RETURN: v8_7: 23256.77 15:46:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23256.77"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1459.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7314.62"}}} 15:46:33 INFO - Return code: 0 15:46:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:46:33 INFO - # TBPL SUCCESS # 15:46:33 INFO - Running post-action listener: _resource_record_post_action 15:46:33 INFO - Running post-run listener: _resource_record_post_run 15:46:34 INFO - Total resource usage - Wall time: 1221s; CPU: 13.0%; Read bytes: 0; Write bytes: 360341504; Read time: 0; Write time: 196196 15:46:34 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 97050624; Read time: 0; Write time: 65804 15:46:34 INFO - run-tests - Wall time: 1207s; CPU: 12.0%; Read bytes: 0; Write bytes: 263290880; Read time: 0; Write time: 130392 15:46:34 INFO - Running post-run listener: _upload_blobber_files 15:46:34 INFO - Blob upload gear active. 15:46:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:46:34 INFO - Copying logs to upload dir... 15:46:34 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1263.395425 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-01-05 15:46:34.354112) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:46:34.357916) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010078 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1452027927/firefox-44.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-05 15:46:34.414125) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:46:34.414776) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa9c93d544b966bd2b8d485a000001c6-1452036214.92321-2022402167 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 15:46:34.465535) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 15:46:34.465874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-05 15:46:34.466213) ========= ========= Total master_lag: 0.31 =========