builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1455838254.71 results: success (0) buildid: 20160218130125 builduid: 7c8e68b25000437fbac64e83867f3c45 revision: bfed0f99591e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.712673) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.713328) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.713883) ========= 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005526 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.734170) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.734464) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.750835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.751733) ========= 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017816 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.800110) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.800467) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.800812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:54.801189) ========= 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-18 15:30:55-- 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% 39.2M=0s 2016-02-18 15:30:55 (39.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.410374 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:55.224613) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:55.224956) ========= 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.127916 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:55.364100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-02-18 15:30:55.364478) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bfed0f99591e --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 bfed0f99591e --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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-18 15:30:55,674 Setting DEBUG logging. 2016-02-18 15:30:55,674 attempt 1/10 2016-02-18 15:30:55,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bfed0f99591e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-18 15:30:57,701 unpacking tar archive at: mozilla-beta-bfed0f99591e/testing/mozharness/ program finished with exit code 0 elapsedTime=3.155664 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-02-18 15:30:58.536178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:58.536735) ========= echo bfed0f99591e in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'bfed0f99591e'] 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False bfed0f99591e program finished with exit code 0 elapsedTime=0.003982 script_repo_revision: 'bfed0f99591e' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:58.585649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:58.585943) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-18 15:30:58.595887) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-02-18 15:30:58.596157) ========= /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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:30:59 INFO - MultiFileLogger online at 20160218 15:30:59 in /builds/slave/test-pgo 15:30:59 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:30:59 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:30:59 INFO - {'append_to_log': False, 15:30:59 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:30:59 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:30:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:30:59 INFO - 'branch': 'Mozilla-Beta', 15:30:59 INFO - 'buildbot_json_path': 'buildprops.json', 15:30:59 INFO - 'config_files': ('talos/linux_config.py',), 15:30:59 INFO - 'default_actions': ('clobber', 15:30:59 INFO - 'read-buildbot-config', 15:30:59 INFO - 'download-and-extract', 15:30:59 INFO - 'populate-webroot', 15:30:59 INFO - 'create-virtualenv', 15:30:59 INFO - 'install', 15:30:59 INFO - 'run-tests'), 15:30:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:30:59 INFO - 'download_minidump_stackwalk': True, 15:30:59 INFO - 'download_symbols': 'ondemand', 15:30:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:30:59 INFO - 'tooltool.py': '/tools/tooltool.py', 15:30:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:30:59 INFO - '/tools/misc-python/virtualenv.py')}, 15:30:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:30:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:30:59 INFO - 'installer_path': 'installer.exe', 15:30:59 INFO - 'log_level': 'info', 15:30:59 INFO - 'log_name': 'talos', 15:30:59 INFO - 'log_to_console': True, 15:30:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:30:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:30:59 INFO - 'opt_config_files': (), 15:30:59 INFO - 'pip_index': False, 15:30:59 INFO - 'sps_profile': False, 15:30:59 INFO - 'sps_profile_interval': 0, 15:30:59 INFO - 'suite': 'dromaeojs', 15:30:59 INFO - 'system_bits': '32', 15:30:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:30:59 INFO - 'title': 'talos-linux64-ix-035', 15:30:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:30:59 INFO - 'use_talos_json': True, 15:30:59 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:30:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:30:59 INFO - 'work_dir': 'build'} 15:30:59 INFO - ##### 15:30:59 INFO - ##### Running clobber step. 15:30:59 INFO - ##### 15:30:59 INFO - Running pre-action listener: _resource_record_pre_action 15:30:59 INFO - Running main action method: clobber 15:30:59 INFO - rmtree: /builds/slave/test-pgo/build 15:30:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:31:00 INFO - Running post-action listener: _resource_record_post_action 15:31:00 INFO - ##### 15:31:00 INFO - ##### Running read-buildbot-config step. 15:31:00 INFO - ##### 15:31:00 INFO - Running pre-action listener: _resource_record_pre_action 15:31:00 INFO - Running main action method: read_buildbot_config 15:31:00 INFO - Using buildbot properties: 15:31:00 INFO - { 15:31:00 INFO - "properties": { 15:31:00 INFO - "buildnumber": 88, 15:31:00 INFO - "product": "firefox", 15:31:00 INFO - "script_repo_revision": "production", 15:31:00 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:31:00 INFO - "repository": "", 15:31:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:31:00 INFO - "buildid": "20160218130125", 15:31:00 INFO - "slavename": "talos-linux64-ix-035", 15:31:00 INFO - "pgo_build": "True", 15:31:00 INFO - "basedir": "/builds/slave/test-pgo", 15:31:00 INFO - "project": "", 15:31:00 INFO - "platform": "ubuntu64_hw", 15:31:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:31:00 INFO - "slavebuilddir": "test", 15:31:00 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:31:00 INFO - "branch": "mozilla-beta", 15:31:00 INFO - "repo_path": "releases/mozilla-beta", 15:31:00 INFO - "stage_platform": "linux64-pgo", 15:31:00 INFO - "builduid": "7c8e68b25000437fbac64e83867f3c45", 15:31:00 INFO - "revision": "bfed0f99591e" 15:31:00 INFO - }, 15:31:00 INFO - "sourcestamp": { 15:31:00 INFO - "repository": "", 15:31:00 INFO - "hasPatch": false, 15:31:00 INFO - "project": "", 15:31:00 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:31:00 INFO - "changes": [ 15:31:00 INFO - { 15:31:00 INFO - "category": null, 15:31:00 INFO - "files": [ 15:31:00 INFO - { 15:31:00 INFO - "url": null, 15:31:00 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2" 15:31:00 INFO - } 15:31:00 INFO - ], 15:31:00 INFO - "repository": "", 15:31:00 INFO - "rev": "bfed0f99591e", 15:31:00 INFO - "who": "sendchange", 15:31:00 INFO - "when": 1455838106, 15:31:00 INFO - "number": 7624285, 15:31:00 INFO - "comments": "Bug 1228671 - Make some notification telemetry opt-out. r=bsmedberg a=sylvestre", 15:31:00 INFO - "project": "", 15:31:00 INFO - "at": "Thu 18 Feb 2016 15:28:26", 15:31:00 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 15:31:00 INFO - "revlink": "", 15:31:00 INFO - "properties": [ 15:31:00 INFO - [ 15:31:00 INFO - "buildid", 15:31:00 INFO - "20160218130125", 15:31:00 INFO - "Change" 15:31:00 INFO - ], 15:31:00 INFO - [ 15:31:00 INFO - "builduid", 15:31:00 INFO - "7c8e68b25000437fbac64e83867f3c45", 15:31:00 INFO - "Change" 15:31:00 INFO - ], 15:31:00 INFO - [ 15:31:00 INFO - "pgo_build", 15:31:00 INFO - "True", 15:31:00 INFO - "Change" 15:31:00 INFO - ] 15:31:00 INFO - ], 15:31:00 INFO - "revision": "bfed0f99591e" 15:31:00 INFO - } 15:31:00 INFO - ], 15:31:00 INFO - "revision": "bfed0f99591e" 15:31:00 INFO - } 15:31:00 INFO - } 15:31:00 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2. 15:31:00 INFO - Running post-action listener: _resource_record_post_action 15:31:00 INFO - ##### 15:31:00 INFO - ##### Running download-and-extract step. 15:31:00 INFO - ##### 15:31:00 INFO - Running pre-action listener: _resource_record_pre_action 15:31:00 INFO - Running main action method: download_and_extract 15:31:00 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:31: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:31:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/test_packages.json 15:31:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:31:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:31:00 INFO - Downloaded 1270 bytes. 15:31:00 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:31:00 INFO - Using the following test package requirements: 15:31:00 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 15:31:00 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:31:00 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'jsshell-linux-x86_64.zip'], 15:31:00 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:31:00 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 15:31:00 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 15:31:00 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 15:31:00 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:31:00 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 15:31:00 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 15:31:00 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:31:00 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:31: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:31:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:31:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:31:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:31:01 INFO - Downloaded 22255576 bytes. 15:31:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:31:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:31:01 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:31:02 INFO - Return code: 0 15:31:02 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:31:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:31:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:31:02 INFO - Downloaded 22255576 bytes. 15:31:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 15:31:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 15:31:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 15:31:04 INFO - Return code: 0 15:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 15:31:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 15:31:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:31:04 INFO - Downloaded 11132038 bytes. 15:31:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 15:31:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 15:31:04 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 15:31:05 INFO - Return code: 0 15:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:05 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:31:05 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:31:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:31:05 INFO - Downloaded 58328783 bytes. 15:31:05 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:31:05 INFO - mkdir: /builds/slave/test-pgo/properties 15:31:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:31:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:31:05 INFO - Contents: 15:31:05 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2 15:31:05 INFO - Running post-action listener: _resource_record_post_action 15:31:05 INFO - Running post-action listener: set_extra_try_arguments 15:31:05 INFO - ##### 15:31:05 INFO - ##### Running populate-webroot step. 15:31:05 INFO - ##### 15:31:05 INFO - Running pre-action listener: _resource_record_pre_action 15:31:05 INFO - Running main action method: populate_webroot 15:31:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:31:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:31:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:31:05 INFO - '--tppagecycles', 15:31:05 INFO - '7'], 15:31:05 INFO - 'tests': ['tsvgm']}}, 15:31:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:31:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:31:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:31:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:31:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:31:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:31:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'tests': ['damp', 'tps']}, 15:31:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['damp', 'tps']}, 15:31:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:31:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:31:05 INFO - 'other': {'tests': ['a11yr', 15:31:05 INFO - 'ts_paint', 15:31:05 INFO - 'tpaint', 15:31:05 INFO - 'sessionrestore', 15:31:05 INFO - 'sessionrestore_no_auto_restore']}, 15:31:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['a11yr', 15:31:05 INFO - 'ts_paint', 15:31:05 INFO - 'tpaint', 15:31:05 INFO - 'sessionrestore', 15:31:05 INFO - 'sessionrestore_no_auto_restore']}, 15:31:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['a11yr', 15:31:05 INFO - 'ts_paint', 15:31:05 INFO - 'tpaint', 15:31:05 INFO - 'sessionrestore', 15:31:05 INFO - 'sessionrestore_no_auto_restore']}, 15:31:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['a11yr', 15:31:05 INFO - 'ts_paint', 15:31:05 INFO - 'tpaint', 15:31:05 INFO - 'sessionrestore', 15:31:05 INFO - 'sessionrestore_no_auto_restore']}, 15:31:05 INFO - 'other_l64': {'tests': ['a11yr', 15:31:05 INFO - 'ts_paint', 15:31:05 INFO - 'tpaint', 15:31:05 INFO - 'sessionrestore', 15:31:05 INFO - 'sessionrestore_no_auto_restore']}, 15:31:05 INFO - 'other_nol64': {'tests': ['a11yr', 15:31:05 INFO - 'ts_paint', 15:31:05 INFO - 'tpaint', 15:31:05 INFO - 'sessionrestore', 15:31:05 INFO - 'sessionrestore_no_auto_restore']}, 15:31:05 INFO - 'svgr': {'tests': ['tsvgx', 15:31:05 INFO - 'tsvgr_opacity', 15:31:05 INFO - 'tart', 15:31:05 INFO - 'tscrollx', 15:31:05 INFO - 'cart']}, 15:31:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['tsvgx', 15:31:05 INFO - 'tsvgr_opacity', 15:31:05 INFO - 'tart', 15:31:05 INFO - 'tscrollx', 15:31:05 INFO - 'cart']}, 15:31:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'tests': ['tp5o']}, 15:31:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'talos_options': ['--e10s'], 15:31:05 INFO - 'tests': ['tp5o']}, 15:31:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'talos_options': ['--xperf_path', 15:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:31:05 INFO - 'tests': ['tp5n']}, 15:31:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:31:05 INFO - 'talos_options': ['--e10s', 15:31:05 INFO - '--xperf_path', 15:31:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:31:05 INFO - 'tests': ['tp5n']}}, 15:31:05 INFO - 'talos.zip': {'path': '', 15:31:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:31:05 INFO - Running post-action listener: _resource_record_post_action 15:31:05 INFO - ##### 15:31:05 INFO - ##### Running create-virtualenv step. 15:31:05 INFO - ##### 15:31:05 INFO - Running pre-action listener: _resource_record_pre_action 15:31:05 INFO - Running main action method: create_virtualenv 15:31:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:31:05 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:31:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:31:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:31:06 INFO - Using real prefix '/usr' 15:31:06 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:31:06 INFO - Installing distribute.............................................................................................................................................................................................done. 15:31:08 INFO - Installing pip.................done. 15:31:08 INFO - Return code: 0 15:31:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 15:31:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:31:08 INFO - Using env: {'DISPLAY': ':0', 15:31:08 INFO - 'HOME': '/home/cltbld', 15:31:08 INFO - 'LANG': 'en_US.UTF-8', 15:31:08 INFO - 'LANGUAGE': 'en_US:en', 15:31:08 INFO - 'LOGNAME': 'cltbld', 15:31:08 INFO - 'MAIL': '/var/mail/cltbld', 15:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:08 INFO - 'MOZ_NO_REMOTE': '1', 15:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:08 INFO - 'NO_EM_RESTART': '1', 15:31:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:08 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:08 INFO - 'SHELL': '/bin/bash', 15:31:08 INFO - 'SHLVL': '1', 15:31:08 INFO - 'TERM': 'linux', 15:31:08 INFO - 'TMOUT': '86400', 15:31:08 INFO - 'USER': 'cltbld', 15:31:08 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:08 INFO - '_': '/tools/buildbot/bin/python'} 15:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:10 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:31:10 INFO - Cleaning up... 15:31:10 INFO - Return code: 0 15:31:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 15:31:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:31:10 INFO - Using env: {'DISPLAY': ':0', 15:31:10 INFO - 'HOME': '/home/cltbld', 15:31:10 INFO - 'LANG': 'en_US.UTF-8', 15:31:10 INFO - 'LANGUAGE': 'en_US:en', 15:31:10 INFO - 'LOGNAME': 'cltbld', 15:31:10 INFO - 'MAIL': '/var/mail/cltbld', 15:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:10 INFO - 'MOZ_NO_REMOTE': '1', 15:31:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:10 INFO - 'NO_EM_RESTART': '1', 15:31:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:10 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:10 INFO - 'SHELL': '/bin/bash', 15:31:10 INFO - 'SHLVL': '1', 15:31:10 INFO - 'TERM': 'linux', 15:31:10 INFO - 'TMOUT': '86400', 15:31:10 INFO - 'USER': 'cltbld', 15:31:10 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:10 INFO - '_': '/tools/buildbot/bin/python'} 15:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:11 INFO - Downloading/unpacking psutil>=0.7.1 15:31: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:31: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:31: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:31: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:31: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:31: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:31:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:31:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:31:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:31:13 INFO - Installing collected packages: psutil 15:31:13 INFO - Running setup.py install for psutil 15:31:13 INFO - building 'psutil._psutil_linux' extension 15:31:13 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:31:14 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:31:14 INFO - building 'psutil._psutil_posix' extension 15:31:14 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:31:14 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:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:31:14 INFO - Successfully installed psutil 15:31:14 INFO - Cleaning up... 15:31:14 INFO - Return code: 0 15:31:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:31:14 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:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:14 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:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 15:31:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:31:14 INFO - Using env: {'DISPLAY': ':0', 15:31:14 INFO - 'HOME': '/home/cltbld', 15:31:14 INFO - 'LANG': 'en_US.UTF-8', 15:31:14 INFO - 'LANGUAGE': 'en_US:en', 15:31:14 INFO - 'LOGNAME': 'cltbld', 15:31:14 INFO - 'MAIL': '/var/mail/cltbld', 15:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:14 INFO - 'MOZ_NO_REMOTE': '1', 15:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:14 INFO - 'NO_EM_RESTART': '1', 15:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:14 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:14 INFO - 'SHELL': '/bin/bash', 15:31:14 INFO - 'SHLVL': '1', 15:31:14 INFO - 'TERM': 'linux', 15:31:14 INFO - 'TMOUT': '86400', 15:31:14 INFO - 'USER': 'cltbld', 15:31:14 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:14 INFO - '_': '/tools/buildbot/bin/python'} 15:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:31:14 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:31:14 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:31:14 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:31:14 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:31:14 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:31:14 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:31:16 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:31:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:31:16 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:31:16 INFO - Installing collected packages: mozsystemmonitor 15:31:16 INFO - Running setup.py install for mozsystemmonitor 15:31:16 INFO - Successfully installed mozsystemmonitor 15:31:16 INFO - Cleaning up... 15:31:16 INFO - Return code: 0 15:31:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:31:16 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:31:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:16 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:31:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 15:31:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:31:16 INFO - Using env: {'DISPLAY': ':0', 15:31:16 INFO - 'HOME': '/home/cltbld', 15:31:16 INFO - 'LANG': 'en_US.UTF-8', 15:31:16 INFO - 'LANGUAGE': 'en_US:en', 15:31:16 INFO - 'LOGNAME': 'cltbld', 15:31:16 INFO - 'MAIL': '/var/mail/cltbld', 15:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:16 INFO - 'MOZ_NO_REMOTE': '1', 15:31:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:16 INFO - 'NO_EM_RESTART': '1', 15:31:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:16 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:16 INFO - 'SHELL': '/bin/bash', 15:31:16 INFO - 'SHLVL': '1', 15:31:16 INFO - 'TERM': 'linux', 15:31:16 INFO - 'TMOUT': '86400', 15:31:16 INFO - 'USER': 'cltbld', 15:31:16 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:16 INFO - '_': '/tools/buildbot/bin/python'} 15:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:17 INFO - Downloading/unpacking blobuploader==1.2.4 15:31:17 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:31:17 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:31:17 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:31:17 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:31:17 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:31:17 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:31:18 INFO - Downloading blobuploader-1.2.4.tar.gz 15:31:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:31:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:31:18 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:31:18 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:31:18 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:31:18 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:31:18 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:31:18 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:31:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:31:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:31:19 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:31:19 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:31:19 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:31:19 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:31:19 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:31:19 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:31:19 INFO - Downloading docopt-0.6.1.tar.gz 15:31:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:31:19 INFO - Installing collected packages: blobuploader, requests, docopt 15:31:19 INFO - Running setup.py install for blobuploader 15:31:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:31:19 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:31:19 INFO - Running setup.py install for requests 15:31:20 INFO - Running setup.py install for docopt 15:31:20 INFO - Successfully installed blobuploader requests docopt 15:31:20 INFO - Cleaning up... 15:31:20 INFO - Return code: 0 15:31:20 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:20 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 15:31:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:31:20 INFO - Using env: {'DISPLAY': ':0', 15:31:20 INFO - 'HOME': '/home/cltbld', 15:31:20 INFO - 'LANG': 'en_US.UTF-8', 15:31:20 INFO - 'LANGUAGE': 'en_US:en', 15:31:20 INFO - 'LOGNAME': 'cltbld', 15:31:20 INFO - 'MAIL': '/var/mail/cltbld', 15:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:20 INFO - 'MOZ_NO_REMOTE': '1', 15:31:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:20 INFO - 'NO_EM_RESTART': '1', 15:31:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:20 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:20 INFO - 'SHELL': '/bin/bash', 15:31:20 INFO - 'SHLVL': '1', 15:31:20 INFO - 'TERM': 'linux', 15:31:20 INFO - 'TMOUT': '86400', 15:31:20 INFO - 'USER': 'cltbld', 15:31:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:20 INFO - '_': '/tools/buildbot/bin/python'} 15:31:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:31:20 INFO - Running setup.py (path:/tmp/pip-XrozhC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:31:20 INFO - Running setup.py (path:/tmp/pip-OoBrzZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:31:20 INFO - Running setup.py (path:/tmp/pip-vnocks-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:31:20 INFO - Running setup.py (path:/tmp/pip-lHGOYj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:31:20 INFO - Running setup.py (path:/tmp/pip-ESXVE0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:31:20 INFO - Running setup.py (path:/tmp/pip-4DqN5S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:31:20 INFO - Running setup.py (path:/tmp/pip-gK_KVL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:31:20 INFO - Running setup.py (path:/tmp/pip-_8r1XP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:31:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:31:20 INFO - Running setup.py (path:/tmp/pip-q2b2ou-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:31:21 INFO - Running setup.py (path:/tmp/pip-2m71I6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:31:21 INFO - Running setup.py (path:/tmp/pip-7QCsJ4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:31:21 INFO - Running setup.py (path:/tmp/pip-kp2kKt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:31:21 INFO - Running setup.py (path:/tmp/pip-KZfUAy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:31:21 INFO - Running setup.py (path:/tmp/pip-zH3qSu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:31:21 INFO - Running setup.py (path:/tmp/pip-Cx5bxp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:31:21 INFO - Running setup.py (path:/tmp/pip-lpOoRa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:31:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:31:21 INFO - Running setup.py (path:/tmp/pip-Jm8e6q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:31:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:31:21 INFO - Running setup.py install for manifestparser 15:31:21 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:31:21 INFO - Running setup.py install for mozcrash 15:31:21 INFO - Running setup.py install for mozdebug 15:31:21 INFO - Running setup.py install for mozdevice 15:31:22 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for mozfile 15:31:22 INFO - Running setup.py install for mozhttpd 15:31:22 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for mozinfo 15:31:22 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for mozInstall 15:31:22 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for mozleak 15:31:22 INFO - Running setup.py install for mozlog 15:31:22 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for moznetwork 15:31:22 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for mozprocess 15:31:22 INFO - Running setup.py install for mozprofile 15:31:22 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:31:22 INFO - Running setup.py install for mozrunner 15:31:23 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:31:23 INFO - Running setup.py install for mozscreenshot 15:31:23 INFO - Running setup.py install for moztest 15:31:23 INFO - Running setup.py install for mozversion 15:31:23 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:31:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:31:23 INFO - Cleaning up... 15:31:23 INFO - Return code: 0 15:31:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:31:23 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:31:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:23 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:31:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:23 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 15:31:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:31:23 INFO - Using env: {'DISPLAY': ':0', 15:31:23 INFO - 'HOME': '/home/cltbld', 15:31:23 INFO - 'LANG': 'en_US.UTF-8', 15:31:23 INFO - 'LANGUAGE': 'en_US:en', 15:31:23 INFO - 'LOGNAME': 'cltbld', 15:31:23 INFO - 'MAIL': '/var/mail/cltbld', 15:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:23 INFO - 'MOZ_NO_REMOTE': '1', 15:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:23 INFO - 'NO_EM_RESTART': '1', 15:31:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:23 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:23 INFO - 'SHELL': '/bin/bash', 15:31:23 INFO - 'SHLVL': '1', 15:31:23 INFO - 'TERM': 'linux', 15:31:23 INFO - 'TMOUT': '86400', 15:31:23 INFO - 'USER': 'cltbld', 15:31:23 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:23 INFO - '_': '/tools/buildbot/bin/python'} 15:31:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:31:23 INFO - Running setup.py (path:/tmp/pip-RwomwM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:31:23 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:31:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:31:23 INFO - Running setup.py (path:/tmp/pip-VjLplO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:31:23 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:31:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:31:23 INFO - Running setup.py (path:/tmp/pip-HBMiZp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:31:23 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:31:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:31:23 INFO - Running setup.py (path:/tmp/pip-MqXeb3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:31:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:31:23 INFO - Running setup.py (path:/tmp/pip-zNvpH_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:31:23 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:31:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:31:23 INFO - Running setup.py (path:/tmp/pip-ul9fFo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:31:24 INFO - Running setup.py (path:/tmp/pip-c933gl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:31:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 15:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:31:24 INFO - Running setup.py (path:/tmp/pip-kfXJaX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:31:24 INFO - Running setup.py (path:/tmp/pip-cRucPy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:31:24 INFO - Running setup.py (path:/tmp/pip-mfiVBF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:31:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:31:24 INFO - Running setup.py (path:/tmp/pip-ciuz5T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:31:24 INFO - Running setup.py (path:/tmp/pip-O5MG1S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:31:24 INFO - Running setup.py (path:/tmp/pip-DXDnyQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:31:24 INFO - Running setup.py (path:/tmp/pip-vLRfX0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:31:24 INFO - Running setup.py (path:/tmp/pip-iDgNmK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:31:24 INFO - Running setup.py (path:/tmp/pip-6g1Stv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:31:24 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:31:24 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:31:24 INFO - Running setup.py (path:/tmp/pip-ThowVG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:31:24 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:31:24 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:31:24 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:31:24 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:31:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 15:31:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:31:24 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:31:24 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:31:24 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:31:24 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:31:24 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:31:24 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:31:26 INFO - Downloading blessings-1.6.tar.gz 15:31:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:31:26 INFO - Installing collected packages: blessings 15:31:26 INFO - Running setup.py install for blessings 15:31:26 INFO - Successfully installed blessings 15:31:26 INFO - Cleaning up... 15:31:26 INFO - Return code: 0 15:31:26 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:31:26 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:31:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:31:27 INFO - Reading from file tmpfile_stdout 15:31:27 INFO - Current package versions: 15:31:27 INFO - argparse == 1.2.1 15:31:27 INFO - blessings == 1.6 15:31:27 INFO - blobuploader == 1.2.4 15:31:27 INFO - docopt == 0.6.1 15:31:27 INFO - manifestparser == 1.1 15:31:27 INFO - mozInstall == 1.12 15:31:27 INFO - mozcrash == 0.16 15:31:27 INFO - mozdebug == 0.1 15:31:27 INFO - mozdevice == 0.47 15:31:27 INFO - mozfile == 1.2 15:31:27 INFO - mozhttpd == 0.7 15:31:27 INFO - mozinfo == 0.9 15:31:27 INFO - mozleak == 0.1 15:31:27 INFO - mozlog == 3.1 15:31:27 INFO - moznetwork == 0.27 15:31:27 INFO - mozprocess == 0.22 15:31:27 INFO - mozprofile == 0.27 15:31:27 INFO - mozrunner == 6.11 15:31:27 INFO - mozscreenshot == 0.1 15:31:27 INFO - mozsystemmonitor == 0.0 15:31:27 INFO - moztest == 0.7 15:31:27 INFO - mozversion == 1.4 15:31:27 INFO - psutil == 3.1.1 15:31:27 INFO - requests == 1.2.3 15:31:27 INFO - wsgiref == 0.1.2 15:31:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:27 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 15:31:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:31:27 INFO - Using env: {'DISPLAY': ':0', 15:31:27 INFO - 'HOME': '/home/cltbld', 15:31:27 INFO - 'LANG': 'en_US.UTF-8', 15:31:27 INFO - 'LANGUAGE': 'en_US:en', 15:31:27 INFO - 'LOGNAME': 'cltbld', 15:31:27 INFO - 'MAIL': '/var/mail/cltbld', 15:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:27 INFO - 'MOZ_NO_REMOTE': '1', 15:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:27 INFO - 'NO_EM_RESTART': '1', 15:31:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:27 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:27 INFO - 'SHELL': '/bin/bash', 15:31:27 INFO - 'SHLVL': '1', 15:31:27 INFO - 'TERM': 'linux', 15:31:27 INFO - 'TMOUT': '86400', 15:31:27 INFO - 'USER': 'cltbld', 15:31:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:27 INFO - '_': '/tools/buildbot/bin/python'} 15:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 INFO - Cleaning up... 15:31:27 INFO - Return code: 0 15:31:27 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:27 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 15:31:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:31:27 INFO - Using env: {'DISPLAY': ':0', 15:31:27 INFO - 'HOME': '/home/cltbld', 15:31:27 INFO - 'LANG': 'en_US.UTF-8', 15:31:27 INFO - 'LANGUAGE': 'en_US:en', 15:31:27 INFO - 'LOGNAME': 'cltbld', 15:31:27 INFO - 'MAIL': '/var/mail/cltbld', 15:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:27 INFO - 'MOZ_NO_REMOTE': '1', 15:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:27 INFO - 'NO_EM_RESTART': '1', 15:31:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:27 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:27 INFO - 'SHELL': '/bin/bash', 15:31:27 INFO - 'SHLVL': '1', 15:31:27 INFO - 'TERM': 'linux', 15:31:27 INFO - 'TMOUT': '86400', 15:31:27 INFO - 'USER': 'cltbld', 15:31:27 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:27 INFO - '_': '/tools/buildbot/bin/python'} 15:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:27 INFO - Downloading/unpacking jsonschema 15:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:31:29 INFO - Downloading/unpacking functools32 (from jsonschema) 15:31:29 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:31:29 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:31:29 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:31:29 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:31:29 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:31:29 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:31:29 INFO - Downloading functools32-3.2.3-2.tar.gz 15:31:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:31:29 INFO - warning: no files found matching '*.txt' 15:31:29 INFO - no previously-included directories found matching 'build' 15:31:29 INFO - no previously-included directories found matching 'dist' 15:31:29 INFO - no previously-included directories found matching '.git*' 15:31:29 INFO - Installing collected packages: jsonschema, functools32 15:31:29 INFO - Running setup.py install for functools32 15:31:30 INFO - warning: no files found matching '*.txt' 15:31:30 INFO - no previously-included directories found matching 'build' 15:31:30 INFO - no previously-included directories found matching 'dist' 15:31:30 INFO - no previously-included directories found matching '.git*' 15:31:30 INFO - Successfully installed jsonschema functools32 15:31:30 INFO - Cleaning up... 15:31:30 INFO - Return code: 0 15:31:30 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:31:30 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:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:30 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:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:31:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5681f4df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x172c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, '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': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', '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:31:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 15:31:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:31:30 INFO - Using env: {'DISPLAY': ':0', 15:31:30 INFO - 'HOME': '/home/cltbld', 15:31:30 INFO - 'LANG': 'en_US.UTF-8', 15:31:30 INFO - 'LANGUAGE': 'en_US:en', 15:31:30 INFO - 'LOGNAME': 'cltbld', 15:31:30 INFO - 'MAIL': '/var/mail/cltbld', 15:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:30 INFO - 'MOZ_NO_REMOTE': '1', 15:31:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:30 INFO - 'NO_EM_RESTART': '1', 15:31:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:30 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:30 INFO - 'SHELL': '/bin/bash', 15:31:30 INFO - 'SHLVL': '1', 15:31:30 INFO - 'TERM': 'linux', 15:31:30 INFO - 'TMOUT': '86400', 15:31:30 INFO - 'USER': 'cltbld', 15:31:30 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:30 INFO - '_': '/tools/buildbot/bin/python'} 15:31:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:31:30 INFO - Downloading/unpacking flake8 15:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:31:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:31:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:31:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:31:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:31:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:31:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:31:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:31:32 INFO - no previously-included directories found matching 'docs/_build' 15:31:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:31:32 INFO - Running setup.py install for pep8 15:31:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:31:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:31:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:31:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:31:32 INFO - no previously-included directories found matching 'docs/_build' 15:31:32 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:31:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:31:32 INFO - Cleaning up... 15:31:33 INFO - Return code: 0 15:31:33 INFO - Running post-action listener: _resource_record_post_action 15:31:33 INFO - Running post-action listener: _start_resource_monitoring 15:31:33 INFO - Starting resource monitoring. 15:31:33 INFO - ##### 15:31:33 INFO - ##### Running install step. 15:31:33 INFO - ##### 15:31:33 INFO - Running pre-action listener: _resource_record_pre_action 15:31:33 INFO - Running main action method: install 15:31:33 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:31:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:31:33 INFO - Reading from file tmpfile_stdout 15:31:33 INFO - Detecting whether we're running mozinstall >=1.0... 15:31:33 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:31:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:31:33 INFO - Reading from file tmpfile_stdout 15:31:33 INFO - Output received: 15:31:33 INFO - Usage: mozinstall [options] installer 15:31:33 INFO - Options: 15:31:33 INFO - -h, --help show this help message and exit 15:31:33 INFO - -d DEST, --destination=DEST 15:31:33 INFO - Directory to install application into. [default: 15:31:33 INFO - "/builds/slave/test-pgo"] 15:31:33 INFO - --app=APP Application being installed. [default: firefox] 15:31:33 INFO - mkdir: /builds/slave/test-pgo/build/application 15:31:33 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 15:31:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 15:31:47 INFO - Reading from file tmpfile_stdout 15:31:47 INFO - Output received: 15:31:47 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:31:47 INFO - Running post-action listener: _resource_record_post_action 15:31:47 INFO - ##### 15:31:47 INFO - ##### Running run-tests step. 15:31:47 INFO - ##### 15:31:47 INFO - Running pre-action listener: _resource_record_pre_action 15:31:47 INFO - Running main action method: run_tests 15:31:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:31:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:31:47 INFO - Python 2.7.3 15:31:47 INFO - Return code: 0 15:31:47 INFO - grabbing minidump binary from tooltool 15:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:47 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 0x1888350>, '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 0x1889250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18896e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:31:47 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:31:47 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:31:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:31:48 INFO - Return code: 0 15:31:48 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:31:48 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:31:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:31:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:31:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:31:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:31:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:31:49 INFO - Return code: 0 15:31:49 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/1455829285/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 15:31:49 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/1455829285/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost 15:31:49 INFO - Using env: {'DISPLAY': ':0', 15:31:49 INFO - 'HOME': '/home/cltbld', 15:31:49 INFO - 'LANG': 'en_US.UTF-8', 15:31:49 INFO - 'LANGUAGE': 'en_US:en', 15:31:49 INFO - 'LOGNAME': 'cltbld', 15:31:49 INFO - 'MAIL': '/var/mail/cltbld', 15:31:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:31:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:31:49 INFO - 'MOZ_NO_REMOTE': '1', 15:31:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:31:49 INFO - 'NO_EM_RESTART': '1', 15:31:49 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:31:49 INFO - 'PWD': '/builds/slave/test-pgo', 15:31:49 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:31:49 INFO - 'SHELL': '/bin/bash', 15:31:49 INFO - 'SHLVL': '1', 15:31:49 INFO - 'TERM': 'linux', 15:31:49 INFO - 'TMOUT': '86400', 15:31:49 INFO - 'USER': 'cltbld', 15:31:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004', 15:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:31:49 INFO - '_': '/tools/buildbot/bin/python'} 15:31:49 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/1455829285/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] with output_timeout 3600 15:31:49 INFO - mozversion INFO | application_buildid: 20160218130125 15:31:49 INFO - mozversion INFO | application_changeset: bfed0f99591e03f3b769320fdfd9352be7390ff9 15:31:49 INFO - mozversion INFO | application_display_name: Nightly 15:31:49 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:31:49 INFO - mozversion INFO | application_name: Firefox 15:31:49 INFO - mozversion INFO | application_remotingname: firefox 15:31:49 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:31:49 INFO - mozversion INFO | application_vendor: Mozilla 15:31:49 INFO - mozversion INFO | application_version: 45.0 15:31:49 INFO - mozversion INFO | platform_buildid: 20160218130125 15:31:49 INFO - mozversion INFO | platform_changeset: bfed0f99591e03f3b769320fdfd9352be7390ff9 15:31:49 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:31:49 INFO - mozversion INFO | platform_version: 45.0 15:31:49 INFO - 2016-02-18 15:31:49,415 DEBUG : using testdate: 1455838309 15:31:49 INFO - 2016-02-18 15:31:49,416 DEBUG : actual date: 1455838309 15:31:49 INFO - 2016-02-18 15:31:49,423 INFO : starting webserver on 'localhost:52552' 15:31:49 INFO - 2016-02-18 15:31:49,423 INFO : Starting test suite talos-linux64-ix-035 15:31:49 INFO - 2016-02-18 15:31:49,424 INFO : Starting test dromaeo_css 15:31:49 INFO - 2016-02-18 15:31:49,424 DEBUG : operating with platform_type : linux_ 15:31:49 INFO - 2016-02-18 15:31:49,424 INFO : Initialising browser for dromaeo_css test... 15:31:49 INFO - 2016-02-18 15:31:49,441 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGEh8k0/profile http://localhost:52552/getInfo.html 15:31:53 INFO - 2016-02-18 15:31:53,852 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:31:53 INFO - 2016-02-18 15:31:53,852 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:31:53 INFO - 2016-02-18 15:31:53,852 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:31:53 INFO - 2016-02-18 15:31:53,852 DEBUG : BROWSER_OUTPUT: __metrics 15:31:53 INFO - 2016-02-18 15:31:53,995 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:31:55 INFO - 2016-02-18 15:31:55,186 INFO : Browser initialized. 15:31:55 INFO - 2016-02-18 15:31:55,186 INFO : Running cycle 1/1 for dromaeo_css test... 15:31:55 INFO - 2016-02-18 15:31:55,187 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGEh8k0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:32:06 INFO - 2016-02-18 15:32:06,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 133267456 15:32:06 INFO - 2016-02-18 15:32:06,991 DEBUG : BROWSER_OUTPUT: 15:32:58 INFO - 2016-02-18 15:32:58,033 DEBUG : BROWSER_OUTPUT: 1455838378031 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:35:06 INFO - 2016-02-18 15:35:06,666 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52552/tests/dromaeo/cssquery-ext.html) 15:35:08 INFO - 2016-02-18 15:35:08,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 307171328 15:35:08 INFO - 2016-02-18 15:35:08,141 DEBUG : BROWSER_OUTPUT: 15:38:10 INFO - 2016-02-18 15:38:10,294 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/dromaeo/cssquery-ext.html (next: http://localhost:52552/tests/dromaeo/cssquery-jquery.html) 15:38:10 INFO - 2016-02-18 15:38:10,863 DEBUG : BROWSER_OUTPUT: RSS: Main: 222949376 15:38:10 INFO - 2016-02-18 15:38:10,863 DEBUG : BROWSER_OUTPUT: 15:41:12 INFO - 2016-02-18 15:41:12,610 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52552/tests/dromaeo/cssquery-mootools.html) 15:41:13 INFO - 2016-02-18 15:41:13,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 207699968 15:41:13 INFO - 2016-02-18 15:41:13,177 DEBUG : BROWSER_OUTPUT: 15:44:14 INFO - 2016-02-18 15:44:14,939 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52552/tests/dromaeo/cssquery-prototype.html) 15:44:15 INFO - 2016-02-18 15:44:15,501 DEBUG : BROWSER_OUTPUT: RSS: Main: 207192064 15:44:15 INFO - 2016-02-18 15:44:15,501 DEBUG : BROWSER_OUTPUT: 15:47:17 INFO - 2016-02-18 15:47:17,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52552/tests/dromaeo/cssquery-yui.html) 15:47:18 INFO - 2016-02-18 15:47:18,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 200974336 15:47:18 INFO - 2016-02-18 15:47:18,335 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,392 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/dromaeo/cssquery-yui.html (next: http://localhost:52552/tests/dromaeo/cssquery-dojo.html) 15:50:20 INFO - 2016-02-18 15:50:20,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 213663744 15:50:20 INFO - 2016-02-18 15:50:20,650 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3323.601167859857;3416.333814906094;3438.381091403546;3448.775829571078;3467.133216695826;5576.191452239423;5635.380108188101;5671.744771485282;5688.687122208277;5691.573132015094;5634.239377684022;5659.830205093844;5684.175794509804;5701.0878259478495;5724.5992780505385;5076.13705670037;5141.948580514184;5148.613853960958;5198.92201616976;5247.632665713382;5191.091558977183;5225.268462415265;5228.999999999981;5235.685858848462;5237.240600113;5057.772400241998;5067.113255180348;5169.844904652867;5177.844664660048;5236.31927849379;5173.042987047408;5188.662736922088;5192.169252919514;5194.506521880416;5207.6875387476875;5019.347484826985;5106.08090543702;5162.974185129088;5226.3989641191365;5227.137522308814;5342.439043900412;5444.891102177934;5450.209719590637;5453.23654688344;5463.043967305705;6016.8495787605225;6050.848728781772;6057.909131363033;6057.969710151465;6065.211522502091;5813.360530341659;5890;6065.696715164268;6067.666278354689;6068.120122582245;5566;5644.238027866268;5645.774169033275;5659.094544872801;5687.118496633028;6093.146959425684;6104.114903338991;6111.572189946661;6121.724522396414;6121.877562448815;5712.228849105318;5776.740046697909;5823.883522329615;5961.046232602763;5968.940310596925;6021.42796434338;6135.417135372132;6139.723712432862;6145.139680444741;6163.876722465526;6039;6070.089486576961;6083.452489275986;6083.8174854754425;6106.72519736604;5883.882322353529;5956.612820166675;6017.217761691039;6021.217241758631;6071.635701857815;4608.354830323758;4609.469910960215;4610.331501932268;4610.515895830889;4612.469565999886;4146.958530414718;4156.916861662749;4159.480064991876;4170.958290417057;4191.643710284599;5558.277423934944;5621.971890140605;5631.2960879890015;5647;5652.236948012048;6045.78839740607;6074.483668888194;6088.665123418255;6112.327643959161;6117.602355846856;5490.423505531941;5582.190582365534;5600.635958662674;5605.523530499871;5649.4633009864;5253.106971814801;5258.7370631468275;5272.894542109137;5285.735713214324;5289.312389389354;6217.502599792006;6251.031090181029;6269.5611307208055;6291.937080629226;6296.496280297565;5864;5921.467067963904;5996.070609055603;6036.336003039574;6076.331603523698;6143.078538219124;6241.001439769615;6241.906371404341;6266.498680105489;6276.874462510816;5930.199423077829;6017.398260174123;6028.87942241144;6032.819015429544;6046.909296360646;5871.911921321098;5909.586328957103;5985.940140598713;5990.7304171311525;6081.695915204311;5848.970755146197;5925.348211696623;5931.822045338647;5943.52451803846;5944.227250457413;5819.970900145472;5827.796027139199;5858.033424484912;5860.472557469848;5860.589758717019;5930.614510056833;5938.4061593842;5940.1683764272175;5940.8811823764145;5946.28644562638;5978.163057172086;6031.5476339273855;6044.214252147192;6050.45545900871;6060.1212824139375;5628.85927851807;5701.828945131652;5776.7111644418455;5903.911441328298;5912.46787789101 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: |1;ext.html;559.6362364463098;619.9349068347823;623.4731651754266;633.9366063393664;634.2230767310043;29528;29919.700802992018;29925.25186870324;29987.850060749694;30006.849965750167;34984.475232871526;43045;43917.560824391745;44033.11933761331;44070.559294407045;9189.989101198846;9301.627934882597;9321.161095501404;9324.300677449219;9333.393329433566;8078.35373170145;8228.42401031925;9052.41159324645;9127.178553930145;9167.908320916806;10959.56161753529;11184.608538701106;11194.888051119508;11198.38408887511;11200.383978881158;10693.572257109707;10753.462326883624;10786.244962852603;10786.946065269623;10790.946045269802;10636.042756151983;10769.83845242322;10778.19163562736;10780.514876830483;10783.892161078445;42983.78508107439;43939.56060439387;43956.340654890206;43965.78017109926;44029.33955990663;3674;3683.797391143486;3736.1220113273357;4150.501939767217;4355.368471571636;32808;33180.1704957378;33592.16019599505;33640.15899602505;34019.31961360783;3889.727719059667;4007.5190977082643;4044.3529035354204;4072.246634372621;4233.640140588053;296788;298513.50743246364;298714;298936;299535;65157.67421162864;66555.66722166358;67638.66180669065;75436;77132;67481;67623;67916;67930;67941.32058679449;18339.266429342944;18567;18573.16420761069;18576.164072616764;18585.628287434447;13894.722105558034;13989;14009.71980560383;14017.439302427993;14032;22470.438239044153;22761.544769104526;22767.772322276564;22846.543069138523;22902.42743931415;8583.613737381833;8678.305735541142;8737.43206691563;8803.735887923373;8835.807166032631;1032.7934413117257;1092.448313601626;1094.8576685030896;1095.3318475729798;1143.954241830334;31473.527897081563;35170.29659406798;35297.823510882285;35502.28995420077;35647.4652880207;1106.5794997900907;1107.9279846810093;1140.589387820384;1159.8144296912453;1195.1872726546032;14681.92659036698;15723.449679261172;17313.82686173147;18091.276348945896;18107.275708971756;399889;406286;406315;406711;411901;52083.73958130185;57971.420285797445;58692.70653646704;58740;58744.412555873896;1310.6461255460972;1392.9164250145025;1460.5472303585923;1468.5489100613686;1471.7571600685767;30449.54325685072;30464;30491.847540762155;31228.843855780575;31358;48010.75994620004;48673;48678.26982595193;48681.756591216814;48743;218995;219901;221129;237548;238548;34977.825110874284;35778.28443431169;35826.10434739153;36105.277894442486;36259.27481450409;46340.30489542727;46626.06747865092;47472.2879156806;47592.52407475881;47926.760366197945;38910;39756;45522.77238613918;45532.544674554156;47322;39243.60756392399;40045.55626598382;40271.79864100661;40274.395884062345;40629.593704063765;41378.793106034274;41691.58308416877;41723.16553668849;41845;42279.36580951227;41457.37813932854;42064;42549.36175957301;43639.781801090794;43901.56098438975;32848.67151328552;33124;33232.70028702757;33716;34190.31619367649 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: |2;jquery.html;388.70007448440504;389.44504081683596;390.6601256906492;394.8321963165654;397.8189923584769;14475.78286325706;17610.735838962424;17999.550011249725;18049.4585162445;19929.302474413398;18764.53088672784;18921.62156756864;19445.41663750086;19467.902660486714;19488.805111948877;6195.411435913581;6304.211973503312;6313.1792866927235;6317.33667964864;6343.6193828370215;6161.137440758297;6245.500359971192;6405.231372235315;6406.263279722822;6408.7756928507515;4581.702189357681;4707.741074240916;6387.904181437282;6391.296957334689;6398.520110991671;6700;6877.690503927335;6904.585724856524;6907.792766217021;6908.101946746941;5454.972725136388;5599.860003499904;5600.691961942091;5601.3558440779425;6601.108850305195;13590.59228223155;13715.039947203699;17269.222884970208;20915.26796562113;20938.16247350104;2236.675682026097;2253.1550668499312;2260.3106052653934;2280.977190228093;2293.4610366563843;10126.443045632488;10131.44277064761;10446.738831529197;11637.767244655059;13457.730845383136;1836.669399508088;1906.809319068082;1973.891435971021;1975.4666240115089;1986.2055177928798;60984.247648121025;61542.38457615456;64141.358586414004;66918;69959.95060074153;10970.957759012881;10989.06592939593;11023.118150547936;11040.171987101;11206.719832004264;13774.104683195561;13987.580372588838;14111.71776564463;14124.788128178085;14316.212608306643;8604.13958604147;8669.869951950726;8682.6092825823;8702.390832641655;8706.782330441789;8694.347923905732;8720.956395217983;8737.388382813142;8760.992485864079;8807.163319484638;4388.605025547716;4461;4580.610648094883;4772.188727916263;4914.70511769295;1756.2594153429695;1911.2546107018275;1919.193938545814;1925.1048262557697;1993.132987150594;879.4986857491297;986.8569057486624;1023.9129673977646;1060.8355704865758;1064.5794911010107;17219;18170.54573635643;18327.816721832878;18571.350002749838;18766.718499222523;980.4901451245313;1002.7793885345212;1013.5945621751358;1016.0855230292796;1045.900639439252;10079.092881640687;10183.949080254552;10296.742581435525;10308.948455257825;10359.741006474747;404385;405487;638210;638343;660331.570158688;86162.56918715367;86304.56847715721;86949;88693.55653221822;89353.55323223342;1239.4608345369793;1244.5519612939336;1249.6438515023356;1251.874812518759;1262.3625069339926;16549.420770272984;16595.91702041482;16685.666286673957;16715.665686686443;16899.408520701716;37469;38088;38704.61295387123;38825.223495530496;39062.46094003881;50340.24489632732;50912.490875092255;51078;52063.47936520738;55375;10178.643747468803;10275.897241027495;10431;10484.26610137283;10566.207534434794;12454.190477618926;13894.861051389356;18428.723569146205;18488.537786555444;19268.807311927263;14774.409023639364;14962.925185374004;14974.251287435802;15185.696286073995;15620.609484762972;15225.238738063274;15521.689566208386;15797;15956.281967311266;16157.111358875374;15409.614759631098;15458.768118478456;15805.051696898223;15878.285477153324;15902.443414480438;9986.700398988041;10530.514559234267;10582.259241852997;10625.787484250426;10803.081738052038;12501.187422817487;15717.449889254278;16005.199740013186;16082.43711470093;16327.510174694778 15:50:20 INFO - 2016-02-18 15:50:20,651 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1557.7663350497423;1581.4623028170427;1792.2920446423655;1833.6607727570401;1850.8704390692653;7888.763337099882;8262.958685206573;8267.214614611617;8274.793130171742;8277.296429803473;7144.678489467973;7282.162551306601;7798.649060792264;7938.047434307877;8152.021757389107;2345.8827058647125;2364.5507353602848;2381.226101517005;2385.940351491209;2390.2590197038885;2053.507158282009;2328.6274196128625;2361.645753137026;2362.244081893795;2370.4429459077105;2024.7772744998038;2285.1887579909144;2316.9652455213186;2319.420144963759;2320.7331156917003;2288.164819840763;2294.3117064880553;2296.219285442941;2353.035255545218;2356.7996720278798;2355.8704271265074;2361.929142125739;2387.78509934105;2388.4984153327823;2404.410919324759;7861.489003214772;7945.761627151137;7948.324392426593;7950.3242224411;7955.4033447491665;4842.83050093245;4915.3855768029;4922;4932.778024988884;4944.579710724593;3154.479510880702;3160.604924384452;3209.0533292678715;3284.868605255787;3312.271300313927;1083.3283364314175;2005.1979208316643;2010.4672261850621;2015.9193632254692;2047.5904819036252;11093.500792464358;11189.496472658668;11196.888031119666;11198.552057917674;11224.158188135922;4140.213359461693;4194.0563373240775;4209.305464598368;4246.044639955985;4286.635635970915;8860.468371897707;8868.15752503511;8870.911290887138;8871;8871.600777965006;4751.619870410359;4783.354247176587;4795.136875362468;4799.832005879777;4807.4471435784635;4408.779561022;4408.889777755582;4414.448193975753;4415.779211039499;4421.756803375781;3494.685478306965;3551.804650744234;3579.552555930509;3582.0686621478603;3589.964100359015;987.4914419074182;1030.829913064336;1034.6430481483876;1036.4817591204398;1041.6822869024868;1102.3165637304762;1104.8950349716763;1156.1213477756964;1293.5666551705285;1371.7873729571932;7410.5183163094225;7511.136219334737;7628.732994345282;7667.3099421052375;7702.730404435818;1208.6374087773634;1227.1226073357413;1235.5613757116273;1240.8945239654731;1242.2608547913976;5574.414686457944;5622.212890195376;5635.887282254332;5641.435856414326;5647.830565083054;9290.72127836166;9665.178459830853;9678.806423871483;9788.804223915482;9808.803823923481;9608.471534065693;9651;9679.564419601134;9692.95153524242;9706.611735530641;7251.093613298338;7465.029546159073;7467.962660186664;7471.066116735408;7483.064616922885;7063.293670632896;7116.430685545248;7162.928370716226;7163.892541611985;7168;8381.203785640355;8534.359923005924;8596.441231319945;8644;8653.956730216309;8544.35917306217;8620.827583448421;8718.738437846874;8726;8802.427842190236;7183.928160718326;7233.168185658506;7270.781876543712;7284.271572842674;7286.307800758919;7728.497647653109;7782;7838.216178382117;7838.372930165459;7853.921460785319;6574;7388.704451821867;7392.22381649919;7438.107427108782;7459.254074592497;6856;6994.825129371807;7151.106111736033;7406.592637404994;7563.848723025619;7442;7457.030586023782;7464.664090115849;7485.1017877855;7492.325690687864;6859.828504287433;6894.207166175954;6906.240313565604;6913.516053876381;6914.135733033371;6817.352351526597;6862.4853136014;6895.5173137879865;6898.827529311808;6909 15:50:20 INFO - 2016-02-18 15:50:20,653 DEBUG : BROWSER_OUTPUT: |4;prototype.html;368.40502588319873;370.74603896331007;373.13805110195443;373.6170425314053;375.74824867338896;6020.518358531318;6071.848203794907;6113.388661133884;6115.663638499892;6124.60190087644;5795.739191736371;5845.444682755132;5927.170196172539;6014.398560143984;6021.668808215546;4678.672492925497;4691.671582989192;4693.71837689738;4708.105459962613;4715.12770137524;4741.478437371887;4743.146233677937;4743.85768426946;4744.454387745402;4750.667453278272;4475.462944446655;4538.183127037132;4554.20301447247;4606.355110284563;4712.740799256039;4663.347131401607;4675.392199014123;4685.437747470309;4700.9059818803435;4702.858914232578;4129.669626429878;4153.958460415387;4170.124273902484;4172.979135104305;4174.019105510202;5663.292088488939;5671.347795003586;5671.546276297886;5697.4587414195585;5714.51426628737;5366.356037275513;5443.292371991616;5455.918161227585;5463.453654634544;5483.396826349099;6380.330065343119;6382.914904466205;6386.2975072741965;6387.616742995435;6388.105665206875;4627.953720462786;4633.934195135138;4637.721736695776;4640.69835460694;4642.930356044662;6479.35206479353;6609.702563384659;6611.471082313404;6622.93377066228;6638.10385597943;4663.883402914887;4688.944987377813;4689.507601701772;4690.179218636725;4692.9530704692515;4710.8351207707565;4738.19450693383;4742.383490146259;4743.193657078305;4746.644001699887;5429.945700542944;5434.972825135849;5435.619506634576;5438.293021907206;5443.047466693313;6367.395097465734;6467.094606755058;6472.288048314682;6474.1907261592305;6494.545381823227;1642.269190210345;1670.6408122253774;1672.916354182286;1673.1299724143378;1673.313941284092;1124.3422597780227;1125.9324440533594;1133.4162906103363;1150.3558007515817;1152.7175841918784;6215.12988181655;6224.626522408579;6238.313785483593;6238.594491358047;6242.875142497124;6648.767293144717;6653.201615806134;6672.566283191577;6674.532782705164;6681.699323530453;6078.513718902477;6098.75604975796;6126.326104128542;6204.317525072239;6230.7507699691505;3569.286142771456;3600.7299452541165;3605.747597668138;3609.4405367168133;3619.909502262412;6872.278410766798;6880.2431732509385;6884.759033433806;6885.2770459101075;6886.655667216619;6712.42944349726;6836.316368363124;6857.725690972405;6874.518783685094;6876.656167191621;6870.31296870309;6871.175458944958;6872.656367181621;6878.38094571491;6885.655717214019;6697;6703.229128650268;6703.899441508283;6709.463242940651;6713.295104014008;6491;6615.206175259;6616.603003819786;6625.569337993015;6628.767993120217;6870.965645171942;6874.828129296808;6878.174619045746;6879.724811007503;6881.243063262937;4687.648426368104;4689.812407503799;4692.53074692528;4698.906021879475;4700.059988002345;6573.80278591643;6705.228898676714;6717.395434410926;6722.1261236039;6723.865522689616;6530.510211734044;6542.247641521286;6546.705398257185;6548.1487406638735;6558.147440832661;6495.642739649365;6507.12153859223;6523;6523.7716679915975;6533.118029066015;6273;6385.521085918667;6386.393292637192;6390.073439351175;6434.099226108255;6555.082288479522;6557.311482294481;6559.573627714183;6560.3111673275525;6560.967195163994;6277.30949595535;6405.743770249324;6408.03879418091;6410.61536307823;6423.903641445476 15:50:20 INFO - 2016-02-18 15:50:20,654 DEBUG : BROWSER_OUTPUT: |5;yui.html;803.8472690188863;838.2037064788449;841.3437518735386;888.8044630181362;890.9599068041938;12894.613161605155;13419.932900335509;13443;13468.461261549552;13512.59462216134;13395.129316594415;14662.633434164125;14961.326740296685;14963.177025263607;15023;752.8080339513416;764.5871229536044;890.2966656341482;893.4415990006246;899.7165892743765;871.3464901324007;891.393852180517;896.1083721696921;898.0660113481971;899.0245583541865;935.046252822121;937.3485427627801;942.8868535775716;943.5659596585579;943.9103285187896;517.5135372749622;521.0438844719924;925.8703781470566;951.3150531617224;971.1453920549942;696.03946553756;696.4567637242959;696.4985210648325;697.2817997462622;699.3810477727197;13286.136401133896;13495.190288582717;13580.049396542146;13597.86402135976;13601.31993400326;13082.607521774362;13185.802212966813;13563.728725425437;13822.028263923205;14101;11136.832947505794;11545.884541154564;11572.826407603892;11580.536778528933;11824.290542567387;3850.5764365919727;3883.184531248413;3885;3892.3577609694366;3895.61043895611;50378.24432633514;50745.23882141771;51918.74040629811;52204.73897630488;52253.477465225624;10017.799644007078;10121.03850134236;10640;11437.656870293904;11626.53493860253;20628.69056964147;20833.58332833335;20963.161473540888;20970.89514552448;20985.580288394453;7938.325242354349;8106.63520141595;8150.103488616366;8169.714060007871;8320.916790832136;4612.930806037912;4649.04694537619;4665.066986602626;4685.789139488663;4709.128811169876;3325.334933013407;3354.5135955286346;3359.0930448778695;3359.882404115844;3364.310316385135;563.5885803363567;578.9189513468118;584.541135208859;585.1895125251557;587.5945597537685;698.2214830463996;716.7527203114917;734.4565021883874;737.5869513072696;746.1121265693807;9027.954860225787;9100.635974560944;9105.408148470327;9243.722688319362;9339.439633622005;591.6893630843841;707.4587940225731;712.6116266634697;732.739877343546;742.9591372474458;4514.81940722374;4585.816567337336;4715.151272770866;4769.689970151929;4863.756812159378;61830;62845.68577157176;62870.37129628737;62915.37084629095;62964.37035629585;54381.728091360084;54549.72725136349;55378.72310638421;55560;55570.16644750332;50073.24890126651;50214.24678629824;50362;50499;51399.743001285504;8826.911730882866;8858.424202426822;8883.822323553622;9191.356605037583;9336.206422454168;36395;37274.25451491009;37409.625903741704;37748.245035099695;38111.61888381081;48162.27756583584;48210;48459.27311090266;49778;51943.74028129835;11790.115741319607;11975.28148311104;12234.571789987307;12251.938740306598;12444.440000200193;12894.419751111034;13228.177626452522;13386.799198011842;13436.395362208528;13597;12163.513459461523;12195;12337.938310308391;12533.435995380047;12579.056570757048;11973.221740586834;12136.575219867262;12214.26714397139;12301;12425.751484970431;11834.467448964646;11944.223625464318;11989.34058626749;12298.754024919273;12405;10877.238593298393;11073.667789966314;11268.549258029587;11280.774384512428;11337.886621133683;10196.133328667147;10513.421761803174;10521.368717876952;10598.364098154136;10603.416812075102 15:50:20 INFO - 2016-02-18 15:50:20,654 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:50:20 INFO - 2016-02-18 15:50:20,654 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:50:20 INFO - 2016-02-18 15:50:20,654 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1224 15:50:20 INFO - 2016-02-18 15:50:20,654 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:50:20 INFO - 2016-02-18 15:50:20,654 DEBUG : BROWSER_OUTPUT: __startTimestamp1455839420645__endTimestamp 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: Number of tests: 6 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5611.82 Median:5823.88 stddev:612.17 (10.5%) stddev-sans-first:587.14 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: Values: 3323.6 3416.3 3438.4 3448.8 3467.1 5576.2 5635.4 5671.7 5688.7 5691.6 5634.2 5659.8 5684.2 5701.1 5724.6 5076.1 5141.9 5148.6 5198.9 5247.6 5191.1 5225.3 5229.0 5235.7 5237.2 5057.8 5067.1 5169.8 5177.8 5236.3 5173.0 5188.7 5192.2 5194.5 5207.7 5019.3 5106.1 5163.0 5226.4 5227.1 5342.4 5444.9 5450.2 5453.2 5463.0 6016.8 6050.8 6057.9 6058.0 6065.2 5813.4 5890.0 6065.7 6067.7 6068.1 5566.0 5644.2 5645.8 5659.1 5687.1 6093.1 6104.1 6111.6 6121.7 6121.9 5712.2 5776.7 5823.9 5961.0 5968.9 6021.4 6135.4 6139.7 6145.1 6163.9 6039.0 6070.1 6083.5 6083.8 6106.7 5883.9 5956.6 6017.2 6021.2 6071.6 4608.4 4609.5 4610.3 4610.5 4612.5 4147.0 4156.9 4159.5 4171.0 4191.6 5558.3 5622.0 5631.3 5647.0 5652.2 6045.8 6074.5 6088.7 6112.3 6117.6 5490.4 5582.2 5600.6 5605.5 5649.5 5253.1 5258.7 5272.9 5285.7 5289.3 6217.5 6251.0 6269.6 6291.9 6296.5 5864.0 5921.5 5996.1 6036.3 6076.3 6143.1 6241.0 6241.9 6266.5 6276.9 5930.2 6017.4 6028.9 6032.8 6046.9 5871.9 5909.6 5985.9 5990.7 6081.7 5849.0 5925.3 5931.8 5943.5 5944.2 5820.0 5827.8 5858.0 5860.5 5860.6 5930.6 5938.4 5940.2 5940.9 5946.3 5978.2 6031.5 6044.2 6050.5 6060.1 5628.9 5701.8 5776.7 5903.9 5912.5 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,655 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50515.08 Median:19966.64 stddev:83685.71 (419.1%) stddev-sans-first:83836.41 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: Values: 559.6 619.9 623.5 633.9 634.2 29528.0 29919.7 29925.3 29987.9 30006.8 34984.5 43045.0 43917.6 44033.1 44070.6 9190.0 9301.6 9321.2 9324.3 9333.4 8078.4 8228.4 9052.4 9127.2 9167.9 10959.6 11184.6 11194.9 11198.4 11200.4 10693.6 10753.5 10786.2 10786.9 10790.9 10636.0 10769.8 10778.2 10780.5 10783.9 42983.8 43939.6 43956.3 43965.8 44029.3 3674.0 3683.8 3736.1 4150.5 4355.4 32808.0 33180.2 33592.2 33640.2 34019.3 3889.7 4007.5 4044.4 4072.2 4233.6 296788.0 298513.5 298714.0 298936.0 299535.0 65157.7 66555.7 67638.7 75436.0 77132.0 67481.0 67623.0 67916.0 67930.0 67941.3 18339.3 18567.0 18573.2 18576.2 18585.6 13894.7 13989.0 14009.7 14017.4 14032.0 22470.4 22761.5 22767.8 22846.5 22902.4 8583.6 8678.3 8737.4 8803.7 8835.8 1032.8 1092.4 1094.9 1095.3 1144.0 31473.5 35170.3 35297.8 35502.3 35647.5 1106.6 1107.9 1140.6 1159.8 1195.2 14681.9 15723.4 17313.8 18091.3 18107.3 399889.0 406286.0 406315.0 406711.0 411901.0 52083.7 57971.4 58692.7 58740.0 58744.4 1310.6 1392.9 1460.5 1468.5 1471.8 30449.5 30464.0 30491.8 31228.8 31358.0 48010.8 48673.0 48678.3 48681.8 48743.0 218995.0 219901.0 221129.0 237548.0 238548.0 34977.8 35778.3 35826.1 36105.3 36259.3 46340.3 46626.1 47472.3 47592.5 47926.8 38910.0 39756.0 45522.8 45532.5 47322.0 39243.6 40045.6 40271.8 40274.4 40629.6 41378.8 41691.6 41723.2 41845.0 42279.4 41457.4 42064.0 42549.4 43639.8 43901.6 32848.7 33124.0 33232.7 33716.0 34190.3 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30262.91 Median:19357.11 stddev:91970.06 (475.1%) stddev-sans-first:92200.70 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: Values: 388.7 389.4 390.7 394.8 397.8 14475.8 17610.7 17999.6 18049.5 19929.3 18764.5 18921.6 19445.4 19467.9 19488.8 6195.4 6304.2 6313.2 6317.3 6343.6 6161.1 6245.5 6405.2 6406.3 6408.8 4581.7 4707.7 6387.9 6391.3 6398.5 6700.0 6877.7 6904.6 6907.8 6908.1 5455.0 5599.9 5600.7 5601.4 6601.1 13590.6 13715.0 17269.2 20915.3 20938.2 2236.7 2253.2 2260.3 2281.0 2293.5 10126.4 10131.4 10446.7 11637.8 13457.7 1836.7 1906.8 1973.9 1975.5 1986.2 60984.2 61542.4 64141.4 66918.0 69960.0 10971.0 10989.1 11023.1 11040.2 11206.7 13774.1 13987.6 14111.7 14124.8 14316.2 8604.1 8669.9 8682.6 8702.4 8706.8 8694.3 8721.0 8737.4 8761.0 8807.2 4388.6 4461.0 4580.6 4772.2 4914.7 1756.3 1911.3 1919.2 1925.1 1993.1 879.5 986.9 1023.9 1060.8 1064.6 17219.0 18170.5 18327.8 18571.4 18766.7 980.5 1002.8 1013.6 1016.1 1045.9 10079.1 10183.9 10296.7 10308.9 10359.7 404385.0 405487.0 638210.0 638343.0 660331.6 86162.6 86304.6 86949.0 88693.6 89353.6 1239.5 1244.6 1249.6 1251.9 1262.4 16549.4 16595.9 16685.7 16715.7 16899.4 37469.0 38088.0 38704.6 38825.2 39062.5 50340.2 50912.5 51078.0 52063.5 55375.0 10178.6 10275.9 10431.0 10484.3 10566.2 12454.2 13894.9 18428.7 18488.5 19268.8 14774.4 14962.9 14974.3 15185.7 15620.6 15225.2 15521.7 15797.0 15956.3 16157.1 15409.6 15458.8 15805.1 15878.3 15902.4 9986.7 10530.5 10582.3 10625.8 10803.1 12501.2 15717.4 16005.2 16082.4 16327.5 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5629.60 Median:6836.68 stddev:2874.72 (42.0%) stddev-sans-first:2866.49 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: Values: 1557.8 1581.5 1792.3 1833.7 1850.9 7888.8 8263.0 8267.2 8274.8 8277.3 7144.7 7282.2 7798.6 7938.0 8152.0 2345.9 2364.6 2381.2 2385.9 2390.3 2053.5 2328.6 2361.6 2362.2 2370.4 2024.8 2285.2 2317.0 2319.4 2320.7 2288.2 2294.3 2296.2 2353.0 2356.8 2355.9 2361.9 2387.8 2388.5 2404.4 7861.5 7945.8 7948.3 7950.3 7955.4 4842.8 4915.4 4922.0 4932.8 4944.6 3154.5 3160.6 3209.1 3284.9 3312.3 1083.3 2005.2 2010.5 2015.9 2047.6 11093.5 11189.5 11196.9 11198.6 11224.2 4140.2 4194.1 4209.3 4246.0 4286.6 8860.5 8868.2 8870.9 8871.0 8871.6 4751.6 4783.4 4795.1 4799.8 4807.4 4408.8 4408.9 4414.4 4415.8 4421.8 3494.7 3551.8 3579.6 3582.1 3590.0 987.5 1030.8 1034.6 1036.5 1041.7 1102.3 1104.9 1156.1 1293.6 1371.8 7410.5 7511.1 7628.7 7667.3 7702.7 1208.6 1227.1 1235.6 1240.9 1242.3 5574.4 5622.2 5635.9 5641.4 5647.8 9290.7 9665.2 9678.8 9788.8 9808.8 9608.5 9651.0 9679.6 9693.0 9706.6 7251.1 7465.0 7468.0 7471.1 7483.1 7063.3 7116.4 7162.9 7163.9 7168.0 8381.2 8534.4 8596.4 8644.0 8654.0 8544.4 8620.8 8718.7 8726.0 8802.4 7183.9 7233.2 7270.8 7284.3 7286.3 7728.5 7782.0 7838.2 7838.4 7853.9 6574.0 7388.7 7392.2 7438.1 7459.3 6856.0 6994.8 7151.1 7406.6 7563.8 7442.0 7457.0 7464.7 7485.1 7492.3 6859.8 6894.2 6906.2 6913.5 6914.1 6817.4 6862.5 6895.5 6898.8 6909.0 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,657 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5424.43 Median:6120.13 stddev:1609.44 (26.3%) stddev-sans-first:1568.58 15:50:20 INFO - 2016-02-18 15:50:20,659 DEBUG : BROWSER_OUTPUT: Values: 368.4 370.7 373.1 373.6 375.7 6020.5 6071.8 6113.4 6115.7 6124.6 5795.7 5845.4 5927.2 6014.4 6021.7 4678.7 4691.7 4693.7 4708.1 4715.1 4741.5 4743.1 4743.9 4744.5 4750.7 4475.5 4538.2 4554.2 4606.4 4712.7 4663.3 4675.4 4685.4 4700.9 4702.9 4129.7 4154.0 4170.1 4173.0 4174.0 5663.3 5671.3 5671.5 5697.5 5714.5 5366.4 5443.3 5455.9 5463.5 5483.4 6380.3 6382.9 6386.3 6387.6 6388.1 4628.0 4633.9 4637.7 4640.7 4642.9 6479.4 6609.7 6611.5 6622.9 6638.1 4663.9 4688.9 4689.5 4690.2 4693.0 4710.8 4738.2 4742.4 4743.2 4746.6 5429.9 5435.0 5435.6 5438.3 5443.0 6367.4 6467.1 6472.3 6474.2 6494.5 1642.3 1670.6 1672.9 1673.1 1673.3 1124.3 1125.9 1133.4 1150.4 1152.7 6215.1 6224.6 6238.3 6238.6 6242.9 6648.8 6653.2 6672.6 6674.5 6681.7 6078.5 6098.8 6126.3 6204.3 6230.8 3569.3 3600.7 3605.7 3609.4 3619.9 6872.3 6880.2 6884.8 6885.3 6886.7 6712.4 6836.3 6857.7 6874.5 6876.7 6870.3 6871.2 6872.7 6878.4 6885.7 6697.0 6703.2 6703.9 6709.5 6713.3 6491.0 6615.2 6616.6 6625.6 6628.8 6871.0 6874.8 6878.2 6879.7 6881.2 4687.6 4689.8 4692.5 4698.9 4700.1 6573.8 6705.2 6717.4 6722.1 6723.9 6530.5 6542.2 6546.7 6548.1 6558.1 6495.6 6507.1 6523.0 6523.8 6533.1 6273.0 6385.5 6386.4 6390.1 6434.1 6555.1 6557.3 6559.6 6560.3 6561.0 6277.3 6405.7 6408.0 6410.6 6423.9 15:50:20 INFO - 2016-02-18 15:50:20,659 DEBUG : BROWSER_OUTPUT: 15:50:20 INFO - 2016-02-18 15:50:20,659 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14954.16 Median:4697.46 stddev:17295.03 (368.2%) stddev-sans-first:17310.90 15:50:20 INFO - 2016-02-18 15:50:20,659 DEBUG : BROWSER_OUTPUT: Values: 803.8 838.2 841.3 888.8 891.0 12894.6 13419.9 13443.0 13468.5 13512.6 13395.1 14662.6 14961.3 14963.2 15023.0 752.8 764.6 890.3 893.4 899.7 871.3 891.4 896.1 898.1 899.0 935.0 937.3 942.9 943.6 943.9 517.5 521.0 925.9 951.3 971.1 696.0 696.5 696.5 697.3 699.4 13286.1 13495.2 13580.0 13597.9 13601.3 13082.6 13185.8 13563.7 13822.0 14101.0 11136.8 11545.9 11572.8 11580.5 11824.3 3850.6 3883.2 3885.0 3892.4 3895.6 50378.2 50745.2 51918.7 52204.7 52253.5 10017.8 10121.0 10640.0 11437.7 11626.5 20628.7 20833.6 20963.2 20970.9 20985.6 7938.3 8106.6 8150.1 8169.7 8320.9 4612.9 4649.0 4665.1 4685.8 4709.1 3325.3 3354.5 3359.1 3359.9 3364.3 563.6 578.9 584.5 585.2 587.6 698.2 716.8 734.5 737.6 746.1 9028.0 9100.6 9105.4 9243.7 9339.4 591.7 707.5 712.6 732.7 743.0 4514.8 4585.8 4715.2 4769.7 4863.8 61830.0 62845.7 62870.4 62915.4 62964.4 54381.7 54549.7 55378.7 55560.0 55570.2 50073.2 50214.2 50362.0 50499.0 51399.7 8826.9 8858.4 8883.8 9191.4 9336.2 36395.0 37274.3 37409.6 37748.2 38111.6 48162.3 48210.0 48459.3 49778.0 51943.7 11790.1 11975.3 12234.6 12251.9 12444.4 12894.4 13228.2 13386.8 13436.4 13597.0 12163.5 12195.0 12337.9 12533.4 12579.1 11973.2 12136.6 12214.3 12301.0 12425.8 11834.5 11944.2 11989.3 12298.8 12405.0 10877.2 11073.7 11268.5 11280.8 11337.9 10196.1 10513.4 10521.4 10598.4 10603.4 15:50:20 INFO - 2016-02-18 15:50:20,659 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:50:20 INFO - 2016-02-18 15:50:20,659 DEBUG : BROWSER_OUTPUT: 15:50:21 INFO - 2016-02-18 15:50:21,202 INFO : Browser exited with error code: 0 15:50:21 INFO - 2016-02-18 15:50:21,220 INFO : Completed test dromaeo_css (00:18:31) 15:50:21 INFO - 2016-02-18 15:50:21,220 INFO : Starting test kraken 15:50:21 INFO - 2016-02-18 15:50:21,220 DEBUG : operating with platform_type : linux_ 15:50:21 INFO - 2016-02-18 15:50:21,220 INFO : Initialising browser for kraken test... 15:50:21 INFO - 2016-02-18 15:50:21,227 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBhxgMR/profile http://localhost:52552/getInfo.html 15:50:24 INFO - 2016-02-18 15:50:24,945 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:50:24 INFO - 2016-02-18 15:50:24,945 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:50:24 INFO - 2016-02-18 15:50:24,945 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:50:24 INFO - 2016-02-18 15:50:24,945 DEBUG : BROWSER_OUTPUT: __metrics 15:50:25 INFO - 2016-02-18 15:50:25,114 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:50:26 INFO - 2016-02-18 15:50:26,234 INFO : Browser initialized. 15:50:26 INFO - 2016-02-18 15:50:26,234 INFO : Running cycle 1/1 for kraken test... 15:50:26 INFO - 2016-02-18 15:50:26,234 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBhxgMR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:50:37 INFO - 2016-02-18 15:50:37,895 DEBUG : BROWSER_OUTPUT: RSS: Main: 133619712 15:50:37 INFO - 2016-02-18 15:50:37,895 DEBUG : BROWSER_OUTPUT: 15:50:40 INFO - 2016-02-18 15:50:40,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52552/tests/kraken/driver.html?testName=audio-beat-detection) 15:50:40 INFO - 2016-02-18 15:50:40,924 DEBUG : BROWSER_OUTPUT: RSS: Main: 165277696 15:50:40 INFO - 2016-02-18 15:50:40,925 DEBUG : BROWSER_OUTPUT: 15:50:43 INFO - 2016-02-18 15:50:43,534 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52552/tests/kraken/driver.html?testName=audio-dft) 15:50:44 INFO - 2016-02-18 15:50:44,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 160935936 15:50:44 INFO - 2016-02-18 15:50:44,100 DEBUG : BROWSER_OUTPUT: 15:50:46 INFO - 2016-02-18 15:50:46,889 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52552/tests/kraken/driver.html?testName=audio-fft) 15:50:47 INFO - 2016-02-18 15:50:47,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 159891456 15:50:47 INFO - 2016-02-18 15:50:47,451 DEBUG : BROWSER_OUTPUT: 15:50:49 INFO - 2016-02-18 15:50:49,408 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52552/tests/kraken/driver.html?testName=audio-oscillator) 15:50:49 INFO - 2016-02-18 15:50:49,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 158453760 15:50:49 INFO - 2016-02-18 15:50:49,974 DEBUG : BROWSER_OUTPUT: 15:50:52 INFO - 2016-02-18 15:50:52,147 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52552/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:50:52 INFO - 2016-02-18 15:50:52,700 DEBUG : BROWSER_OUTPUT: RSS: Main: 158822400 15:50:52 INFO - 2016-02-18 15:50:52,700 DEBUG : BROWSER_OUTPUT: 15:50:55 INFO - 2016-02-18 15:50:55,538 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52552/tests/kraken/driver.html?testName=imaging-darkroom) 15:50:56 INFO - 2016-02-18 15:50:56,104 DEBUG : BROWSER_OUTPUT: RSS: Main: 158191616 15:50:56 INFO - 2016-02-18 15:50:56,104 DEBUG : BROWSER_OUTPUT: 15:50:59 INFO - 2016-02-18 15:50:59,445 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52552/tests/kraken/driver.html?testName=imaging-desaturate) 15:51:00 INFO - 2016-02-18 15:51:00,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 158539776 15:51:00 INFO - 2016-02-18 15:51:00,009 DEBUG : BROWSER_OUTPUT: 15:51:02 INFO - 2016-02-18 15:51:02,692 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52552/tests/kraken/driver.html?testName=json-parse-financial) 15:51:03 INFO - 2016-02-18 15:51:03,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 157945856 15:51:03 INFO - 2016-02-18 15:51:03,259 DEBUG : BROWSER_OUTPUT: 15:51:04 INFO - 2016-02-18 15:51:04,813 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52552/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:51:05 INFO - 2016-02-18 15:51:05,374 DEBUG : BROWSER_OUTPUT: RSS: Main: 163745792 15:51:05 INFO - 2016-02-18 15:51:05,374 DEBUG : BROWSER_OUTPUT: 15:51:07 INFO - 2016-02-18 15:51:07,144 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:51:07 INFO - 2016-02-18 15:51:07,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 163274752 15:51:07 INFO - 2016-02-18 15:51:07,731 DEBUG : BROWSER_OUTPUT: 15:51:09 INFO - 2016-02-18 15:51:09,703 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:51:10 INFO - 2016-02-18 15:51:10,269 DEBUG : BROWSER_OUTPUT: RSS: Main: 165752832 15:51:10 INFO - 2016-02-18 15:51:10,269 DEBUG : BROWSER_OUTPUT: 15:51:12 INFO - 2016-02-18 15:51:12,623 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:51:13 INFO - 2016-02-18 15:51:13,204 DEBUG : BROWSER_OUTPUT: RSS: Main: 172716032 15:51:13 INFO - 2016-02-18 15:51:13,205 DEBUG : BROWSER_OUTPUT: 15:51:15 INFO - 2016-02-18 15:51:15,701 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:51:16 INFO - 2016-02-18 15:51:16,267 DEBUG : BROWSER_OUTPUT: RSS: Main: 170323968 15:51:16 INFO - 2016-02-18 15:51:16,267 DEBUG : BROWSER_OUTPUT: 15:51:17 INFO - 2016-02-18 15:51:17,924 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52552/tests/kraken/driver.html?testName=ai-astar) 15:51:18 INFO - 2016-02-18 15:51:18,175 DEBUG : BROWSER_OUTPUT: RSS: Main: 228954112 15:51:18 INFO - 2016-02-18 15:51:18,175 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,179 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:51:18 INFO - 2016-02-18 15:51:18,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:51:18 INFO - 2016-02-18 15:51:18,179 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:51:18 INFO - 2016-02-18 15:51:18,179 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:51:18 INFO - 2016-02-18 15:51:18,179 DEBUG : BROWSER_OUTPUT: |0;ai-astar;107;111;104;107;111;108;98;109;109;98 15:51:18 INFO - 2016-02-18 15:51:18,179 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;146;121;155;159;134;159;147;127;164;125 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;158;159;158;162;161;160;157;159;160 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;71;146;72;70;72;71;153;73 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;134;92;93;92;140;92;92;144 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;110;109;109;109 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;146;146;146;146;145;147;146 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;95;97;95;96;95 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;56;57;56;57;57;56;58;54;54 15:51:18 INFO - 2016-02-18 15:51:18,180 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;65;66;66;65;65;73;65;65 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;78;79;78;80;80;80;81;82;80;79 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;112;115;117;118;116;118;111;122;119 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;136;141;143;144;145;135;144;151;144;138 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;65;64;65;64;65;67;64;63 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: __start_cc_report 15:51:18 INFO - 2016-02-18 15:51:18,181 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,872 15:51:18 INFO - 2016-02-18 15:51:18,182 DEBUG : BROWSER_OUTPUT: __end_cc_report 15:51:18 INFO - 2016-02-18 15:51:18,182 DEBUG : BROWSER_OUTPUT: __startTimestamp1455839478177__endTimestamp 15:51:18 INFO - 2016-02-18 15:51:18,182 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,185 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:51:18 INFO - 2016-02-18 15:51:18,185 DEBUG : BROWSER_OUTPUT: Number of tests: 14 15:51:18 INFO - 2016-02-18 15:51:18,186 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,186 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.20 Median:110.00 stddev:4.78 (4.3%) stddev-sans-first:5.06 15:51:18 INFO - 2016-02-18 15:51:18,186 DEBUG : BROWSER_OUTPUT: Values: 107.0 111.0 104.0 107.0 111.0 108.0 98.0 109.0 109.0 98.0 15:51:18 INFO - 2016-02-18 15:51:18,186 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,186 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.70 Median:151.00 stddev:15.85 (10.5%) stddev-sans-first:16.79 15:51:18 INFO - 2016-02-18 15:51:18,186 DEBUG : BROWSER_OUTPUT: Values: 146.0 121.0 155.0 159.0 134.0 159.0 147.0 127.0 164.0 125.0 15:51:18 INFO - 2016-02-18 15:51:18,187 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,187 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.40 Median:160.00 stddev:1.51 (0.9%) stddev-sans-first:1.58 15:51:18 INFO - 2016-02-18 15:51:18,187 DEBUG : BROWSER_OUTPUT: Values: 160.0 158.0 159.0 158.0 162.0 161.0 160.0 157.0 159.0 160.0 15:51:18 INFO - 2016-02-18 15:51:18,187 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,187 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.10 Median:71.50 stddev:32.94 (46.1%) stddev-sans-first:34.48 15:51:18 INFO - 2016-02-18 15:51:18,187 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 146.0 72.0 70.0 72.0 71.0 153.0 73.0 15:51:18 INFO - 2016-02-18 15:51:18,188 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,188 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:92.00 stddev:22.85 (24.8%) stddev-sans-first:23.64 15:51:18 INFO - 2016-02-18 15:51:18,188 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 134.0 92.0 93.0 92.0 140.0 92.0 92.0 144.0 15:51:18 INFO - 2016-02-18 15:51:18,188 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,188 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:51:18 INFO - 2016-02-18 15:51:18,189 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 15:51:18 INFO - 2016-02-18 15:51:18,189 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,189 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 15:51:18 INFO - 2016-02-18 15:51:18,189 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 147.0 146.0 15:51:18 INFO - 2016-02-18 15:51:18,189 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,189 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 95.0 97.0 95.0 96.0 95.0 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.32 (2.3%) stddev-sans-first:1.36 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: Values: 57.0 56.0 57.0 56.0 57.0 57.0 56.0 58.0 54.0 54.0 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.00 stddev:2.71 (4.2%) stddev-sans-first:2.69 15:51:18 INFO - 2016-02-18 15:51:18,190 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 65.0 66.0 66.0 65.0 65.0 73.0 65.0 65.0 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:80.00 stddev:1.25 (1.6%) stddev-sans-first:1.17 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: Values: 78.0 79.0 78.0 80.0 80.0 80.0 81.0 82.0 80.0 79.0 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:117.50 stddev:3.41 (2.9%) stddev-sans-first:3.43 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: Values: 113.0 112.0 115.0 117.0 118.0 116.0 118.0 111.0 122.0 119.0 15:51:18 INFO - 2016-02-18 15:51:18,191 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.10 Median:144.00 stddev:4.77 (3.3%) stddev-sans-first:4.52 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: Values: 136.0 141.0 143.0 144.0 145.0 135.0 144.0 151.0 144.0 138.0 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.50 Median:64.50 stddev:1.08 (1.7%) stddev-sans-first:1.13 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 65.0 64.0 65.0 64.0 65.0 67.0 64.0 63.0 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:51:18 INFO - 2016-02-18 15:51:18,192 DEBUG : BROWSER_OUTPUT: 15:51:18 INFO - 2016-02-18 15:51:18,759 INFO : Browser exited with error code: 0 15:51:18 INFO - 2016-02-18 15:51:18,806 INFO : Completed test kraken (00:00:57) 15:51:18 INFO - 2016-02-18 15:51:18,806 INFO : Starting test v8_7 15:51:18 INFO - 2016-02-18 15:51:18,806 DEBUG : operating with platform_type : linux_ 15:51:18 INFO - 2016-02-18 15:51:18,806 INFO : Initialising browser for v8_7 test... 15:51:18 INFO - 2016-02-18 15:51:18,812 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZLoqbu/profile http://localhost:52552/getInfo.html 15:51:22 INFO - 2016-02-18 15:51:22,861 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 15:51:22 INFO - 2016-02-18 15:51:22,861 DEBUG : BROWSER_OUTPUT: colorDepth:24 15:51:22 INFO - 2016-02-18 15:51:22,861 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 15:51:22 INFO - 2016-02-18 15:51:22,861 DEBUG : BROWSER_OUTPUT: __metrics 15:51:22 INFO - 2016-02-18 15:51:22,998 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:51:23 INFO - 2016-02-18 15:51:23,850 INFO : Browser initialized. 15:51:23 INFO - 2016-02-18 15:51:23,850 INFO : Running cycle 1/1 for v8_7 test... 15:51:23 INFO - 2016-02-18 15:51:23,851 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZLoqbu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 15:51:35 INFO - 2016-02-18 15:51:35,469 DEBUG : BROWSER_OUTPUT: RSS: Main: 132685824 15:51:35 INFO - 2016-02-18 15:51:35,470 DEBUG : BROWSER_OUTPUT: 15:51:56 INFO - 2016-02-18 15:51:56,834 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:52552/tests/v8_7/run.html (next: http://localhost:52552/tests/v8_7/run.html) 15:51:57 INFO - 2016-02-18 15:51:57,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 424751104 15:51:57 INFO - 2016-02-18 15:51:57,084 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,085 DEBUG : BROWSER_OUTPUT: __start_tp_report 15:51:57 INFO - 2016-02-18 15:51:57,086 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 15:51:57 INFO - 2016-02-18 15:51:57,086 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 15:51:57 INFO - 2016-02-18 15:51:57,086 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 15:51:57 INFO - 2016-02-18 15:51:57,086 DEBUG : BROWSER_OUTPUT: |0;Richards;164.2845408247084 15:51:57 INFO - 2016-02-18 15:51:57,086 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.50074025333114 15:51:57 INFO - 2016-02-18 15:51:57,087 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.2690582959641 15:51:57 INFO - 2016-02-18 15:51:57,087 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 15:51:57 INFO - 2016-02-18 15:51:57,087 DEBUG : BROWSER_OUTPUT: |4;RayTrace;935.4536950420954 15:51:57 INFO - 2016-02-18 15:51:57,087 DEBUG : BROWSER_OUTPUT: |5;Earley;539.9568034557235 15:51:57 INFO - 2016-02-18 15:51:57,087 DEBUG : BROWSER_OUTPUT: |6;Boyer;10541.666666666666 15:51:57 INFO - 2016-02-18 15:51:57,087 DEBUG : BROWSER_OUTPUT: |7;RegExp;24047.619047619046 15:51:57 INFO - 2016-02-18 15:51:57,088 DEBUG : BROWSER_OUTPUT: |8;Splay;532.4813631522896 15:51:57 INFO - 2016-02-18 15:51:57,088 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 15:51:57 INFO - 2016-02-18 15:51:57,088 DEBUG : BROWSER_OUTPUT: __end_tp_report 15:51:57 INFO - 2016-02-18 15:51:57,088 DEBUG : BROWSER_OUTPUT: __startTimestamp1455839517084__endTimestamp 15:51:57 INFO - 2016-02-18 15:51:57,088 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,089 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 15:51:57 INFO - 2016-02-18 15:51:57,089 DEBUG : BROWSER_OUTPUT: Number of tests: 10 15:51:57 INFO - 2016-02-18 15:51:57,089 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,089 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.28 Median:164.28 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,089 DEBUG : BROWSER_OUTPUT: Values: 164.3 15:51:57 INFO - 2016-02-18 15:51:57,089 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,090 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.50 Median:164.50 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,090 DEBUG : BROWSER_OUTPUT: Values: 164.5 15:51:57 INFO - 2016-02-18 15:51:57,090 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,090 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.27 Median:280.27 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,090 DEBUG : BROWSER_OUTPUT: Values: 280.3 15:51:57 INFO - 2016-02-18 15:51:57,090 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,091 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,091 DEBUG : BROWSER_OUTPUT: Values: 5138.5 15:51:57 INFO - 2016-02-18 15:51:57,091 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,091 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:935.45 Median:935.45 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,091 DEBUG : BROWSER_OUTPUT: Values: 935.5 15:51:57 INFO - 2016-02-18 15:51:57,091 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,092 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:539.96 Median:539.96 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: Values: 540.0 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10541.67 Median:10541.67 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: Values: 10541.7 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24047.62 Median:24047.62 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: Values: 24047.6 15:51:57 INFO - 2016-02-18 15:51:57,093 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:532.48 Median:532.48 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: Values: 532.5 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: Values: 4826.9 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 15:51:57 INFO - 2016-02-18 15:51:57,094 DEBUG : BROWSER_OUTPUT: 15:51:57 INFO - 2016-02-18 15:51:57,782 INFO : Browser exited with error code: 0 15:51:57 INFO - 2016-02-18 15:51:57,793 INFO : Completed test v8_7 (00:00:38) 15:51:58 INFO - 2016-02-18 15:51:58,174 INFO : Completed test suite (00:20:08) 15:51:58 INFO - 2016-02-18 15:51:58,174 DEBUG : Working with test: dromaeo_css 15:51:58 INFO - 2016-02-18 15:51:58,174 DEBUG : Generating results file: dromaeo_css 15:51:58 INFO - 2016-02-18 15:51:58,175 DEBUG : Working with test: kraken 15:51:58 INFO - 2016-02-18 15:51:58,175 DEBUG : Generating results file: kraken 15:51:58 INFO - 2016-02-18 15:51:58,175 INFO : javascript benchmark 15:51:58 INFO - 2016-02-18 15:51:58,176 DEBUG : Working with test: v8_7 15:51:58 INFO - 2016-02-18 15:51:58,176 DEBUG : Generating results file: v8_7 15:51:58 INFO - 2016-02-18 15:51:58,176 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:51:58 INFO - 2016-02-18 15:51:58,237 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 15:51:58 INFO - 2016-02-18 15:51:58,237 DEBUG : process_Request line: dromaeo_css 7465.25 graph.html#tests=[[72,53,35]] 15:51:58 INFO - 2016-02-18 15:51:58,237 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:51:58 INFO - 2016-02-18 15:51:58,285 DEBUG : process_Request line: kraken 1477.70 graph.html#tests=[[232,53,35]] 15:51:58 INFO - 2016-02-18 15:51:58,286 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 15:51:58 INFO - 2016-02-18 15:51:58,329 DEBUG : process_Request line: v8_7 23368.87 graph.html#tests=[[230,53,35]] 15:51:58 INFO - 2016-02-18 15:51:58,330 INFO : javascript benchmark 15:51:58 INFO - 2016-02-18 15:51:58,334 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3323.601167859857, 3416.333814906094, 3438.381091403546, 3448.775829571078, 3467.133216695826, 5576.191452239423, 5635.380108188101, 5671.744771485282, 5688.687122208277, 5691.573132015094, 5634.239377684022, 5659.830205093844, 5684.175794509804, 5701.0878259478495, 5724.5992780505385, 5076.13705670037, 5141.948580514184, 5148.613853960958, 5198.92201616976, 5247.632665713382, 5191.091558977183, 5225.268462415265, 5228.999999999981, 5235.685858848462, 5237.240600113, 5057.772400241998, 5067.113255180348, 5169.844904652867, 5177.844664660048, 5236.31927849379, 5173.042987047408, 5188.662736922088, 5192.169252919514, 5194.506521880416, 5207.6875387476875, 5019.347484826985, 5106.08090543702, 5162.974185129088, 5226.3989641191365, 5227.137522308814, 5342.439043900412, 5444.891102177934, 5450.209719590637, 5453.23654688344, 5463.043967305705, 6016.8495787605225, 6050.848728781772, 6057.909131363033, 6057.969710151465, 6065.211522502091, 5813.360530341659, 5890.0, 6065.696715164268, 6067.666278354689, 6068.120122582245, 5566.0, 5644.238027866268, 5645.774169033275, 5659.094544872801, 5687.118496633028, 6093.146959425684, 6104.114903338991, 6111.572189946661, 6121.724522396414, 6121.877562448815, 5712.228849105318, 5776.740046697909, 5823.883522329615, 5961.046232602763, 5968.940310596925, 6021.42796434338, 6135.417135372132, 6139.723712432862, 6145.139680444741, 6163.876722465526, 6039.0, 6070.089486576961, 6083.452489275986, 6083.8174854754425, 6106.72519736604, 5883.882322353529, 5956.612820166675, 6017.217761691039, 6021.217241758631, 6071.635701857815, 4608.354830323758, 4609.469910960215, 4610.331501932268, 4610.515895830889, 4612.469565999886, 4146.958530414718, 4156.916861662749, 4159.480064991876, 4170.958290417057, 4191.643710284599, 5558.277423934944, 5621.971890140605, 5631.2960879890015, 5647.0, 5652.236948012048, 6045.78839740607, 6074.483668888194, 6088.665123418255, 6112.327643959161, 6117.602355846856, 5490.423505531941, 5582.190582365534, 5600.635958662674, 5605.523530499871, 5649.4633009864, 5253.106971814801, 5258.7370631468275, 5272.894542109137, 5285.735713214324, 5289.312389389354, 6217.502599792006, 6251.031090181029, 6269.5611307208055, 6291.937080629226, 6296.496280297565, 5864.0, 5921.467067963904, 5996.070609055603, 6036.336003039574, 6076.331603523698, 6143.078538219124, 6241.001439769615, 6241.906371404341, 6266.498680105489, 6276.874462510816, 5930.199423077829, 6017.398260174123, 6028.87942241144, 6032.819015429544, 6046.909296360646, 5871.911921321098, 5909.586328957103, 5985.940140598713, 5990.7304171311525, 6081.695915204311, 5848.970755146197, 5925.348211696623, 5931.822045338647, 5943.52451803846, 5944.227250457413, 5819.970900145472, 5827.796027139199, 5858.033424484912, 5860.472557469848, 5860.589758717019, 5930.614510056833, 5938.4061593842, 5940.1683764272175, 5940.8811823764145, 5946.28644562638, 5978.163057172086, 6031.5476339273855, 6044.214252147192, 6050.45545900871, 6060.1212824139375, 5628.85927851807, 5701.828945131652, 5776.7111644418455, 5903.911441328298, 5912.46787789101], "unit": "ms", "name": "dojo.html", "value": 5572.893835976806}, {"lowerIsBetter": false, "replicates": [559.6362364463098, 619.9349068347823, 623.4731651754266, 633.9366063393664, 634.2230767310043, 29528.0, 29919.700802992018, 29925.25186870324, 29987.850060749694, 30006.849965750167, 34984.475232871526, 43045.0, 43917.560824391745, 44033.11933761331, 44070.559294407045, 9189.989101198846, 9301.627934882597, 9321.161095501404, 9324.300677449219, 9333.393329433566, 8078.35373170145, 8228.42401031925, 9052.41159324645, 9127.178553930145, 9167.908320916806, 10959.56161753529, 11184.608538701106, 11194.888051119508, 11198.38408887511, 11200.383978881158, 10693.572257109707, 10753.462326883624, 10786.244962852603, 10786.946065269623, 10790.946045269802, 10636.042756151983, 10769.83845242322, 10778.19163562736, 10780.514876830483, 10783.892161078445, 42983.78508107439, 43939.56060439387, 43956.340654890206, 43965.78017109926, 44029.33955990663, 3674.0, 3683.797391143486, 3736.1220113273357, 4150.501939767217, 4355.368471571636, 32808.0, 33180.1704957378, 33592.16019599505, 33640.15899602505, 34019.31961360783, 3889.727719059667, 4007.5190977082643, 4044.3529035354204, 4072.246634372621, 4233.640140588053, 296788.0, 298513.50743246364, 298714.0, 298936.0, 299535.0, 65157.67421162864, 66555.66722166358, 67638.66180669065, 75436.0, 77132.0, 67481.0, 67623.0, 67916.0, 67930.0, 67941.32058679449, 18339.266429342944, 18567.0, 18573.16420761069, 18576.164072616764, 18585.628287434447, 13894.722105558034, 13989.0, 14009.71980560383, 14017.439302427993, 14032.0, 22470.438239044153, 22761.544769104526, 22767.772322276564, 22846.543069138523, 22902.42743931415, 8583.613737381833, 8678.305735541142, 8737.43206691563, 8803.735887923373, 8835.807166032631, 1032.7934413117257, 1092.448313601626, 1094.8576685030896, 1095.3318475729798, 1143.954241830334, 31473.527897081563, 35170.29659406798, 35297.823510882285, 35502.28995420077, 35647.4652880207, 1106.5794997900907, 1107.9279846810093, 1140.589387820384, 1159.8144296912453, 1195.1872726546032, 14681.92659036698, 15723.449679261172, 17313.82686173147, 18091.276348945896, 18107.275708971756, 399889.0, 406286.0, 406315.0, 406711.0, 411901.0, 52083.73958130185, 57971.420285797445, 58692.70653646704, 58740.0, 58744.412555873896, 1310.6461255460972, 1392.9164250145025, 1460.5472303585923, 1468.5489100613686, 1471.7571600685767, 30449.54325685072, 30464.0, 30491.847540762155, 31228.843855780575, 31358.0, 48010.75994620004, 48673.0, 48678.26982595193, 48681.756591216814, 48743.0, 218995.0, 219901.0, 221129.0, 237548.0, 238548.0, 34977.825110874284, 35778.28443431169, 35826.10434739153, 36105.277894442486, 36259.27481450409, 46340.30489542727, 46626.06747865092, 47472.2879156806, 47592.52407475881, 47926.760366197945, 38910.0, 39756.0, 45522.77238613918, 45532.544674554156, 47322.0, 39243.60756392399, 40045.55626598382, 40271.79864100661, 40274.395884062345, 40629.593704063765, 41378.793106034274, 41691.58308416877, 41723.16553668849, 41845.0, 42279.36580951227, 41457.37813932854, 42064.0, 42549.36175957301, 43639.781801090794, 43901.56098438975, 32848.67151328552, 33124.0, 33232.70028702757, 33716.0, 34190.31619367649], "unit": "ms", "name": "ext.html", "value": 20593.249544904655}, {"lowerIsBetter": false, "replicates": [388.70007448440504, 389.44504081683596, 390.6601256906492, 394.8321963165654, 397.8189923584769, 14475.78286325706, 17610.735838962424, 17999.550011249725, 18049.4585162445, 19929.302474413398, 18764.53088672784, 18921.62156756864, 19445.41663750086, 19467.902660486714, 19488.805111948877, 6195.411435913581, 6304.211973503312, 6313.1792866927235, 6317.33667964864, 6343.6193828370215, 6161.137440758297, 6245.500359971192, 6405.231372235315, 6406.263279722822, 6408.7756928507515, 4581.702189357681, 4707.741074240916, 6387.904181437282, 6391.296957334689, 6398.520110991671, 6700.0, 6877.690503927335, 6904.585724856524, 6907.792766217021, 6908.101946746941, 5454.972725136388, 5599.860003499904, 5600.691961942091, 5601.3558440779425, 6601.108850305195, 13590.59228223155, 13715.039947203699, 17269.222884970208, 20915.26796562113, 20938.16247350104, 2236.675682026097, 2253.1550668499312, 2260.3106052653934, 2280.977190228093, 2293.4610366563843, 10126.443045632488, 10131.44277064761, 10446.738831529197, 11637.767244655059, 13457.730845383136, 1836.669399508088, 1906.809319068082, 1973.891435971021, 1975.4666240115089, 1986.2055177928798, 60984.247648121025, 61542.38457615456, 64141.358586414004, 66918.0, 69959.95060074153, 10970.957759012881, 10989.06592939593, 11023.118150547936, 11040.171987101, 11206.719832004264, 13774.104683195561, 13987.580372588838, 14111.71776564463, 14124.788128178085, 14316.212608306643, 8604.13958604147, 8669.869951950726, 8682.6092825823, 8702.390832641655, 8706.782330441789, 8694.347923905732, 8720.956395217983, 8737.388382813142, 8760.992485864079, 8807.163319484638, 4388.605025547716, 4461.0, 4580.610648094883, 4772.188727916263, 4914.70511769295, 1756.2594153429695, 1911.2546107018275, 1919.193938545814, 1925.1048262557697, 1993.132987150594, 879.4986857491297, 986.8569057486624, 1023.9129673977646, 1060.8355704865758, 1064.5794911010107, 17219.0, 18170.54573635643, 18327.816721832878, 18571.350002749838, 18766.718499222523, 980.4901451245313, 1002.7793885345212, 1013.5945621751358, 1016.0855230292796, 1045.900639439252, 10079.092881640687, 10183.949080254552, 10296.742581435525, 10308.948455257825, 10359.741006474747, 404385.0, 405487.0, 638210.0, 638343.0, 660331.570158688, 86162.56918715367, 86304.56847715721, 86949.0, 88693.55653221822, 89353.55323223342, 1239.4608345369793, 1244.5519612939336, 1249.6438515023356, 1251.874812518759, 1262.3625069339926, 16549.420770272984, 16595.91702041482, 16685.666286673957, 16715.665686686443, 16899.408520701716, 37469.0, 38088.0, 38704.61295387123, 38825.223495530496, 39062.46094003881, 50340.24489632732, 50912.490875092255, 51078.0, 52063.47936520738, 55375.0, 10178.643747468803, 10275.897241027495, 10431.0, 10484.26610137283, 10566.207534434794, 12454.190477618926, 13894.861051389356, 18428.723569146205, 18488.537786555444, 19268.807311927263, 14774.409023639364, 14962.925185374004, 14974.251287435802, 15185.696286073995, 15620.609484762972, 15225.238738063274, 15521.689566208386, 15797.0, 15956.281967311266, 16157.111358875374, 15409.614759631098, 15458.768118478456, 15805.051696898223, 15878.285477153324, 15902.443414480438, 9986.700398988041, 10530.514559234267, 10582.259241852997, 10625.787484250426, 10803.081738052038, 12501.187422817487, 15717.449889254278, 16005.199740013186, 16082.43711470093, 16327.510174694778], "unit": "ms", "name": "jquery.html", "value": 9637.73694158647}, {"lowerIsBetter": false, "replicates": [1557.7663350497423, 1581.4623028170427, 1792.2920446423655, 1833.6607727570401, 1850.8704390692653, 7888.763337099882, 8262.958685206573, 8267.214614611617, 8274.793130171742, 8277.296429803473, 7144.678489467973, 7282.162551306601, 7798.649060792264, 7938.047434307877, 8152.021757389107, 2345.8827058647125, 2364.5507353602848, 2381.226101517005, 2385.940351491209, 2390.2590197038885, 2053.507158282009, 2328.6274196128625, 2361.645753137026, 2362.244081893795, 2370.4429459077105, 2024.7772744998038, 2285.1887579909144, 2316.9652455213186, 2319.420144963759, 2320.7331156917003, 2288.164819840763, 2294.3117064880553, 2296.219285442941, 2353.035255545218, 2356.7996720278798, 2355.8704271265074, 2361.929142125739, 2387.78509934105, 2388.4984153327823, 2404.410919324759, 7861.489003214772, 7945.761627151137, 7948.324392426593, 7950.3242224411, 7955.4033447491665, 4842.83050093245, 4915.3855768029, 4922.0, 4932.778024988884, 4944.579710724593, 3154.479510880702, 3160.604924384452, 3209.0533292678715, 3284.868605255787, 3312.271300313927, 1083.3283364314175, 2005.1979208316643, 2010.4672261850621, 2015.9193632254692, 2047.5904819036252, 11093.500792464358, 11189.496472658668, 11196.888031119666, 11198.552057917674, 11224.158188135922, 4140.213359461693, 4194.0563373240775, 4209.305464598368, 4246.044639955985, 4286.635635970915, 8860.468371897707, 8868.15752503511, 8870.911290887138, 8871.0, 8871.600777965006, 4751.619870410359, 4783.354247176587, 4795.136875362468, 4799.832005879777, 4807.4471435784635, 4408.779561022, 4408.889777755582, 4414.448193975753, 4415.779211039499, 4421.756803375781, 3494.685478306965, 3551.804650744234, 3579.552555930509, 3582.0686621478603, 3589.964100359015, 987.4914419074182, 1030.829913064336, 1034.6430481483876, 1036.4817591204398, 1041.6822869024868, 1102.3165637304762, 1104.8950349716763, 1156.1213477756964, 1293.5666551705285, 1371.7873729571932, 7410.5183163094225, 7511.136219334737, 7628.732994345282, 7667.3099421052375, 7702.730404435818, 1208.6374087773634, 1227.1226073357413, 1235.5613757116273, 1240.8945239654731, 1242.2608547913976, 5574.414686457944, 5622.212890195376, 5635.887282254332, 5641.435856414326, 5647.830565083054, 9290.72127836166, 9665.178459830853, 9678.806423871483, 9788.804223915482, 9808.803823923481, 9608.471534065693, 9651.0, 9679.564419601134, 9692.95153524242, 9706.611735530641, 7251.093613298338, 7465.029546159073, 7467.962660186664, 7471.066116735408, 7483.064616922885, 7063.293670632896, 7116.430685545248, 7162.928370716226, 7163.892541611985, 7168.0, 8381.203785640355, 8534.359923005924, 8596.441231319945, 8644.0, 8653.956730216309, 8544.35917306217, 8620.827583448421, 8718.738437846874, 8726.0, 8802.427842190236, 7183.928160718326, 7233.168185658506, 7270.781876543712, 7284.271572842674, 7286.307800758919, 7728.497647653109, 7782.0, 7838.216178382117, 7838.372930165459, 7853.921460785319, 6574.0, 7388.704451821867, 7392.22381649919, 7438.107427108782, 7459.254074592497, 6856.0, 6994.825129371807, 7151.106111736033, 7406.592637404994, 7563.848723025619, 7442.0, 7457.030586023782, 7464.664090115849, 7485.1017877855, 7492.325690687864, 6859.828504287433, 6894.207166175954, 6906.240313565604, 6913.516053876381, 6914.135733033371, 6817.352351526597, 6862.4853136014, 6895.5173137879865, 6898.827529311808, 6909.0], "unit": "ms", "name": "mootools.html", "value": 4678.226745108962}, {"lowerIsBetter": false, "replicates": [368.40502588319873, 370.74603896331007, 373.13805110195443, 373.6170425314053, 375.74824867338896, 6020.518358531318, 6071.848203794907, 6113.388661133884, 6115.663638499892, 6124.60190087644, 5795.739191736371, 5845.444682755132, 5927.170196172539, 6014.398560143984, 6021.668808215546, 4678.672492925497, 4691.671582989192, 4693.71837689738, 4708.105459962613, 4715.12770137524, 4741.478437371887, 4743.146233677937, 4743.85768426946, 4744.454387745402, 4750.667453278272, 4475.462944446655, 4538.183127037132, 4554.20301447247, 4606.355110284563, 4712.740799256039, 4663.347131401607, 4675.392199014123, 4685.437747470309, 4700.9059818803435, 4702.858914232578, 4129.669626429878, 4153.958460415387, 4170.124273902484, 4172.979135104305, 4174.019105510202, 5663.292088488939, 5671.347795003586, 5671.546276297886, 5697.4587414195585, 5714.51426628737, 5366.356037275513, 5443.292371991616, 5455.918161227585, 5463.453654634544, 5483.396826349099, 6380.330065343119, 6382.914904466205, 6386.2975072741965, 6387.616742995435, 6388.105665206875, 4627.953720462786, 4633.934195135138, 4637.721736695776, 4640.69835460694, 4642.930356044662, 6479.35206479353, 6609.702563384659, 6611.471082313404, 6622.93377066228, 6638.10385597943, 4663.883402914887, 4688.944987377813, 4689.507601701772, 4690.179218636725, 4692.9530704692515, 4710.8351207707565, 4738.19450693383, 4742.383490146259, 4743.193657078305, 4746.644001699887, 5429.945700542944, 5434.972825135849, 5435.619506634576, 5438.293021907206, 5443.047466693313, 6367.395097465734, 6467.094606755058, 6472.288048314682, 6474.1907261592305, 6494.545381823227, 1642.269190210345, 1670.6408122253774, 1672.916354182286, 1673.1299724143378, 1673.313941284092, 1124.3422597780227, 1125.9324440533594, 1133.4162906103363, 1150.3558007515817, 1152.7175841918784, 6215.12988181655, 6224.626522408579, 6238.313785483593, 6238.594491358047, 6242.875142497124, 6648.767293144717, 6653.201615806134, 6672.566283191577, 6674.532782705164, 6681.699323530453, 6078.513718902477, 6098.75604975796, 6126.326104128542, 6204.317525072239, 6230.7507699691505, 3569.286142771456, 3600.7299452541165, 3605.747597668138, 3609.4405367168133, 3619.909502262412, 6872.278410766798, 6880.2431732509385, 6884.759033433806, 6885.2770459101075, 6886.655667216619, 6712.42944349726, 6836.316368363124, 6857.725690972405, 6874.518783685094, 6876.656167191621, 6870.31296870309, 6871.175458944958, 6872.656367181621, 6878.38094571491, 6885.655717214019, 6697.0, 6703.229128650268, 6703.899441508283, 6709.463242940651, 6713.295104014008, 6491.0, 6615.206175259, 6616.603003819786, 6625.569337993015, 6628.767993120217, 6870.965645171942, 6874.828129296808, 6878.174619045746, 6879.724811007503, 6881.243063262937, 4687.648426368104, 4689.812407503799, 4692.53074692528, 4698.906021879475, 4700.059988002345, 6573.80278591643, 6705.228898676714, 6717.395434410926, 6722.1261236039, 6723.865522689616, 6530.510211734044, 6542.247641521286, 6546.705398257185, 6548.1487406638735, 6558.147440832661, 6495.642739649365, 6507.12153859223, 6523.0, 6523.7716679915975, 6533.118029066015, 6273.0, 6385.521085918667, 6386.393292637192, 6390.073439351175, 6434.099226108255, 6555.082288479522, 6557.311482294481, 6559.573627714183, 6560.3111673275525, 6560.967195163994, 6277.30949595535, 6405.743770249324, 6408.03879418091, 6410.61536307823, 6423.903641445476], "unit": "ms", "name": "prototype.html", "value": 4912.501020046686}, {"lowerIsBetter": false, "replicates": [803.8472690188863, 838.2037064788449, 841.3437518735386, 888.8044630181362, 890.9599068041938, 12894.613161605155, 13419.932900335509, 13443.0, 13468.461261549552, 13512.59462216134, 13395.129316594415, 14662.633434164125, 14961.326740296685, 14963.177025263607, 15023.0, 752.8080339513416, 764.5871229536044, 890.2966656341482, 893.4415990006246, 899.7165892743765, 871.3464901324007, 891.393852180517, 896.1083721696921, 898.0660113481971, 899.0245583541865, 935.046252822121, 937.3485427627801, 942.8868535775716, 943.5659596585579, 943.9103285187896, 517.5135372749622, 521.0438844719924, 925.8703781470566, 951.3150531617224, 971.1453920549942, 696.03946553756, 696.4567637242959, 696.4985210648325, 697.2817997462622, 699.3810477727197, 13286.136401133896, 13495.190288582717, 13580.049396542146, 13597.86402135976, 13601.31993400326, 13082.607521774362, 13185.802212966813, 13563.728725425437, 13822.028263923205, 14101.0, 11136.832947505794, 11545.884541154564, 11572.826407603892, 11580.536778528933, 11824.290542567387, 3850.5764365919727, 3883.184531248413, 3885.0, 3892.3577609694366, 3895.61043895611, 50378.24432633514, 50745.23882141771, 51918.74040629811, 52204.73897630488, 52253.477465225624, 10017.799644007078, 10121.03850134236, 10640.0, 11437.656870293904, 11626.53493860253, 20628.69056964147, 20833.58332833335, 20963.161473540888, 20970.89514552448, 20985.580288394453, 7938.325242354349, 8106.63520141595, 8150.103488616366, 8169.714060007871, 8320.916790832136, 4612.930806037912, 4649.04694537619, 4665.066986602626, 4685.789139488663, 4709.128811169876, 3325.334933013407, 3354.5135955286346, 3359.0930448778695, 3359.882404115844, 3364.310316385135, 563.5885803363567, 578.9189513468118, 584.541135208859, 585.1895125251557, 587.5945597537685, 698.2214830463996, 716.7527203114917, 734.4565021883874, 737.5869513072696, 746.1121265693807, 9027.954860225787, 9100.635974560944, 9105.408148470327, 9243.722688319362, 9339.439633622005, 591.6893630843841, 707.4587940225731, 712.6116266634697, 732.739877343546, 742.9591372474458, 4514.81940722374, 4585.816567337336, 4715.151272770866, 4769.689970151929, 4863.756812159378, 61830.0, 62845.68577157176, 62870.37129628737, 62915.37084629095, 62964.37035629585, 54381.728091360084, 54549.72725136349, 55378.72310638421, 55560.0, 55570.16644750332, 50073.24890126651, 50214.24678629824, 50362.0, 50499.0, 51399.743001285504, 8826.911730882866, 8858.424202426822, 8883.822323553622, 9191.356605037583, 9336.206422454168, 36395.0, 37274.25451491009, 37409.625903741704, 37748.245035099695, 38111.61888381081, 48162.27756583584, 48210.0, 48459.27311090266, 49778.0, 51943.74028129835, 11790.115741319607, 11975.28148311104, 12234.571789987307, 12251.938740306598, 12444.440000200193, 12894.419751111034, 13228.177626452522, 13386.799198011842, 13436.395362208528, 13597.0, 12163.513459461523, 12195.0, 12337.938310308391, 12533.435995380047, 12579.056570757048, 11973.221740586834, 12136.575219867262, 12214.26714397139, 12301.0, 12425.751484970431, 11834.467448964646, 11944.223625464318, 11989.34058626749, 12298.754024919273, 12405.0, 10877.238593298393, 11073.667789966314, 11268.549258029587, 11280.774384512428, 11337.886621133683, 10196.133328667147, 10513.421761803174, 10521.368717876952, 10598.364098154136, 10603.416812075102], "unit": "ms", "name": "yui.html", "value": 6808.663721940807}], "name": "dromaeo_css", "value": 7465.251237286132}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [107.0, 111.0, 104.0, 107.0, 111.0, 108.0, 98.0, 109.0, 109.0, 98.0], "unit": "ms", "name": "ai-astar", "value": 106.2}, {"lowerIsBetter": true, "replicates": [146.0, 121.0, 155.0, 159.0, 134.0, 159.0, 147.0, 127.0, 164.0, 125.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [160.0, 158.0, 159.0, 158.0, 162.0, 161.0, 160.0, 157.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 159.4}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 146.0, 72.0, 70.0, 72.0, 71.0, 153.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 87.1}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 134.0, 92.0, 93.0, 92.0, 140.0, 92.0, 92.0, 144.0], "unit": "ms", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 97.0, 95.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 57.0, 56.0, 57.0, 57.0, 56.0, 58.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 65.0, 66.0, 66.0, 65.0, 65.0, 73.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.7}, {"lowerIsBetter": true, "replicates": [78.0, 79.0, 78.0, 80.0, 80.0, 80.0, 81.0, 82.0, 80.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.7}, {"lowerIsBetter": true, "replicates": [113.0, 112.0, 115.0, 117.0, 118.0, 116.0, 118.0, 111.0, 122.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.1}, {"lowerIsBetter": true, "replicates": [136.0, 141.0, 143.0, 144.0, 145.0, 135.0, 144.0, 151.0, 144.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 142.1}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 65.0, 67.0, 64.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.5}], "name": "kraken", "value": 1477.6999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.2845408247084], "unit": "score", "name": "Richards", "value": 214.88327399999997}, {"lowerIsBetter": false, "replicates": [164.50074025333114], "unit": "score", "name": "DeltaBlue", "value": 401.93132199999985}, {"lowerIsBetter": false, "replicates": [280.2690582959641], "unit": "score", "name": "Encrypt", "value": 949.7338080000005}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [935.4536950420954], "unit": "score", "name": "RayTrace", "value": 791.048241}, {"lowerIsBetter": false, "replicates": [539.9568034557235], "unit": "score", "name": "Earley", "value": 1234.2894759999995}, {"lowerIsBetter": false, "replicates": [10541.666666666666], "unit": "score", "name": "Boyer", "value": 63.22178656126487}, {"lowerIsBetter": false, "replicates": [24047.619047619046], "unit": "score", "name": "RegExp", "value": 37.88254455445545}, {"lowerIsBetter": false, "replicates": [532.4813631522896], "unit": "score", "name": "Splay", "value": 153.04009800000009}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23368.8703460329}]} 15:51:58 INFO - RETURN: dromaeo_css: 7465.25 15:51:58 INFO - RETURN: kraken: 1477.70 15:51:58 INFO - RETURN: v8_7: 23368.87 15:51:58 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23368.87"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1477.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7465.25"}}} 15:51:58 INFO - Return code: 0 15:51:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 15:51:58 INFO - # TBPL SUCCESS # 15:51:58 INFO - Running post-action listener: _resource_record_post_action 15:51:58 INFO - Running post-run listener: _resource_record_post_run 15:51:59 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 8224768; Write bytes: 397606912; Read time: 1592; Write time: 259304 15:51:59 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 26632192; Read time: 0; Write time: 4940 15:51:59 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 6488064; Write bytes: 284508160; Read time: 1548; Write time: 181704 15:51:59 INFO - Running post-run listener: _upload_blobber_files 15:51:59 INFO - Blob upload gear active. 15:51:59 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 15:51:59 INFO - Copying logs to upload dir... 15:51:59 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.694853 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-02-18 15:51:59.618183) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-18 15:51:59.621730) ========= 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.008900 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455829285/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-18 15:51:59.673514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:51:59.673833) ========= 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=ed14279e38b05397cf4aa391000001a4-1455838253.453196-2126674004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 15:51:59.723473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 15:51:59.723885) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 15:51:59.724172) ========= ========= Total master_lag: 0.55 =========