builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1454603398.4 results: success (0) buildid: 20160204060427 builduid: 320500899663494993bc973fc425a35b revision: 1971245e0a52 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.400065) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.400503) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.400799) ========= 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006165 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.423273) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.423573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.436672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.437034) ========= 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004666 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.485857) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.486157) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.486514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.486793) ========= 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-04 08:29:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2016-02-04 08:29:58 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091263 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.590282) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.590595) ========= 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021935 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:29:58.641241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-04 08:29:58.641761) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1971245e0a52 --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 1971245e0a52 --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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-04 08:29:58,730 Setting DEBUG logging. 2016-02-04 08:29:58,730 attempt 1/10 2016-02-04 08:29:58,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1971245e0a52?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-04 08:29:59,928 unpacking tar archive at: mozilla-beta-1971245e0a52/testing/mozharness/ program finished with exit code 0 elapsedTime=1.488309 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-04 08:30:00.140293) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-04 08:30:00.140734) ========= echo 1971245e0a52 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'1971245e0a52'] 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 1971245e0a52 program finished with exit code 0 elapsedTime=0.004637 script_repo_revision: '1971245e0a52' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-04 08:30:00.191941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-04 08:30:00.192256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-04 08:30:00.201753) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-04 08:30:00.202106) ========= /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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:30:00 INFO - MultiFileLogger online at 20160204 08:30:00 in /builds/slave/test-pgo 08:30:00 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 08:30:00 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:30:00 INFO - {'append_to_log': False, 08:30:00 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:30:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:30:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:30:00 INFO - 'branch': 'Mozilla-Beta', 08:30:00 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:00 INFO - 'config_files': ('talos/linux_config.py',), 08:30:00 INFO - 'default_actions': ('clobber', 08:30:00 INFO - 'read-buildbot-config', 08:30:00 INFO - 'download-and-extract', 08:30:00 INFO - 'populate-webroot', 08:30:00 INFO - 'create-virtualenv', 08:30:00 INFO - 'install', 08:30:00 INFO - 'run-tests'), 08:30:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:00 INFO - 'download_minidump_stackwalk': True, 08:30:00 INFO - 'download_symbols': 'ondemand', 08:30:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:00 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:00 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:00 INFO - 'installer_path': 'installer.exe', 08:30:00 INFO - 'log_level': 'info', 08:30:00 INFO - 'log_name': 'talos', 08:30:00 INFO - 'log_to_console': True, 08:30:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:30:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:30:00 INFO - 'opt_config_files': (), 08:30:00 INFO - 'pip_index': False, 08:30:00 INFO - 'sps_profile': False, 08:30:00 INFO - 'sps_profile_interval': 0, 08:30:00 INFO - 'suite': 'dromaeojs', 08:30:00 INFO - 'system_bits': '32', 08:30:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:00 INFO - 'title': 'talos-linux64-ix-059', 08:30:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:00 INFO - 'use_talos_json': True, 08:30:00 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:30:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:00 INFO - 'work_dir': 'build'} 08:30:00 INFO - ##### 08:30:00 INFO - ##### Running clobber step. 08:30:00 INFO - ##### 08:30:00 INFO - Running pre-action listener: _resource_record_pre_action 08:30:00 INFO - Running main action method: clobber 08:30:00 INFO - rmtree: /builds/slave/test-pgo/build 08:30:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:30:00 INFO - Running post-action listener: _resource_record_post_action 08:30:00 INFO - ##### 08:30:00 INFO - ##### Running read-buildbot-config step. 08:30:00 INFO - ##### 08:30:00 INFO - Running pre-action listener: _resource_record_pre_action 08:30:00 INFO - Running main action method: read_buildbot_config 08:30:00 INFO - Using buildbot properties: 08:30:00 INFO - { 08:30:00 INFO - "properties": { 08:30:00 INFO - "buildnumber": 101, 08:30:00 INFO - "product": "firefox", 08:30:00 INFO - "script_repo_revision": "production", 08:30:00 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:30:00 INFO - "repository": "", 08:30:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:30:00 INFO - "buildid": "20160204060427", 08:30:00 INFO - "slavename": "talos-linux64-ix-059", 08:30:00 INFO - "pgo_build": "True", 08:30:00 INFO - "basedir": "/builds/slave/test-pgo", 08:30:00 INFO - "project": "", 08:30:00 INFO - "platform": "ubuntu64_hw", 08:30:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:30:00 INFO - "slavebuilddir": "test", 08:30:00 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:30:00 INFO - "branch": "mozilla-beta", 08:30:00 INFO - "repo_path": "releases/mozilla-beta", 08:30:00 INFO - "stage_platform": "linux64-pgo", 08:30:00 INFO - "builduid": "320500899663494993bc973fc425a35b", 08:30:00 INFO - "revision": "1971245e0a52" 08:30:00 INFO - }, 08:30:00 INFO - "sourcestamp": { 08:30:00 INFO - "repository": "", 08:30:00 INFO - "hasPatch": false, 08:30:00 INFO - "project": "", 08:30:00 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:30:00 INFO - "changes": [ 08:30:00 INFO - { 08:30:00 INFO - "category": null, 08:30:00 INFO - "files": [ 08:30:00 INFO - { 08:30:00 INFO - "url": null, 08:30:00 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:30:00 INFO - } 08:30:00 INFO - ], 08:30:00 INFO - "repository": "", 08:30:00 INFO - "rev": "1971245e0a52", 08:30:00 INFO - "who": "sendchange", 08:30:00 INFO - "when": 1454603303, 08:30:00 INFO - "number": 7563859, 08:30:00 INFO - "comments": "Bug 1242777 - Expose child process hang stats to Javascript. r=chutten, a=sylvestre", 08:30:00 INFO - "project": "", 08:30:00 INFO - "at": "Thu 04 Feb 2016 08:28:23", 08:30:00 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:30:00 INFO - "revlink": "", 08:30:00 INFO - "properties": [ 08:30:00 INFO - [ 08:30:00 INFO - "buildid", 08:30:00 INFO - "20160204060427", 08:30:00 INFO - "Change" 08:30:00 INFO - ], 08:30:00 INFO - [ 08:30:00 INFO - "builduid", 08:30:00 INFO - "320500899663494993bc973fc425a35b", 08:30:00 INFO - "Change" 08:30:00 INFO - ], 08:30:00 INFO - [ 08:30:00 INFO - "pgo_build", 08:30:00 INFO - "True", 08:30:00 INFO - "Change" 08:30:00 INFO - ] 08:30:00 INFO - ], 08:30:00 INFO - "revision": "1971245e0a52" 08:30:00 INFO - } 08:30:00 INFO - ], 08:30:00 INFO - "revision": "1971245e0a52" 08:30:00 INFO - } 08:30:00 INFO - } 08:30:00 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:30:00 INFO - Running post-action listener: _resource_record_post_action 08:30:00 INFO - ##### 08:30:00 INFO - ##### Running download-and-extract step. 08:30:00 INFO - ##### 08:30:00 INFO - Running pre-action listener: _resource_record_pre_action 08:30:00 INFO - Running main action method: download_and_extract 08:30:00 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:30: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')]} 08:30:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/test_packages.json 08:30:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:30:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:30:00 INFO - Downloaded 1270 bytes. 08:30:00 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:30:00 INFO - Using the following test package requirements: 08:30:00 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:30:00 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:30:00 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'jsshell-linux-x86_64.zip'], 08:30:00 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:30:00 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:30:00 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:30:00 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:30:00 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:30:00 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:30:00 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:30:00 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:30:00 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:30: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')]} 08:30:00 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:30:00 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:01 INFO - Downloaded 21581822 bytes. 08:30: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 08:30:01 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:30: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 08:30:02 INFO - Return code: 0 08:30: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 08:30: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')]} 08:30:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:30:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:02 INFO - Downloaded 21581822 bytes. 08:30: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 08:30:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:30: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 08:30:03 INFO - Return code: 0 08:30:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:30:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:30:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:04 INFO - Downloaded 11132038 bytes. 08:30: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 08:30:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:30: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 08:30:04 INFO - Return code: 0 08:30: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')]} 08:30:04 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:30:04 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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 08:30:05 INFO - Downloaded 57945386 bytes. 08:30:05 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:30:05 INFO - mkdir: /builds/slave/test-pgo/properties 08:30:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:30:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:30:05 INFO - Contents: 08:30:05 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:30:05 INFO - Running post-action listener: _resource_record_post_action 08:30:05 INFO - Running post-action listener: set_extra_try_arguments 08:30:05 INFO - ##### 08:30:05 INFO - ##### Running populate-webroot step. 08:30:05 INFO - ##### 08:30:05 INFO - Running pre-action listener: _resource_record_pre_action 08:30:05 INFO - Running main action method: populate_webroot 08:30:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:05 INFO - '--tppagecycles', 08:30:05 INFO - '7'], 08:30:05 INFO - 'tests': ['tsvgm']}}, 08:30:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:30:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:30:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:30:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'tests': ['damp', 'tps']}, 08:30:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['damp', 'tps']}, 08:30:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:30:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:05 INFO - 'other': {'tests': ['a11yr', 08:30:05 INFO - 'ts_paint', 08:30:05 INFO - 'tpaint', 08:30:05 INFO - 'sessionrestore', 08:30:05 INFO - 'sessionrestore_no_auto_restore']}, 08:30:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['a11yr', 08:30:05 INFO - 'ts_paint', 08:30:05 INFO - 'tpaint', 08:30:05 INFO - 'sessionrestore', 08:30:05 INFO - 'sessionrestore_no_auto_restore']}, 08:30:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['a11yr', 08:30:05 INFO - 'ts_paint', 08:30:05 INFO - 'tpaint', 08:30:05 INFO - 'sessionrestore', 08:30:05 INFO - 'sessionrestore_no_auto_restore']}, 08:30:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['a11yr', 08:30:05 INFO - 'ts_paint', 08:30:05 INFO - 'tpaint', 08:30:05 INFO - 'sessionrestore', 08:30:05 INFO - 'sessionrestore_no_auto_restore']}, 08:30:05 INFO - 'other_l64': {'tests': ['a11yr', 08:30:05 INFO - 'ts_paint', 08:30:05 INFO - 'tpaint', 08:30:05 INFO - 'sessionrestore', 08:30:05 INFO - 'sessionrestore_no_auto_restore']}, 08:30:05 INFO - 'other_nol64': {'tests': ['a11yr', 08:30:05 INFO - 'ts_paint', 08:30:05 INFO - 'tpaint', 08:30:05 INFO - 'sessionrestore', 08:30:05 INFO - 'sessionrestore_no_auto_restore']}, 08:30:05 INFO - 'svgr': {'tests': ['tsvgx', 08:30:05 INFO - 'tsvgr_opacity', 08:30:05 INFO - 'tart', 08:30:05 INFO - 'tscrollx', 08:30:05 INFO - 'cart']}, 08:30:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['tsvgx', 08:30:05 INFO - 'tsvgr_opacity', 08:30:05 INFO - 'tart', 08:30:05 INFO - 'tscrollx', 08:30:05 INFO - 'cart']}, 08:30:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'tests': ['tp5o']}, 08:30:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'talos_options': ['--e10s'], 08:30:05 INFO - 'tests': ['tp5o']}, 08:30:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'talos_options': ['--xperf_path', 08:30:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:05 INFO - 'tests': ['tp5n']}, 08:30:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:05 INFO - 'talos_options': ['--e10s', 08:30:05 INFO - '--xperf_path', 08:30:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:05 INFO - 'tests': ['tp5n']}}, 08:30:05 INFO - 'talos.zip': {'path': '', 08:30:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:05 INFO - Running post-action listener: _resource_record_post_action 08:30:05 INFO - ##### 08:30:05 INFO - ##### Running create-virtualenv step. 08:30:05 INFO - ##### 08:30:05 INFO - Running pre-action listener: _resource_record_pre_action 08:30:05 INFO - Running main action method: create_virtualenv 08:30:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:30: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 08:30:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:30:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:05 INFO - Using real prefix '/usr' 08:30:05 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:30:05 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:07 INFO - Installing pip.................done. 08:30:07 INFO - Return code: 0 08:30:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:30:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:07 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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:07 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 08:30:07 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 08:30:07 INFO - Using env: {'DISPLAY': ':0', 08:30:07 INFO - 'HOME': '/home/cltbld', 08:30:07 INFO - 'LANG': 'en_US.UTF-8', 08:30:07 INFO - 'LANGUAGE': 'en_US:en', 08:30:07 INFO - 'LOGNAME': 'cltbld', 08:30:07 INFO - 'MAIL': '/var/mail/cltbld', 08:30:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:07 INFO - 'MOZ_NO_REMOTE': '1', 08:30:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:07 INFO - 'NO_EM_RESTART': '1', 08:30:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:07 INFO - 'SHELL': '/bin/bash', 08:30:07 INFO - 'SHLVL': '1', 08:30:07 INFO - 'TERM': 'linux', 08:30:07 INFO - 'TMOUT': '86400', 08:30:07 INFO - 'USER': 'cltbld', 08:30:07 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:07 INFO - '_': '/tools/buildbot/bin/python'} 08:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:08 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 08:30:08 INFO - Cleaning up... 08:30:08 INFO - Return code: 0 08:30:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:30: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')]} 08:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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', '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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30: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', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 08:30: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 psutil>=0.7.1 08:30:08 INFO - Using env: {'DISPLAY': ':0', 08:30:08 INFO - 'HOME': '/home/cltbld', 08:30:08 INFO - 'LANG': 'en_US.UTF-8', 08:30:08 INFO - 'LANGUAGE': 'en_US:en', 08:30:08 INFO - 'LOGNAME': 'cltbld', 08:30:08 INFO - 'MAIL': '/var/mail/cltbld', 08:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:08 INFO - 'MOZ_NO_REMOTE': '1', 08:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:08 INFO - 'NO_EM_RESTART': '1', 08:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:08 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:08 INFO - 'SHELL': '/bin/bash', 08:30:08 INFO - 'SHLVL': '1', 08:30:08 INFO - 'TERM': 'linux', 08:30:08 INFO - 'TMOUT': '86400', 08:30:08 INFO - 'USER': 'cltbld', 08:30:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:08 INFO - '_': '/tools/buildbot/bin/python'} 08:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:08 INFO - Downloading/unpacking psutil>=0.7.1 08:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:10 INFO - Installing collected packages: psutil 08:30:10 INFO - Running setup.py install for psutil 08:30:10 INFO - building 'psutil._psutil_linux' extension 08:30:10 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 08:30:10 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 08:30:10 INFO - building 'psutil._psutil_posix' extension 08:30:10 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 08:30:10 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 08:30:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:10 INFO - Successfully installed psutil 08:30:10 INFO - Cleaning up... 08:30:10 INFO - Return code: 0 08:30:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:30: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')]} 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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', '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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 08:30: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 mozsystemmonitor==0.0.0 08:30:10 INFO - Using env: {'DISPLAY': ':0', 08:30:10 INFO - 'HOME': '/home/cltbld', 08:30:10 INFO - 'LANG': 'en_US.UTF-8', 08:30:10 INFO - 'LANGUAGE': 'en_US:en', 08:30:10 INFO - 'LOGNAME': 'cltbld', 08:30:10 INFO - 'MAIL': '/var/mail/cltbld', 08:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:10 INFO - 'MOZ_NO_REMOTE': '1', 08:30:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:10 INFO - 'NO_EM_RESTART': '1', 08:30:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:10 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:10 INFO - 'SHELL': '/bin/bash', 08:30:10 INFO - 'SHLVL': '1', 08:30:10 INFO - 'TERM': 'linux', 08:30:10 INFO - 'TMOUT': '86400', 08:30:10 INFO - 'USER': 'cltbld', 08:30:10 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:10 INFO - '_': '/tools/buildbot/bin/python'} 08:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:30:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:30:12 INFO - Installing collected packages: mozsystemmonitor 08:30:12 INFO - Running setup.py install for mozsystemmonitor 08:30:12 INFO - Successfully installed mozsystemmonitor 08:30:12 INFO - Cleaning up... 08:30:12 INFO - Return code: 0 08:30:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:30:12 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')]} 08:30:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:12 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')]} 08:30:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:12 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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:12 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 08:30:12 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 08:30:12 INFO - Using env: {'DISPLAY': ':0', 08:30:12 INFO - 'HOME': '/home/cltbld', 08:30:12 INFO - 'LANG': 'en_US.UTF-8', 08:30:12 INFO - 'LANGUAGE': 'en_US:en', 08:30:12 INFO - 'LOGNAME': 'cltbld', 08:30:12 INFO - 'MAIL': '/var/mail/cltbld', 08:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:12 INFO - 'MOZ_NO_REMOTE': '1', 08:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:12 INFO - 'NO_EM_RESTART': '1', 08:30:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:12 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:12 INFO - 'SHELL': '/bin/bash', 08:30:12 INFO - 'SHLVL': '1', 08:30:12 INFO - 'TERM': 'linux', 08:30:12 INFO - 'TMOUT': '86400', 08:30:12 INFO - 'USER': 'cltbld', 08:30:12 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:12 INFO - '_': '/tools/buildbot/bin/python'} 08:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:13 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:14 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:30:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 INFO - Downloading docopt-0.6.1.tar.gz 08:30:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:15 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:15 INFO - Running setup.py install for blobuploader 08:30:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:15 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:30:15 INFO - Running setup.py install for requests 08:30:16 INFO - Running setup.py install for docopt 08:30:16 INFO - Successfully installed blobuploader requests docopt 08:30:16 INFO - Cleaning up... 08:30:16 INFO - Return code: 0 08:30:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:30: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')]} 08:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:16 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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:16 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 08:30:16 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 08:30:16 INFO - Using env: {'DISPLAY': ':0', 08:30:16 INFO - 'HOME': '/home/cltbld', 08:30:16 INFO - 'LANG': 'en_US.UTF-8', 08:30:16 INFO - 'LANGUAGE': 'en_US:en', 08:30:16 INFO - 'LOGNAME': 'cltbld', 08:30:16 INFO - 'MAIL': '/var/mail/cltbld', 08:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:16 INFO - 'MOZ_NO_REMOTE': '1', 08:30:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:16 INFO - 'NO_EM_RESTART': '1', 08:30:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:16 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:16 INFO - 'SHELL': '/bin/bash', 08:30:16 INFO - 'SHLVL': '1', 08:30:16 INFO - 'TERM': 'linux', 08:30:16 INFO - 'TMOUT': '86400', 08:30:16 INFO - 'USER': 'cltbld', 08:30:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:16 INFO - '_': '/tools/buildbot/bin/python'} 08:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:30:16 INFO - Running setup.py (path:/tmp/pip-IGRDfs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:30:16 INFO - Running setup.py (path:/tmp/pip-V9ln9z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:30:16 INFO - Running setup.py (path:/tmp/pip-WSDt5v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:30:16 INFO - Running setup.py (path:/tmp/pip-hJy39n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:30:16 INFO - Running setup.py (path:/tmp/pip-cccaTq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:30:16 INFO - Running setup.py (path:/tmp/pip-uWcn18-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:30:16 INFO - Running setup.py (path:/tmp/pip-ec_TOe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:30:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:30:16 INFO - Running setup.py (path:/tmp/pip-u8HjDA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:30:17 INFO - Running setup.py (path:/tmp/pip-_BMlLr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:30:17 INFO - Running setup.py (path:/tmp/pip-Ro_Rer-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:30:17 INFO - Running setup.py (path:/tmp/pip-1Qiben-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:30:17 INFO - Running setup.py (path:/tmp/pip-GrCDgx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:30:17 INFO - Running setup.py (path:/tmp/pip-HvOLXY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:30:17 INFO - Running setup.py (path:/tmp/pip-8WGE6l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:30:17 INFO - Running setup.py (path:/tmp/pip-nebD8b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:30:17 INFO - Running setup.py (path:/tmp/pip-ivZuCd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:30:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:30:17 INFO - Running setup.py (path:/tmp/pip-PHwTLT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:30:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:30:17 INFO - Running setup.py install for manifestparser 08:30:17 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:30:17 INFO - Running setup.py install for mozcrash 08:30:17 INFO - Running setup.py install for mozdebug 08:30:18 INFO - Running setup.py install for mozdevice 08:30:18 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Running setup.py install for mozfile 08:30:18 INFO - Running setup.py install for mozhttpd 08:30:18 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Running setup.py install for mozinfo 08:30:18 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Running setup.py install for mozInstall 08:30:18 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Running setup.py install for mozleak 08:30:18 INFO - Running setup.py install for mozlog 08:30:18 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Running setup.py install for moznetwork 08:30:18 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:30:18 INFO - Running setup.py install for mozprocess 08:30:18 INFO - Running setup.py install for mozprofile 08:30:19 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:30:19 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:30:19 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:30:19 INFO - Running setup.py install for mozrunner 08:30:19 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:30:19 INFO - Running setup.py install for mozscreenshot 08:30:19 INFO - Running setup.py install for moztest 08:30:19 INFO - Running setup.py install for mozversion 08:30:19 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:30:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:30:19 INFO - Cleaning up... 08:30:19 INFO - Return code: 0 08:30:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:30:19 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')]} 08:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:19 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')]} 08:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:19 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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:19 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 08:30:19 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 08:30:19 INFO - Using env: {'DISPLAY': ':0', 08:30:19 INFO - 'HOME': '/home/cltbld', 08:30:19 INFO - 'LANG': 'en_US.UTF-8', 08:30:19 INFO - 'LANGUAGE': 'en_US:en', 08:30:19 INFO - 'LOGNAME': 'cltbld', 08:30:19 INFO - 'MAIL': '/var/mail/cltbld', 08:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:19 INFO - 'MOZ_NO_REMOTE': '1', 08:30:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:19 INFO - 'NO_EM_RESTART': '1', 08:30:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:19 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:19 INFO - 'SHELL': '/bin/bash', 08:30:19 INFO - 'SHLVL': '1', 08:30:19 INFO - 'TERM': 'linux', 08:30:19 INFO - 'TMOUT': '86400', 08:30:19 INFO - 'USER': 'cltbld', 08:30:19 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:19 INFO - '_': '/tools/buildbot/bin/python'} 08:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:30:19 INFO - Running setup.py (path:/tmp/pip-Q5oLlv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:30:19 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)) 08:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:30:19 INFO - Running setup.py (path:/tmp/pip-ghTVg7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:30:19 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)) 08:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:30:19 INFO - Running setup.py (path:/tmp/pip-7uF5NH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:30:19 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)) 08:30:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:30:19 INFO - Running setup.py (path:/tmp/pip-Q_oGXa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:30:20 INFO - Running setup.py (path:/tmp/pip-MWDOfq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:30:20 INFO - Running setup.py (path:/tmp/pip-VargdW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:30:20 INFO - Running setup.py (path:/tmp/pip-ZIEXyo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:30:20 INFO - Running setup.py (path:/tmp/pip-8jLtQm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:30:20 INFO - Running setup.py (path:/tmp/pip-jkNvEj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:30:20 INFO - Running setup.py (path:/tmp/pip-CESC1p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:30:20 INFO - Running setup.py (path:/tmp/pip-A10thB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:30:20 INFO - Running setup.py (path:/tmp/pip-NuGDXc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:30:20 INFO - Running setup.py (path:/tmp/pip-MtVjly-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:30:20 INFO - Running setup.py (path:/tmp/pip-dSyT9r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:30:20 INFO - Running setup.py (path:/tmp/pip-fCV_nh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:30:20 INFO - Running setup.py (path:/tmp/pip-tyzuc6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:30:20 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)) 08:30:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:30:20 INFO - Running setup.py (path:/tmp/pip-FyIUQq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:30:20 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)) 08:30:20 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)) 08:30:20 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)) 08:30:20 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)) 08:30:20 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)) 08:30:20 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)) 08:30:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 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 08:30:20 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 08:30:22 INFO - Downloading blessings-1.6.tar.gz 08:30:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:30:22 INFO - Installing collected packages: blessings 08:30:22 INFO - Running setup.py install for blessings 08:30:22 INFO - Successfully installed blessings 08:30:22 INFO - Cleaning up... 08:30:22 INFO - Return code: 0 08:30:22 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:30:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:30:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:30:23 INFO - Reading from file tmpfile_stdout 08:30:23 INFO - Current package versions: 08:30:23 INFO - argparse == 1.2.1 08:30:23 INFO - blessings == 1.6 08:30:23 INFO - blobuploader == 1.2.4 08:30:23 INFO - docopt == 0.6.1 08:30:23 INFO - manifestparser == 1.1 08:30:23 INFO - mozInstall == 1.12 08:30:23 INFO - mozcrash == 0.16 08:30:23 INFO - mozdebug == 0.1 08:30:23 INFO - mozdevice == 0.47 08:30:23 INFO - mozfile == 1.2 08:30:23 INFO - mozhttpd == 0.7 08:30:23 INFO - mozinfo == 0.9 08:30:23 INFO - mozleak == 0.1 08:30:23 INFO - mozlog == 3.1 08:30:23 INFO - moznetwork == 0.27 08:30:23 INFO - mozprocess == 0.22 08:30:23 INFO - mozprofile == 0.27 08:30:23 INFO - mozrunner == 6.11 08:30:23 INFO - mozscreenshot == 0.1 08:30:23 INFO - mozsystemmonitor == 0.0 08:30:23 INFO - moztest == 0.7 08:30:23 INFO - mozversion == 1.4 08:30:23 INFO - psutil == 3.1.1 08:30:23 INFO - requests == 1.2.3 08:30:23 INFO - wsgiref == 0.1.2 08:30:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:30: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')]} 08:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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/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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:23 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 08:30:23 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 08:30:23 INFO - Using env: {'DISPLAY': ':0', 08:30:23 INFO - 'HOME': '/home/cltbld', 08:30:23 INFO - 'LANG': 'en_US.UTF-8', 08:30:23 INFO - 'LANGUAGE': 'en_US:en', 08:30:23 INFO - 'LOGNAME': 'cltbld', 08:30:23 INFO - 'MAIL': '/var/mail/cltbld', 08:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:23 INFO - 'MOZ_NO_REMOTE': '1', 08:30:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:23 INFO - 'NO_EM_RESTART': '1', 08:30:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:23 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:23 INFO - 'SHELL': '/bin/bash', 08:30:23 INFO - 'SHLVL': '1', 08:30:23 INFO - 'TERM': 'linux', 08:30:23 INFO - 'TMOUT': '86400', 08:30:23 INFO - 'USER': 'cltbld', 08:30:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:23 INFO - '_': '/tools/buildbot/bin/python'} 08:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 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)) 08:30:23 INFO - Cleaning up... 08:30:23 INFO - Return code: 0 08:30:23 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:30: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')]} 08:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:23 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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:23 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 08:30:23 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 08:30:23 INFO - Using env: {'DISPLAY': ':0', 08:30:23 INFO - 'HOME': '/home/cltbld', 08:30:23 INFO - 'LANG': 'en_US.UTF-8', 08:30:23 INFO - 'LANGUAGE': 'en_US:en', 08:30:23 INFO - 'LOGNAME': 'cltbld', 08:30:23 INFO - 'MAIL': '/var/mail/cltbld', 08:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:23 INFO - 'MOZ_NO_REMOTE': '1', 08:30:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:23 INFO - 'NO_EM_RESTART': '1', 08:30:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:23 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:23 INFO - 'SHELL': '/bin/bash', 08:30:23 INFO - 'SHLVL': '1', 08:30:23 INFO - 'TERM': 'linux', 08:30:23 INFO - 'TMOUT': '86400', 08:30:23 INFO - 'USER': 'cltbld', 08:30:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:23 INFO - '_': '/tools/buildbot/bin/python'} 08:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:23 INFO - Downloading/unpacking jsonschema 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:23 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 08:30:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:25 INFO - Downloading/unpacking functools32 (from jsonschema) 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:25 INFO - warning: no files found matching '*.txt' 08:30:25 INFO - no previously-included directories found matching 'build' 08:30:25 INFO - no previously-included directories found matching 'dist' 08:30:25 INFO - no previously-included directories found matching '.git*' 08:30:25 INFO - Installing collected packages: jsonschema, functools32 08:30:25 INFO - Running setup.py install for functools32 08:30:25 INFO - warning: no files found matching '*.txt' 08:30:25 INFO - no previously-included directories found matching 'build' 08:30:25 INFO - no previously-included directories found matching 'dist' 08:30:25 INFO - no previously-included directories found matching '.git*' 08:30:25 INFO - Successfully installed jsonschema functools32 08:30:25 INFO - Cleaning up... 08:30:25 INFO - Return code: 0 08:30:25 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:30:25 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')]} 08:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:25 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')]} 08:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:25 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 0x7f0be67c7f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1780600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, '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': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', '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 08:30:25 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 08:30:25 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 08:30:25 INFO - Using env: {'DISPLAY': ':0', 08:30:25 INFO - 'HOME': '/home/cltbld', 08:30:25 INFO - 'LANG': 'en_US.UTF-8', 08:30:25 INFO - 'LANGUAGE': 'en_US:en', 08:30:25 INFO - 'LOGNAME': 'cltbld', 08:30:25 INFO - 'MAIL': '/var/mail/cltbld', 08:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:25 INFO - 'MOZ_NO_REMOTE': '1', 08:30:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:25 INFO - 'NO_EM_RESTART': '1', 08:30:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:25 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:25 INFO - 'SHELL': '/bin/bash', 08:30:25 INFO - 'SHLVL': '1', 08:30:25 INFO - 'TERM': 'linux', 08:30:25 INFO - 'TMOUT': '86400', 08:30:25 INFO - 'USER': 'cltbld', 08:30:25 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:25 INFO - '_': '/tools/buildbot/bin/python'} 08:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:26 INFO - Downloading/unpacking flake8 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 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 08:30:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:30:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30: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 08:30:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:30:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 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 08:30:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:30:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:30:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:30:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:30:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:30:28 INFO - no previously-included directories found matching 'docs/_build' 08:30:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:30:28 INFO - Running setup.py install for pep8 08:30:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:30:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:30:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:30:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:30:28 INFO - no previously-included directories found matching 'docs/_build' 08:30:28 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:30:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:30:28 INFO - Cleaning up... 08:30:28 INFO - Return code: 0 08:30:28 INFO - Running post-action listener: _resource_record_post_action 08:30:28 INFO - Running post-action listener: _start_resource_monitoring 08:30:28 INFO - Starting resource monitoring. 08:30:28 INFO - ##### 08:30:28 INFO - ##### Running install step. 08:30:28 INFO - ##### 08:30:28 INFO - Running pre-action listener: _resource_record_pre_action 08:30:28 INFO - Running main action method: install 08:30:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:30:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:30:29 INFO - Reading from file tmpfile_stdout 08:30:29 INFO - Detecting whether we're running mozinstall >=1.0... 08:30:29 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:30:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:30:29 INFO - Reading from file tmpfile_stdout 08:30:29 INFO - Output received: 08:30:29 INFO - Usage: mozinstall [options] installer 08:30:29 INFO - Options: 08:30:29 INFO - -h, --help show this help message and exit 08:30:29 INFO - -d DEST, --destination=DEST 08:30:29 INFO - Directory to install application into. [default: 08:30:29 INFO - "/builds/slave/test-pgo"] 08:30:29 INFO - --app=APP Application being installed. [default: firefox] 08:30:29 INFO - mkdir: /builds/slave/test-pgo/build/application 08:30:29 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'] 08:30:29 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 08:30:43 INFO - Reading from file tmpfile_stdout 08:30:43 INFO - Output received: 08:30:43 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:30:43 INFO - Running post-action listener: _resource_record_post_action 08:30:43 INFO - ##### 08:30:43 INFO - ##### Running run-tests step. 08:30:43 INFO - ##### 08:30:43 INFO - Running pre-action listener: _resource_record_pre_action 08:30:43 INFO - Running main action method: run_tests 08:30:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:30:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:30:43 INFO - Python 2.7.3 08:30:43 INFO - Return code: 0 08:30:43 INFO - grabbing minidump binary from tooltool 08:30:43 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')]} 08:30:43 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 0x180f350>, '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 0x1810250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18106e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:30:43 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 08:30:43 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 08:30:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:30:43 INFO - Return code: 0 08:30:43 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:30:43 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:30:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:30:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:30:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:30:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:30:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:30:44 INFO - Return code: 0 08:30:44 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/1454594667/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:30:44 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/1454594667/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost 08:30:44 INFO - Using env: {'DISPLAY': ':0', 08:30:44 INFO - 'HOME': '/home/cltbld', 08:30:44 INFO - 'LANG': 'en_US.UTF-8', 08:30:44 INFO - 'LANGUAGE': 'en_US:en', 08:30:44 INFO - 'LOGNAME': 'cltbld', 08:30:44 INFO - 'MAIL': '/var/mail/cltbld', 08:30:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:30:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:44 INFO - 'MOZ_NO_REMOTE': '1', 08:30:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:30:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:30:44 INFO - 'NO_EM_RESTART': '1', 08:30:44 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:30:44 INFO - 'PWD': '/builds/slave/test-pgo', 08:30:44 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:30:44 INFO - 'SHELL': '/bin/bash', 08:30:44 INFO - 'SHLVL': '1', 08:30:44 INFO - 'TERM': 'linux', 08:30:44 INFO - 'TMOUT': '86400', 08:30:44 INFO - 'USER': 'cltbld', 08:30:44 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780', 08:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:44 INFO - '_': '/tools/buildbot/bin/python'} 08:30:44 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/1454594667/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost'] with output_timeout 3600 08:30:45 INFO - mozversion INFO | application_buildid: 20160204060427 08:30:45 INFO - mozversion INFO | application_changeset: 1971245e0a52637cdbc41d29ae6921d6774e5473 08:30:45 INFO - mozversion INFO | application_display_name: Nightly 08:30:45 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:30:45 INFO - mozversion INFO | application_name: Firefox 08:30:45 INFO - mozversion INFO | application_remotingname: firefox 08:30:45 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:30:45 INFO - mozversion INFO | application_vendor: Mozilla 08:30:45 INFO - mozversion INFO | application_version: 45.0 08:30:45 INFO - mozversion INFO | platform_buildid: 20160204060427 08:30:45 INFO - mozversion INFO | platform_changeset: 1971245e0a52637cdbc41d29ae6921d6774e5473 08:30:45 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:30:45 INFO - mozversion INFO | platform_version: 45.0 08:30:45 INFO - 2016-02-04 08:30:45,054 DEBUG : using testdate: 1454603445 08:30:45 INFO - 2016-02-04 08:30:45,054 DEBUG : actual date: 1454603445 08:30:45 INFO - 2016-02-04 08:30:45,063 INFO : starting webserver on 'localhost:54293' 08:30:45 INFO - 2016-02-04 08:30:45,064 INFO : Starting test suite talos-linux64-ix-059 08:30:45 INFO - 2016-02-04 08:30:45,064 INFO : Starting test dromaeo_css 08:30:45 INFO - 2016-02-04 08:30:45,064 DEBUG : operating with platform_type : linux_ 08:30:45 INFO - 2016-02-04 08:30:45,065 INFO : Initialising browser for dromaeo_css test... 08:30:45 INFO - 2016-02-04 08:30:45,077 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppN2zxv/profile http://localhost:54293/getInfo.html 08:30:48 INFO - 2016-02-04 08:30:48,895 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:30:48 INFO - 2016-02-04 08:30:48,895 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:30:48 INFO - 2016-02-04 08:30:48,895 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:30:48 INFO - 2016-02-04 08:30:48,895 DEBUG : BROWSER_OUTPUT: __metrics 08:30:49 INFO - 2016-02-04 08:30:49,044 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] 08:30:49 INFO - 2016-02-04 08:30:49,897 INFO : Browser initialized. 08:30:49 INFO - 2016-02-04 08:30:49,897 INFO : Running cycle 1/1 for dromaeo_css test... 08:30:49 INFO - 2016-02-04 08:30:49,898 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppN2zxv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:31:01 INFO - 2016-02-04 08:31:01,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 135168000 08:31:01 INFO - 2016-02-04 08:31:01,682 DEBUG : BROWSER_OUTPUT: 08:31:52 INFO - 2016-02-04 08:31:52,429 DEBUG : BROWSER_OUTPUT: 1454603512429 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] 08:34:00 INFO - 2016-02-04 08:34:00,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54293/tests/dromaeo/cssquery-ext.html) 08:34:01 INFO - 2016-02-04 08:34:01,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 246288384 08:34:01 INFO - 2016-02-04 08:34:01,744 DEBUG : BROWSER_OUTPUT: 08:37:03 INFO - 2016-02-04 08:37:03,867 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/dromaeo/cssquery-ext.html (next: http://localhost:54293/tests/dromaeo/cssquery-jquery.html) 08:37:04 INFO - 2016-02-04 08:37:04,436 DEBUG : BROWSER_OUTPUT: RSS: Main: 212738048 08:37:04 INFO - 2016-02-04 08:37:04,436 DEBUG : BROWSER_OUTPUT: 08:40:06 INFO - 2016-02-04 08:40:06,177 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54293/tests/dromaeo/cssquery-mootools.html) 08:40:06 INFO - 2016-02-04 08:40:06,738 DEBUG : BROWSER_OUTPUT: RSS: Main: 206249984 08:40:06 INFO - 2016-02-04 08:40:06,738 DEBUG : BROWSER_OUTPUT: 08:43:08 INFO - 2016-02-04 08:43:08,472 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54293/tests/dromaeo/cssquery-prototype.html) 08:43:09 INFO - 2016-02-04 08:43:09,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 196866048 08:43:09 INFO - 2016-02-04 08:43:09,038 DEBUG : BROWSER_OUTPUT: 08:46:11 INFO - 2016-02-04 08:46:11,322 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54293/tests/dromaeo/cssquery-yui.html) 08:46:11 INFO - 2016-02-04 08:46:11,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 201490432 08:46:11 INFO - 2016-02-04 08:46:11,883 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/dromaeo/cssquery-yui.html (next: http://localhost:54293/tests/dromaeo/cssquery-dojo.html) 08:49:14 INFO - 2016-02-04 08:49:14,298 DEBUG : BROWSER_OUTPUT: RSS: Main: 241377280 08:49:14 INFO - 2016-02-04 08:49:14,298 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,299 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:49:14 INFO - 2016-02-04 08:49:14,299 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:49:14 INFO - 2016-02-04 08:49:14,300 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:49:14 INFO - 2016-02-04 08:49:14,300 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:49:14 INFO - 2016-02-04 08:49:14,301 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3312.4037673218822;3376.679215474529;3397.711194548465;3405.744569157314;3433.7424693148014;5501.779928802848;5502.449755024496;5535.861603459915;5554.0835762099305;5581.665100093997;5532.031894418481;5559.916601250984;5565.916511252334;5579.079451890433;5582.441755824416;4922.064807686546;5031.22016087507;5058.342415485991;5073.771680274378;5165.096108181072;5099.311592934962;5140.100482415581;5141.331626888518;5144.3826740791155;5150;5025.0954828130925;5028.522290382426;5112.667676601028;5120.846374608767;5164.690118592877;5030.899382012358;5069.949300506984;5076.74616269185;5081;5120;5006.5744411725045;5058.013687330972;5070.011347787183;5084.313617661643;5114.897702045975;5229.189475631283;5235.319408476874;5318.282031925679;5364.1149210380245;5403;6012.158297838306;6019.127226552169;6055.12200730896;6056.909146362808;6061.484773794233;5995.790147344822;6070.817875463743;6071.241094863142;6089.056196289582;6091.817245482643;5415.702136382497;5501.092319767234;5615.578831587606;5678.659280443187;5713.200151978726;6138.14066030756;6139.171211886338;6142.754289828356;6152.846178845475;6168.321484636686;5649.4633009864;5970.462658360681;5975.67133807636;5976.671283079472;5982.132590774313;5868.794592189338;5870.090136028837;5875.6474611523445;5880.14737863016;5898.085796701518;5876.500497457679;5929;5931;5947.107933809876;5969.582129250911;5821.27234095738;5861.296644402699;5981.252343457068;5999.370066143079;6002.939970600238;4530.456345238593;4549.340345649863;4549.499555048942;4554;4562.498125206224;4033.233685599727;4124.237016151961;4125.97937010319;4128.587141285908;4146.834126634961;5770.163326317745;5810.854728631818;5811.564132690065;5817.127430885401;5822.33043200029;6062.939370606326;6122.112293717386;6126;6137.754489820357;6144.631322120687;5550;5584.804531841366;5613.7193140342815;5650.180723795109;5668.376478587352;5213.087709650796;5219.869503262449;5221.712805795718;5224.085784987688;5264.5525130363585;6099.542534309944;6160.44555989963;6167.259928808572;6171.321154672983;6171.5988460750805;5897.321807991964;5917.289925209003;6005.309389420273;6009.098635204754;6050.878982420415;6160.137580738789;6165.0444181152;6166.229221347332;6168.0439531872635;6168.35232300602;5783.392743761844;5945.435183657545;5946.821595352147;5947.9702601486715;5951.910721339097;5919.200907877382;5968.731407086779;6007.249093863267;6007.7596896125415;6016.157737916607;5779.653220806765;5809.622374545641;5813.534917206698;5820.82537523875;5831.154482599914;5695.686737229326;5743.1385292206505;5818.069108942549;5821.068629019336;5823.854403639943;5722.399148089391;5807.332156801853;5812.099124635688;5826.41735826414;5839.065749480063;5962.344142144274;5965.492933100735;5967.164596956342;5967.552433567586;5967.880642387041;5624.29696287964;5719.142128680731;5814.680192589448;5822.4759771619065;5824.417558244311 08:49:14 INFO - 2016-02-04 08:49:14,301 DEBUG : BROWSER_OUTPUT: |1;ext.html;581.8138195777353;627.5669787846386;634.2072409488139;635.4693830651406;667.2793383146203;30864.228394290152;40594.188116237696;40793.59206407935;40825.79587102068;40974.18051638969;44560.55439445613;44861.55138448623;44863;44871.55128448715;44899.77550112245;8012.158723334024;8117.107118216992;8119.959400202991;8853.247473964704;9032.05163457834;8135.918640813576;8236.958815205915;10529.2103092268;10738.08726258269;10742.248042637018;10443.947780261087;10545.156387488982;10548.947255263713;10555.261131720783;10555.366677999305;10395.844062339032;10466.52900619474;10484.899085595978;10514;10517.053465188093;10322.071013608811;10435.79128417435;10443.68668939933;10445.686629401054;10454.163666906628;41617.167656647;41926.371104433456;42038.57961420377;42137.78931105355;42150.367744483854;3441.2601290722614;3604.7296452766145;3686.5391826021746;3700.2229531798353;3702.407614781649;36478.08804779875;36732.81633591815;36969.44545831814;37090.814545927096;37218.25563488715;4038.454808600831;4217.493900731901;4222.683298752575;4268.509121451042;4282.785860706952;278286;284328.578357109;284738;284935;285076;61634.38365616376;62697.68651156715;65851;67135.32864671291;67854;62512.37487625066;62990;63003.36996630067;63101.684491577245;63718.36281637124;14839.406423743145;14864.925675371771;14899.329530171168;14937.103773773608;14952.252387380806;11288.887111128948;11398.487068081791;11413.48639311233;11424.200305978668;11424.942875285737;22552;22837.20069797549;22839.429014274443;22852.200172993864;22901.31296061121;8699.217070463688;8789.428687135316;8896.154865287788;8921.643134274556;9027.23268522183;1047.4291511126455;1112.9944350278197;1113.482230762683;1183.3846399872018;1191.4570631032834;29548;29667.85166074156;29929.102126936228;30437.543436848464;30506;1028.5114570578914;1119.6808909460765;1134.8354488599107;1147.185498296219;1169.6666450061862;13929.58211253664;15559.45541906028;15836.287367068278;15869.20653967297;15961.760573591406;343310;350080;350703;350817;350998;55848.88302233932;56191.71904140535;56195;56277.43722562722;56317.15524267139;1364.7202323523654;1365.2764035061434;1410.964725881861;1417.9432822687183;1425.7291114688383;27950.86024569864;28958.173081725014;29001.274968125123;29396.559051613815;29919.55120673148;44793.328100079176;45110.77444612756;45181.32228016648;45204;45286.547134528235;231975;232096;232585;233111;233625;35328.47007294841;35714;35963.28073438569;36122.277554448236;36136;44910.3263451055;46308;46388.30417543807;46453.53546464627;46537.767311163225;38801.805990969864;39018.41472377974;39215.60784392119;44085.77957110194;44504.777476113704;38394.23211535809;38452.615473844904;39229.0125872459;39425.80287098546;39912.40131397973;38376.6973981562;38637.6136238634;39094.2181156381;39103.2179356417;39166.80416597899;34066.31867362688;34344.65655343515;34348.82825585856;34514;39292.21415571615;26498;27223.72776272212;27393.72606273912;27781.58327625047;28033.579496307164 08:49:14 INFO - 2016-02-04 08:49:14,305 DEBUG : BROWSER_OUTPUT: |2;jquery.html;373.9981300093499;374.0349897265056;376.7042871345993;379.66399736233427;380.2698818268924;15426.69146617067;15663;17207.65584688307;19502.804971950278;19885.701714474275;15191.46829860955;17395;17772.28910843565;17845;17851.464456066307;5167.767450464719;5487.807926722566;5493.450654934504;5613.158026296048;5616.466435688623;4418.293073108303;4556.384888040122;6134.141220229171;6335.619862808247;6343.14367560378;5133.589312854962;6151.4463698267145;6161.599496032766;6176.938230617704;6289.213848268966;5211.296474975887;5213.400458947212;5229.372475302988;5234.319538459976;5240.397354304266;5046.369203849519;5253.4221235664045;5481.794005318823;5491.588130890199;5494.725263736837;12404.503819847196;12448.875511244953;12667.619971400873;13026.478940842355;13029;2249.3926639807323;2256.684064231009;2300.8849557522058;2367.857928524294;2412.0231306320966;9553.904460955371;9920.206383489303;9924.751881203028;9964.900350996542;10263.538140783683;1901.0969789350086;1936.5933154037668;1990.7412036435317;1999.600079984009;2031.6241495323413;66067;66993.66503167454;68151.65924170396;69111.65444172797;69765;10760.730981725519;10771.676849694522;10778.622748203776;10792.406417646947;10818.24272300947;14031.859681403055;14049;14053.367598458093;14057.929710351587;14077.788833167511;7127.643617819088;7291.671874765649;7376.557406555624;7402.074740657418;7457.515261507984;6942.965285173643;7035.437165026786;7045.471589630798;7070.8939365909555;7081.433485321162;4315.525292217853;4459.643228541709;4480.283154695233;4550.795214215368;4553.931691024637;1709.487153853839;1793.973090403645;1812.7643406357354;1821.262388732539;1830.249597664951;442.6923288314655;453.62802501948744;957.1098878043487;980.8970058143935;985.4038306824411;9635.614575417045;10634.20243481742;10926.672199834018;11086.501107450184;11187.32876027441;461.7414248021119;464.1204916682867;466.53346653346654;482.95170482952125;496.3002166944591;5842.5618078644275;6190.350013248634;6231.408016238541;6284;6342.968285158545;581167.0941645264;584054;599844;605038;619198;89448.5527572358;89647;90566.54716726374;90737;91612.54193728989;499.5254508217208;502.01855372746195;503.5644167794831;503.7405736045939;504.1605726465467;10692.83960740574;10854.565817367216;10863.293885897392;10906.836397453886;11104.666859994213;34724.30551388908;38304;38439;38997.41503877387;40007.59992400155;40207.396889047275;41574.792126039174;42590;44544;47652.52347476481;9570.521473926137;9862.21102311799;9995.600615913632;10009.349392289478;10212.540435680263;11459.255148415326;11641.359725215194;11672.941635291769;16006.359745610045;18222.453326400228;14736.705265894836;14824;14865.85134148688;15130.092194468367;15307.311170997109;12490.500379984698;14881.330340134937;14994.850051499345;15548.689026219186;15762.211889405713;15191.392344306105;15301.46444874424;15525.22373881324;15558.533244002698;15676.76484852751;9642.325037247607;9883.555240014073;9967.700968970943;10210.489475526343;10211.693649190536;14937.178455184634;15092.698146036799;15252.23738813077;15334.079955202724;15777.211139443212 08:49:14 INFO - 2016-02-04 08:49:14,306 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1506.209240148921;1714.4428060880214;1771.6456708658268;1777.6711308407946;1825.260769388398;8085.948826652534;8257.587120643959;8263.173682631734;8266.958665206666;8272.296854767337;7771.533707977525;7823.256790604898;7839.9216007839905;7843.33331666809;7843.411744119186;2285.2915596165244;2286.416963674261;2318.6985691860036;2319.9420014499606;2320.9883950580224;2391.5934291170456;2417.6736140620965;2419.4919066995863;2419.6128619420897;2419.9274021779374;2029.076770069615;2071.078370125295;2269.1490690990877;2284.3261237934767;2334.82091543771;2277.3851060213733;2320.756320586348;2334.9883250583725;2338.029717667166;2339.5671800716923;2262.264763951722;2317.9304620861403;2321.1875843454823;2322.581935251671;2323.5236776460783;6831.28271531487;7027.227005029443;7703.229677032308;7861.253180947858;7883.36933045355;4824.469308376076;4873.074115918;4910.852674419773;4911.705297682115;4917.311576379309;3037.271054946819;3064.3871225754715;3102.534619807047;3108.160796584932;3129.264622813637;1002.3234316836107;1013.3869009249372;1031.943243121628;1032.2928793776211;1033.2818691009752;10897.618583349624;11067.280626759315;11068.059214966657;11068.225224234307;11072.335659860515;4241.172971270573;4260.167434487578;4355.412019377343;4369.694121411534;4374.03771170342;9172.357934944623;9173.35786494939;9189.264858811413;9193.77015574616;9203.309751768644;4943.505649435027;4962.60299176065;4965.379327584052;4975.4527002029745;4977.477364876636;4578.5421457853945;4586.197415452282;4592.70147440422;4596.678232523691;4598.471175814757;3453.205762674599;3518.3666939950544;3527.8236088195486;3537.451694987281;3546.2375589248436;1031.6079889642037;1037.0303765978833;1043.3479075577764;1044.1281529922448;1051.1853313681804;1367.2616786934943;1385.0650810702734;1402.2427888939858;1416.4688241909284;1418.5106138382241;7645.311921926942;7824.37405007598;7827.686892524349;7830.373570114377;7838.451308408356;503.9722815245123;507.1074908161588;509.2158076562053;510.35440168187273;513.0201315487401;5687.459691329317;6162.815115546451;6182.474489668429;6213.316535181127;6237.500999919996;9128.45229286245;9738.659146929824;9748.171405430476;9767.16979056774;9776.169025632758;9517.762055948519;9557.283203759745;9671.90328096724;9689.370190937567;9717.61129554824;7685.231476852382;7741.380689544823;7813.492123011986;7822.256885595859;7830.256125668053;7766.650500727594;7818.17909119557;7830.647620857189;7854.4894581851995;7904.13054564009;8739.563021848755;8751.649934002824;8783.780405489913;8827.16141966512;8847.54110594269;8887.11128887132;9047.230985366054;9109.590068446803;9282.535873206447;9297.442153470813;6717.529772915849;6751.426128779109;6758.560693554903;6786.253512113763;6793.762218322335;7333.963330183315;7548.509346892434;7579.886301705589;7583.469157158946;7829.765107046797;7388.593627350332;7398.741044063432;7471.476996610185;7509.774706758806;7600.581967991813;7339.339459448675;7360.595167265852;7401.59291238987;7455.105387353552;7468.178500365064;7147.2852714728115;7489.775306740807;7542.622868856425;7578.734744283924;7623.199564045917;7024.121984751906;7030.753923612649;7048.8590228194125;7051;7066.222715501393;6960.408365289007;6997.055397521473;7045.647717614202;7079.22128565847;7090.539114957512 08:49:14 INFO - 2016-02-04 08:49:14,307 DEBUG : BROWSER_OUTPUT: |4;prototype.html;353.9026767638899;359.4661927038349;360.3909393125617;363.630914621659;363.6654278064182;5977.222961015067;5985.461308482236;5996;6013.75944962201;6022.8795424091495;5932.466078052975;5939.64362138272;5946;5957.225560677106;5962.970185149068;4583.335416364625;4618.607418369425;4659.767011649421;4661.813527458906;4669.252919532875;4643.535646435346;4645.465771436295;4645.9767701161445;4654.208784506625;4657.8835529111875;4601.171789077965;4613.6770426070025;4620.768961551926;4625.306204069383;4633.6061434778085;4406.184855801671;4508.481524624677;4513.977430112861;4650.372199753024;4656.4412270527755;4102.917941641151;4103.753774773523;4110.116324990112;4116.5060192777055;4128.876133715993;5499.697516636584;5631.380548139701;5687.971560142214;5697.715114244313;5718.42815718429;5310.44240354761;5418.729063546807;5427.185922111676;5429.592780541475;5429.864253393657;6333.20834895638;6342.9365706342805;6355.110284560165;6363.268224154235;6364.299927008072;4556.362109304667;4558.49856515783;4560.908781824345;4566.474855391639;4576.702514336557;6413.102165696806;6590.637514936677;6593.934060659428;6598.505112116611;6601.537892347537;4571.474280457724;4609.9308510372375;4611.308303754464;4613.261878099488;4614.792334344963;4658.277966915133;4670.556297151765;4670.6497012724185;4674.368960190331;4674.532546745366;5252.737363131828;5347.064263753828;5348.625596208228;5361.51746342831;5369.677819330853;6376.904346434807;6390.041493775971;6392.776252831129;6392.904106438407;6395.840103997437;1613.0402410565694;1632.3062698353153;1634.4851371818077;1634.8038235411827;1634.959126021859;1066.664000839733;1088.455772113943;1090.1442367741286;1096.3641088168843;1118.7426891814928;6236.750529978841;6238.033104868752;6239.750409983641;6253.093301471352;6254.562180647311;6571.342865713486;6599.406053455212;6606.966965165144;6612.305707900698;6619.50353723463;6099.054646529795;6242.937570624326;6243.720037392323;6246.718897649616;6248.781292654735;3540.291941611688;3555.5022296878456;3562.0738607962116;3563.7327200460068;3566.589842168184;6752.324767523209;6753.425958793558;6764.458843292525;6766.932330676728;6768.781619308474;6547.410733033955;6625.801225963229;6709.261981182066;6805.489588280899;6824;6738.561993470409;6776.898346524806;6794.320567943166;6795.660216989131;6795.72817087311;6493.2857385688485;6621.768238111643;6635.469162467088;6639.4356479697835;6641.203055633355;6512.3162067982175;6544;6544.214694236532;6550.508711846535;6566.606003639797;6764.120664313607;6780.355866192704;6784.525083244125;6790.626515541692;6791;4551.340055691972;4552.954470455253;4552.9772351138035;4554.157480866051;4555.385023021982;6505.902411463927;6592.637404942774;6622.569532980534;6622.701978410887;6625.701843417154;6439.291677915519;6450.741970321134;6454.580452270588;6459.580127291711;6461.353864613689;6338.366163383625;6366.076918846648;6438.67806609677;6443.162388889415;6448.1295025171;6274.592151510137;6340.651264180515;6343.175387199634;6344;6347.270063942521;6423.775167869102;6428.260750013808;6442.645654489048;6450.516211284079;6458.903116453163;6208.534359922934;6301.590396624205;6311.27420346666;6319.66172516172;6323.336049714898 08:49:14 INFO - 2016-02-04 08:49:14,307 DEBUG : BROWSER_OUTPUT: |5;yui.html;895.6059333893087;897.2911399994005;907.2515174980642;908.500324821348;933.5612262236747;13564.186148831064;13672.248026358558;13775.448982040718;15606.297716602752;15711.292991815368;14331.28343582822;14374.49689260876;14415.711685766279;14444.711105777878;14469;739.2939742545883;745.9179490256095;748.3938010211718;851.3955091884753;867.6225841758855;751.0911796725941;758.1508710244507;839.0351096239312;841.4404421060018;848.5884346092171;880.366136381804;884.0584777212281;886.1802832380023;886.5257087458211;906.1799071839995;770.218228498075;773.1032002876636;881.1937077574045;881.2377293640986;905.8414777413959;654.5581732330705;655.5378458187014;655.8917778566579;663.6681659170415;664.4850241063166;13083.738325233537;13258.00564957632;13258.46966121354;13268.13757105785;13270.601881943558;11674;11703.7074073148;12001.279923204636;12125.575604853875;12331;10987.066099381562;11079.889201107966;11123.221374503788;11268.661940141808;11331.546738130464;3793.3930571108767;3809.904752381213;3823.6749876260274;3888.9631246319213;3920.607939206085;45564;45817.54182458166;45820.3126953096;46179.76910115461;46201.76899115483;9481.945684876575;9494.762630934143;9647.228221742243;9817.067378599022;9971.40171589707;17913.37303194382;17978.190981405605;18084.18621162051;18102.45692629223;18150.183241754152;6444.903326450107;6556.672166391661;6560.770373036921;6660.034295027291;6691.899621505681;4549;4587.77061146948;4611.469680986729;4636.629069674418;4659.394278743742;2936.3980384021224;2953.4536110819568;2958.363951750384;2961.6742158362563;2966.555016747462;501.9899602007939;517.7825313368394;519.4934938435072;523.8009556368555;525.1230445156598;635.1933045032782;645.0131299112365;683.3200965039817;733.4205977277998;737.881938889775;8954.507502087317;9268.35121541499;9333.626654933862;9334.25325973934;9365.203957663538;604.4559896093463;612.5405945540845;744.3189481624253;756.4629113329597;771.1093686791764;4303.827846886152;4392.38506609075;4557.361969324297;4590.104929538707;4699.835505757282;52633;60438.09342859861;62023;62195.37804621896;62315.6884215576;49824.75087624539;51268.487315126375;54889.72555137199;55747;56393.718031410404;49392.50607493951;49611.75970600692;50727;51566.226506602434;53474.7326263374;9277.6288948444;9339.53302334867;9344.813103737752;9503.19222866064;9560.808783824146;36804.26391472102;36845.63154368422;37573.06067348338;38051.238975219785;38248.61751382451;45322;46752.29871551997;46859;47450;47523;11278.492467838803;11699.181057325906;11744.119191060876;11961.641150765143;12071.39643017863;11428.48571814287;11477.253978491372;11603.941980290383;11721.941390293336;12100.273983561014;12118.272903625812;12519.749605007666;12552.309622970824;12720.491180352683;13071.738565228832;11906.107041971714;12066.396680166132;12079.27524348542;12132.272063676208;12587.055970802043;11573.13201509907;11652.53389864396;11932.403379830801;11933.641990740292;12348.3825808711;9444.905550944402;9477.801701528266;9499.287553433382;9699;10262.897371026194;9843.70468885906;9858.802823943624;10017.749556261151;10203.336783109075;10219.335743176669 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,826 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: __startTimestamp1454604554298__endTimestamp 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5558.96 Median:5811.56 stddev:617.97 (10.6%) stddev-sans-first:594.21 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: Values: 3312.4 3376.7 3397.7 3405.7 3433.7 5501.8 5502.4 5535.9 5554.1 5581.7 5532.0 5559.9 5565.9 5579.1 5582.4 4922.1 5031.2 5058.3 5073.8 5165.1 5099.3 5140.1 5141.3 5144.4 5150.0 5025.1 5028.5 5112.7 5120.8 5164.7 5030.9 5069.9 5076.7 5081.0 5120.0 5006.6 5058.0 5070.0 5084.3 5114.9 5229.2 5235.3 5318.3 5364.1 5403.0 6012.2 6019.1 6055.1 6056.9 6061.5 5995.8 6070.8 6071.2 6089.1 6091.8 5415.7 5501.1 5615.6 5678.7 5713.2 6138.1 6139.2 6142.8 6152.8 6168.3 5649.5 5970.5 5975.7 5976.7 5982.1 5868.8 5870.1 5875.6 5880.1 5898.1 5876.5 5929.0 5931.0 5947.1 5969.6 5821.3 5861.3 5981.3 5999.4 6002.9 4530.5 4549.3 4549.5 4554.0 4562.5 4033.2 4124.2 4126.0 4128.6 4146.8 5770.2 5810.9 5811.6 5817.1 5822.3 6062.9 6122.1 6126.0 6137.8 6144.6 5550.0 5584.8 5613.7 5650.2 5668.4 5213.1 5219.9 5221.7 5224.1 5264.6 6099.5 6160.4 6167.3 6171.3 6171.6 5897.3 5917.3 6005.3 6009.1 6050.9 6160.1 6165.0 6166.2 6168.0 6168.4 5783.4 5945.4 5946.8 5948.0 5951.9 5919.2 5968.7 6007.2 6007.8 6016.2 5779.7 5809.6 5813.5 5820.8 5831.2 5695.7 5743.1 5818.1 5821.1 5823.9 5722.4 5807.3 5812.1 5826.4 5839.1 5962.3 5965.5 5967.2 5967.6 5967.9 5624.3 5719.1 5814.7 5822.5 5824.4 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,308 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47640.19 Median:35838.64 stddev:76298.38 (212.9%) stddev-sans-first:76430.60 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: Values: 581.8 627.6 634.2 635.5 667.3 30864.2 40594.2 40793.6 40825.8 40974.2 44560.6 44861.6 44863.0 44871.6 44899.8 8012.2 8117.1 8120.0 8853.2 9032.1 8135.9 8237.0 10529.2 10738.1 10742.2 10443.9 10545.2 10548.9 10555.3 10555.4 10395.8 10466.5 10484.9 10514.0 10517.1 10322.1 10435.8 10443.7 10445.7 10454.2 41617.2 41926.4 42038.6 42137.8 42150.4 3441.3 3604.7 3686.5 3700.2 3702.4 36478.1 36732.8 36969.4 37090.8 37218.3 4038.5 4217.5 4222.7 4268.5 4282.8 278286.0 284328.6 284738.0 284935.0 285076.0 61634.4 62697.7 65851.0 67135.3 67854.0 62512.4 62990.0 63003.4 63101.7 63718.4 14839.4 14864.9 14899.3 14937.1 14952.3 11288.9 11398.5 11413.5 11424.2 11424.9 22552.0 22837.2 22839.4 22852.2 22901.3 8699.2 8789.4 8896.2 8921.6 9027.2 1047.4 1113.0 1113.5 1183.4 1191.5 29548.0 29667.9 29929.1 30437.5 30506.0 1028.5 1119.7 1134.8 1147.2 1169.7 13929.6 15559.5 15836.3 15869.2 15961.8 343310.0 350080.0 350703.0 350817.0 350998.0 55848.9 56191.7 56195.0 56277.4 56317.2 1364.7 1365.3 1411.0 1417.9 1425.7 27950.9 28958.2 29001.3 29396.6 29919.6 44793.3 45110.8 45181.3 45204.0 45286.5 231975.0 232096.0 232585.0 233111.0 233625.0 35328.5 35714.0 35963.3 36122.3 36136.0 44910.3 46308.0 46388.3 46453.5 46537.8 38801.8 39018.4 39215.6 44085.8 44504.8 38394.2 38452.6 39229.0 39425.8 39912.4 38376.7 38637.6 39094.2 39103.2 39166.8 34066.3 34344.7 34348.8 34514.0 39292.2 26498.0 27223.7 27393.7 27781.6 28033.6 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30457.17 Median:42082.40 stddev:97944.31 (232.7%) stddev-sans-first:98193.02 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: Values: 374.0 374.0 376.7 379.7 380.3 15426.7 15663.0 17207.7 19502.8 19885.7 15191.5 17395.0 17772.3 17845.0 17851.5 5167.8 5487.8 5493.5 5613.2 5616.5 4418.3 4556.4 6134.1 6335.6 6343.1 5133.6 6151.4 6161.6 6176.9 6289.2 5211.3 5213.4 5229.4 5234.3 5240.4 5046.4 5253.4 5481.8 5491.6 5494.7 12404.5 12448.9 12667.6 13026.5 13029.0 2249.4 2256.7 2300.9 2367.9 2412.0 9553.9 9920.2 9924.8 9964.9 10263.5 1901.1 1936.6 1990.7 1999.6 2031.6 66067.0 66993.7 68151.7 69111.7 69765.0 10760.7 10771.7 10778.6 10792.4 10818.2 14031.9 14049.0 14053.4 14057.9 14077.8 7127.6 7291.7 7376.6 7402.1 7457.5 6943.0 7035.4 7045.5 7070.9 7081.4 4315.5 4459.6 4480.3 4550.8 4553.9 1709.5 1794.0 1812.8 1821.3 1830.2 442.7 453.6 957.1 980.9 985.4 9635.6 10634.2 10926.7 11086.5 11187.3 461.7 464.1 466.5 483.0 496.3 5842.6 6190.4 6231.4 6284.0 6343.0 581167.1 584054.0 599844.0 605038.0 619198.0 89448.6 89647.0 90566.5 90737.0 91612.5 499.5 502.0 503.6 503.7 504.2 10692.8 10854.6 10863.3 10906.8 11104.7 34724.3 38304.0 38439.0 38997.4 40007.6 40207.4 41574.8 42590.0 44544.0 47652.5 9570.5 9862.2 9995.6 10009.3 10212.5 11459.3 11641.4 11672.9 16006.4 18222.5 14736.7 14824.0 14865.9 15130.1 15307.3 12490.5 14881.3 14994.9 15548.7 15762.2 15191.4 15301.5 15525.2 15558.5 15676.8 9642.3 9883.6 9967.7 10210.5 10211.7 14937.2 15092.7 15252.2 15334.1 15777.2 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5660.94 Median:6734.48 stddev:2972.73 (44.1%) stddev-sans-first:2964.67 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: Values: 1506.2 1714.4 1771.6 1777.7 1825.3 8085.9 8257.6 8263.2 8267.0 8272.3 7771.5 7823.3 7839.9 7843.3 7843.4 2285.3 2286.4 2318.7 2319.9 2321.0 2391.6 2417.7 2419.5 2419.6 2419.9 2029.1 2071.1 2269.1 2284.3 2334.8 2277.4 2320.8 2335.0 2338.0 2339.6 2262.3 2317.9 2321.2 2322.6 2323.5 6831.3 7027.2 7703.2 7861.3 7883.4 4824.5 4873.1 4910.9 4911.7 4917.3 3037.3 3064.4 3102.5 3108.2 3129.3 1002.3 1013.4 1031.9 1032.3 1033.3 10897.6 11067.3 11068.1 11068.2 11072.3 4241.2 4260.2 4355.4 4369.7 4374.0 9172.4 9173.4 9189.3 9193.8 9203.3 4943.5 4962.6 4965.4 4975.5 4977.5 4578.5 4586.2 4592.7 4596.7 4598.5 3453.2 3518.4 3527.8 3537.5 3546.2 1031.6 1037.0 1043.3 1044.1 1051.2 1367.3 1385.1 1402.2 1416.5 1418.5 7645.3 7824.4 7827.7 7830.4 7838.5 504.0 507.1 509.2 510.4 513.0 5687.5 6162.8 6182.5 6213.3 6237.5 9128.5 9738.7 9748.2 9767.2 9776.2 9517.8 9557.3 9671.9 9689.4 9717.6 7685.2 7741.4 7813.5 7822.3 7830.3 7766.7 7818.2 7830.6 7854.5 7904.1 8739.6 8751.6 8783.8 8827.2 8847.5 8887.1 9047.2 9109.6 9282.5 9297.4 6717.5 6751.4 6758.6 6786.3 6793.8 7334.0 7548.5 7579.9 7583.5 7829.8 7388.6 7398.7 7471.5 7509.8 7600.6 7339.3 7360.6 7401.6 7455.1 7468.2 7147.3 7489.8 7542.6 7578.7 7623.2 7024.1 7030.8 7048.9 7051.0 7066.2 6960.4 6997.1 7045.6 7079.2 7090.5 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,310 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5360.75 Median:6153.79 stddev:1598.56 (26.0%) stddev-sans-first:1558.25 08:49:14 INFO - 2016-02-04 08:49:14,312 DEBUG : BROWSER_OUTPUT: Values: 353.9 359.5 360.4 363.6 363.7 5977.2 5985.5 5996.0 6013.8 6022.9 5932.5 5939.6 5946.0 5957.2 5963.0 4583.3 4618.6 4659.8 4661.8 4669.3 4643.5 4645.5 4646.0 4654.2 4657.9 4601.2 4613.7 4620.8 4625.3 4633.6 4406.2 4508.5 4514.0 4650.4 4656.4 4102.9 4103.8 4110.1 4116.5 4128.9 5499.7 5631.4 5688.0 5697.7 5718.4 5310.4 5418.7 5427.2 5429.6 5429.9 6333.2 6342.9 6355.1 6363.3 6364.3 4556.4 4558.5 4560.9 4566.5 4576.7 6413.1 6590.6 6593.9 6598.5 6601.5 4571.5 4609.9 4611.3 4613.3 4614.8 4658.3 4670.6 4670.6 4674.4 4674.5 5252.7 5347.1 5348.6 5361.5 5369.7 6376.9 6390.0 6392.8 6392.9 6395.8 1613.0 1632.3 1634.5 1634.8 1635.0 1066.7 1088.5 1090.1 1096.4 1118.7 6236.8 6238.0 6239.8 6253.1 6254.6 6571.3 6599.4 6607.0 6612.3 6619.5 6099.1 6242.9 6243.7 6246.7 6248.8 3540.3 3555.5 3562.1 3563.7 3566.6 6752.3 6753.4 6764.5 6766.9 6768.8 6547.4 6625.8 6709.3 6805.5 6824.0 6738.6 6776.9 6794.3 6795.7 6795.7 6493.3 6621.8 6635.5 6639.4 6641.2 6512.3 6544.0 6544.2 6550.5 6566.6 6764.1 6780.4 6784.5 6790.6 6791.0 4551.3 4553.0 4553.0 4554.2 4555.4 6505.9 6592.6 6622.6 6622.7 6625.7 6439.3 6450.7 6454.6 6459.6 6461.4 6338.4 6366.1 6438.7 6443.2 6448.1 6274.6 6340.7 6343.2 6344.0 6347.3 6423.8 6428.3 6442.6 6450.5 6458.9 6208.5 6301.6 6311.3 6319.7 6323.3 08:49:14 INFO - 2016-02-04 08:49:14,312 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,312 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14316.01 Median:25779.42 stddev:16625.97 (64.5%) stddev-sans-first:16642.07 08:49:14 INFO - 2016-02-04 08:49:14,312 DEBUG : BROWSER_OUTPUT: Values: 895.6 897.3 907.3 908.5 933.6 13564.2 13672.2 13775.4 15606.3 15711.3 14331.3 14374.5 14415.7 14444.7 14469.0 739.3 745.9 748.4 851.4 867.6 751.1 758.2 839.0 841.4 848.6 880.4 884.1 886.2 886.5 906.2 770.2 773.1 881.2 881.2 905.8 654.6 655.5 655.9 663.7 664.5 13083.7 13258.0 13258.5 13268.1 13270.6 11674.0 11703.7 12001.3 12125.6 12331.0 10987.1 11079.9 11123.2 11268.7 11331.5 3793.4 3809.9 3823.7 3889.0 3920.6 45564.0 45817.5 45820.3 46179.8 46201.8 9481.9 9494.8 9647.2 9817.1 9971.4 17913.4 17978.2 18084.2 18102.5 18150.2 6444.9 6556.7 6560.8 6660.0 6691.9 4549.0 4587.8 4611.5 4636.6 4659.4 2936.4 2953.5 2958.4 2961.7 2966.6 502.0 517.8 519.5 523.8 525.1 635.2 645.0 683.3 733.4 737.9 8954.5 9268.4 9333.6 9334.3 9365.2 604.5 612.5 744.3 756.5 771.1 4303.8 4392.4 4557.4 4590.1 4699.8 52633.0 60438.1 62023.0 62195.4 62315.7 49824.8 51268.5 54889.7 55747.0 56393.7 49392.5 49611.8 50727.0 51566.2 53474.7 9277.6 9339.5 9344.8 9503.2 9560.8 36804.3 36845.6 37573.1 38051.2 38248.6 45322.0 46752.3 46859.0 47450.0 47523.0 11278.5 11699.2 11744.1 11961.6 12071.4 11428.5 11477.3 11603.9 11721.9 12100.3 12118.3 12519.7 12552.3 12720.5 13071.7 11906.1 12066.4 12079.3 12132.3 12587.1 11573.1 11652.5 11932.4 11933.6 12348.4 9444.9 9477.8 9499.3 9699.0 10262.9 9843.7 9858.8 10017.7 10203.3 10219.3 08:49:14 INFO - 2016-02-04 08:49:14,312 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:49:14 INFO - 2016-02-04 08:49:14,312 DEBUG : BROWSER_OUTPUT: 08:49:14 INFO - 2016-02-04 08:49:14,841 INFO : Browser exited with error code: 0 08:49:14 INFO - 2016-02-04 08:49:14,862 INFO : Completed test dromaeo_css (00:18:29) 08:49:14 INFO - 2016-02-04 08:49:14,862 INFO : Starting test kraken 08:49:14 INFO - 2016-02-04 08:49:14,862 DEBUG : operating with platform_type : linux_ 08:49:14 INFO - 2016-02-04 08:49:14,863 INFO : Initialising browser for kraken test... 08:49:14 INFO - 2016-02-04 08:49:14,869 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpps1OiV/profile http://localhost:54293/getInfo.html 08:49:18 INFO - 2016-02-04 08:49:18,582 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:49:18 INFO - 2016-02-04 08:49:18,582 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:49:18 INFO - 2016-02-04 08:49:18,582 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:49:18 INFO - 2016-02-04 08:49:18,582 DEBUG : BROWSER_OUTPUT: __metrics 08:49:18 INFO - 2016-02-04 08:49:18,724 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] 08:49:19 INFO - 2016-02-04 08:49:19,439 INFO : Browser initialized. 08:49:19 INFO - 2016-02-04 08:49:19,439 INFO : Running cycle 1/1 for kraken test... 08:49:19 INFO - 2016-02-04 08:49:19,439 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpps1OiV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:49:31 INFO - 2016-02-04 08:49:31,210 DEBUG : BROWSER_OUTPUT: RSS: Main: 134443008 08:49:31 INFO - 2016-02-04 08:49:31,211 DEBUG : BROWSER_OUTPUT: 08:49:33 INFO - 2016-02-04 08:49:33,667 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54293/tests/kraken/driver.html?testName=audio-beat-detection) 08:49:34 INFO - 2016-02-04 08:49:34,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 166662144 08:49:34 INFO - 2016-02-04 08:49:34,234 DEBUG : BROWSER_OUTPUT: 08:49:36 INFO - 2016-02-04 08:49:36,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54293/tests/kraken/driver.html?testName=audio-dft) 08:49:37 INFO - 2016-02-04 08:49:37,348 DEBUG : BROWSER_OUTPUT: RSS: Main: 163880960 08:49:37 INFO - 2016-02-04 08:49:37,349 DEBUG : BROWSER_OUTPUT: 08:49:40 INFO - 2016-02-04 08:49:40,147 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54293/tests/kraken/driver.html?testName=audio-fft) 08:49:40 INFO - 2016-02-04 08:49:40,713 DEBUG : BROWSER_OUTPUT: RSS: Main: 162230272 08:49:40 INFO - 2016-02-04 08:49:40,713 DEBUG : BROWSER_OUTPUT: 08:49:42 INFO - 2016-02-04 08:49:42,673 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54293/tests/kraken/driver.html?testName=audio-oscillator) 08:49:43 INFO - 2016-02-04 08:49:43,238 DEBUG : BROWSER_OUTPUT: RSS: Main: 161411072 08:49:43 INFO - 2016-02-04 08:49:43,238 DEBUG : BROWSER_OUTPUT: 08:49:45 INFO - 2016-02-04 08:49:45,432 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54293/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:49:45 INFO - 2016-02-04 08:49:45,993 DEBUG : BROWSER_OUTPUT: RSS: Main: 161959936 08:49:45 INFO - 2016-02-04 08:49:45,994 DEBUG : BROWSER_OUTPUT: 08:49:48 INFO - 2016-02-04 08:49:48,844 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54293/tests/kraken/driver.html?testName=imaging-darkroom) 08:49:49 INFO - 2016-02-04 08:49:49,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 161316864 08:49:49 INFO - 2016-02-04 08:49:49,412 DEBUG : BROWSER_OUTPUT: 08:49:52 INFO - 2016-02-04 08:49:52,877 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54293/tests/kraken/driver.html?testName=imaging-desaturate) 08:49:53 INFO - 2016-02-04 08:49:53,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 161648640 08:49:53 INFO - 2016-02-04 08:49:53,447 DEBUG : BROWSER_OUTPUT: 08:49:56 INFO - 2016-02-04 08:49:56,137 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54293/tests/kraken/driver.html?testName=json-parse-financial) 08:49:56 INFO - 2016-02-04 08:49:56,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 160583680 08:49:56 INFO - 2016-02-04 08:49:56,703 DEBUG : BROWSER_OUTPUT: 08:49:58 INFO - 2016-02-04 08:49:58,252 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54293/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:49:58 INFO - 2016-02-04 08:49:58,813 DEBUG : BROWSER_OUTPUT: RSS: Main: 168112128 08:49:58 INFO - 2016-02-04 08:49:58,813 DEBUG : BROWSER_OUTPUT: 08:50:00 INFO - 2016-02-04 08:50:00,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:01 INFO - 2016-02-04 08:50:01,140 DEBUG : BROWSER_OUTPUT: RSS: Main: 164532224 08:50:01 INFO - 2016-02-04 08:50:01,140 DEBUG : BROWSER_OUTPUT: 08:50:03 INFO - 2016-02-04 08:50:03,113 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:03 INFO - 2016-02-04 08:50:03,682 DEBUG : BROWSER_OUTPUT: RSS: Main: 168894464 08:50:03 INFO - 2016-02-04 08:50:03,682 DEBUG : BROWSER_OUTPUT: 08:50:06 INFO - 2016-02-04 08:50:06,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:50:06 INFO - 2016-02-04 08:50:06,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 175661056 08:50:06 INFO - 2016-02-04 08:50:06,623 DEBUG : BROWSER_OUTPUT: 08:50:09 INFO - 2016-02-04 08:50:09,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:50:09 INFO - 2016-02-04 08:50:09,701 DEBUG : BROWSER_OUTPUT: RSS: Main: 174231552 08:50:09 INFO - 2016-02-04 08:50:09,701 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,331 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54293/tests/kraken/driver.html?testName=ai-astar) 08:50:11 INFO - 2016-02-04 08:50:11,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 232665088 08:50:11 INFO - 2016-02-04 08:50:11,577 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,579 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:50:11 INFO - 2016-02-04 08:50:11,579 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:50:11 INFO - 2016-02-04 08:50:11,579 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:50:11 INFO - 2016-02-04 08:50:11,579 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:50:11 INFO - 2016-02-04 08:50:11,579 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;110;104;109;111;109;98;110;110;111 08:50:11 INFO - 2016-02-04 08:50:11,579 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;120;143;154;122;150;148;122;160;122 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;160;161;159;162;162;160;158 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |3;audio-fft;70;70;72;158;71;71;71;71;152;71 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;135;92;93;92;144;91;92;147 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;108;109;109;109;109;109;109;109;110;110 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;156;156;156;157;158;157;161;159;156;159 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;95;95;95;95;96;95;95 08:50:11 INFO - 2016-02-04 08:50:11,580 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;55;56;58;58;56;60;56;55;56 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;64;65;65;65;65;64;65 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;76;78;79;78;76;79;86;79;79 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;120;116;115;126;117;127;117;118;118 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;134;144;144;143;145;144;137;156;144;139 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;64;63;65;64;65;64;63;64;66 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,883 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: __startTimestamp1454604611577__endTimestamp 08:50:11 INFO - 2016-02-04 08:50:11,581 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.10 Median:110.00 stddev:4.07 (3.7%) stddev-sans-first:4.30 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 104.0 109.0 111.0 109.0 98.0 110.0 110.0 111.0 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:139.80 Median:149.00 stddev:16.43 (11.0%) stddev-sans-first:16.21 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: Values: 157.0 120.0 143.0 154.0 122.0 150.0 148.0 122.0 160.0 122.0 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,583 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.29 (0.8%) stddev-sans-first:1.36 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 160.0 161.0 159.0 162.0 162.0 160.0 158.0 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.70 Median:71.00 stddev:35.50 (50.0%) stddev-sans-first:37.07 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: Values: 70.0 70.0 72.0 158.0 71.0 71.0 71.0 71.0 152.0 71.0 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.00 Median:92.00 stddev:24.34 (26.5%) stddev-sans-first:25.20 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 135.0 92.0 93.0 92.0 144.0 91.0 92.0 147.0 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.44 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 110.0 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,584 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:157.50 Median:157.50 stddev:1.72 (1.1%) stddev-sans-first:1.73 08:50:11 INFO - 2016-02-04 08:50:11,585 DEBUG : BROWSER_OUTPUT: Values: 156.0 156.0 156.0 157.0 158.0 157.0 161.0 159.0 156.0 159.0 08:50:11 INFO - 2016-02-04 08:50:11,585 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,585 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:50:11 INFO - 2016-02-04 08:50:11,585 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 08:50:11 INFO - 2016-02-04 08:50:11,585 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,586 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:1.78 (3.2%) stddev-sans-first:1.66 08:50:11 INFO - 2016-02-04 08:50:11,586 DEBUG : BROWSER_OUTPUT: Values: 54.0 55.0 56.0 58.0 58.0 56.0 60.0 56.0 55.0 56.0 08:50:11 INFO - 2016-02-04 08:50:11,586 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,586 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 08:50:11 INFO - 2016-02-04 08:50:11,586 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 64.0 65.0 65.0 65.0 65.0 64.0 65.0 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:2.83 (3.6%) stddev-sans-first:2.93 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: Values: 77.0 76.0 78.0 79.0 78.0 76.0 79.0 86.0 79.0 79.0 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.00 Median:118.00 stddev:4.19 (3.6%) stddev-sans-first:4.30 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: Values: 116.0 120.0 116.0 115.0 126.0 117.0 127.0 117.0 118.0 118.0 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.00 Median:144.00 stddev:5.87 (4.1%) stddev-sans-first:5.24 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: Values: 134.0 144.0 144.0 143.0 145.0 144.0 137.0 156.0 144.0 139.0 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:0.99 (1.6%) stddev-sans-first:0.97 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 63.0 65.0 64.0 65.0 64.0 63.0 64.0 66.0 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:50:11 INFO - 2016-02-04 08:50:11,587 DEBUG : BROWSER_OUTPUT: 08:50:12 INFO - 2016-02-04 08:50:12,261 INFO : Browser exited with error code: 0 08:50:12 INFO - 2016-02-04 08:50:12,314 INFO : Completed test kraken (00:00:57) 08:50:12 INFO - 2016-02-04 08:50:12,314 INFO : Starting test v8_7 08:50:12 INFO - 2016-02-04 08:50:12,314 DEBUG : operating with platform_type : linux_ 08:50:12 INFO - 2016-02-04 08:50:12,314 INFO : Initialising browser for v8_7 test... 08:50:12 INFO - 2016-02-04 08:50:12,320 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9Xp9Mv/profile http://localhost:54293/getInfo.html 08:50:15 INFO - 2016-02-04 08:50:15,880 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:50:15 INFO - 2016-02-04 08:50:15,880 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:50:15 INFO - 2016-02-04 08:50:15,880 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:50:15 INFO - 2016-02-04 08:50:15,880 DEBUG : BROWSER_OUTPUT: __metrics 08:50:16 INFO - 2016-02-04 08:50:16,183 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] 08:50:17 INFO - 2016-02-04 08:50:17,045 INFO : Browser initialized. 08:50:17 INFO - 2016-02-04 08:50:17,046 INFO : Running cycle 1/1 for v8_7 test... 08:50:17 INFO - 2016-02-04 08:50:17,046 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp9Xp9Mv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:50:28 INFO - 2016-02-04 08:50:28,745 DEBUG : BROWSER_OUTPUT: RSS: Main: 133783552 08:50:28 INFO - 2016-02-04 08:50:28,746 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,141 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54293/tests/v8_7/run.html (next: http://localhost:54293/tests/v8_7/run.html) 08:50:50 INFO - 2016-02-04 08:50:50,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 424038400 08:50:50 INFO - 2016-02-04 08:50:50,390 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,391 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:50:50 INFO - 2016-02-04 08:50:50,391 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:50:50 INFO - 2016-02-04 08:50:50,392 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:50:50 INFO - 2016-02-04 08:50:50,392 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:50:50 INFO - 2016-02-04 08:50:50,392 DEBUG : BROWSER_OUTPUT: |0;Richards;167.78523489932886 08:50:50 INFO - 2016-02-04 08:50:50,393 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.63615409944023 08:50:50 INFO - 2016-02-04 08:50:50,393 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.17364600781684 08:50:50 INFO - 2016-02-04 08:50:50,393 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 08:50:50 INFO - 2016-02-04 08:50:50,393 DEBUG : BROWSER_OUTPUT: |4;RayTrace;938.9671361502348 08:50:50 INFO - 2016-02-04 08:50:50,393 DEBUG : BROWSER_OUTPUT: |5;Earley;541.4185165132648 08:50:50 INFO - 2016-02-04 08:50:50,393 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 08:50:50 INFO - 2016-02-04 08:50:50,394 DEBUG : BROWSER_OUTPUT: |7;RegExp;24357.14285714286 08:50:50 INFO - 2016-02-04 08:50:50,394 DEBUG : BROWSER_OUTPUT: |8;Splay;531.9148936170212 08:50:50 INFO - 2016-02-04 08:50:50,394 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 08:50:50 INFO - 2016-02-04 08:50:50,394 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:50:50 INFO - 2016-02-04 08:50:50,394 DEBUG : BROWSER_OUTPUT: __startTimestamp1454604650390__endTimestamp 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: Number of tests: 10 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.79 Median:167.79 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: Values: 167.8 08:50:50 INFO - 2016-02-04 08:50:50,395 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,396 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.64 Median:164.64 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,396 DEBUG : BROWSER_OUTPUT: Values: 164.6 08:50:50 INFO - 2016-02-04 08:50:50,396 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,396 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.17 Median:279.17 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,396 DEBUG : BROWSER_OUTPUT: Values: 279.2 08:50:50 INFO - 2016-02-04 08:50:50,396 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,398 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,398 DEBUG : BROWSER_OUTPUT: Values: 5191.7 08:50:50 INFO - 2016-02-04 08:50:50,398 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,398 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:938.97 Median:938.97 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,398 DEBUG : BROWSER_OUTPUT: Values: 939.0 08:50:50 INFO - 2016-02-04 08:50:50,398 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:541.42 Median:541.42 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: Values: 541.4 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: Values: 10526.3 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,399 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24357.14 Median:24357.14 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: Values: 24357.1 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:531.91 Median:531.91 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: Values: 531.9 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: Values: 4826.9 08:50:50 INFO - 2016-02-04 08:50:50,400 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:50:50 INFO - 2016-02-04 08:50:50,401 DEBUG : BROWSER_OUTPUT: 08:50:51 INFO - 2016-02-04 08:50:51,098 INFO : Browser exited with error code: 0 08:50:51 INFO - 2016-02-04 08:50:51,112 INFO : Completed test v8_7 (00:00:38) 08:50:51 INFO - 2016-02-04 08:50:51,448 INFO : Completed test suite (00:20:06) 08:50:51 INFO - 2016-02-04 08:50:51,448 DEBUG : Working with test: dromaeo_css 08:50:51 INFO - 2016-02-04 08:50:51,448 DEBUG : Generating results file: dromaeo_css 08:50:51 INFO - 2016-02-04 08:50:51,449 DEBUG : Working with test: kraken 08:50:51 INFO - 2016-02-04 08:50:51,449 DEBUG : Generating results file: kraken 08:50:51 INFO - 2016-02-04 08:50:51,450 INFO : javascript benchmark 08:50:51 INFO - 2016-02-04 08:50:51,450 DEBUG : Working with test: v8_7 08:50:51 INFO - 2016-02-04 08:50:51,450 DEBUG : Generating results file: v8_7 08:50:51 INFO - 2016-02-04 08:50:51,450 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:50:51 INFO - 2016-02-04 08:50:51,506 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 08:50:51 INFO - 2016-02-04 08:50:51,507 DEBUG : process_Request line: dromaeo_css 7119.74 graph.html#tests=[[72,53,35]] 08:50:51 INFO - 2016-02-04 08:50:51,507 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:50:51 INFO - 2016-02-04 08:50:51,625 DEBUG : process_Request line: kraken 1490.30 graph.html#tests=[[232,53,35]] 08:50:51 INFO - 2016-02-04 08:50:51,626 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:50:51 INFO - 2016-02-04 08:50:51,747 DEBUG : process_Request line: v8_7 23264.85 graph.html#tests=[[230,53,35]] 08:50:51 INFO - 2016-02-04 08:50:51,748 INFO : javascript benchmark 08:50:51 INFO - 2016-02-04 08:50:51,752 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3312.4037673218822, 3376.679215474529, 3397.711194548465, 3405.744569157314, 3433.7424693148014, 5501.779928802848, 5502.449755024496, 5535.861603459915, 5554.0835762099305, 5581.665100093997, 5532.031894418481, 5559.916601250984, 5565.916511252334, 5579.079451890433, 5582.441755824416, 4922.064807686546, 5031.22016087507, 5058.342415485991, 5073.771680274378, 5165.096108181072, 5099.311592934962, 5140.100482415581, 5141.331626888518, 5144.3826740791155, 5150.0, 5025.0954828130925, 5028.522290382426, 5112.667676601028, 5120.846374608767, 5164.690118592877, 5030.899382012358, 5069.949300506984, 5076.74616269185, 5081.0, 5120.0, 5006.5744411725045, 5058.013687330972, 5070.011347787183, 5084.313617661643, 5114.897702045975, 5229.189475631283, 5235.319408476874, 5318.282031925679, 5364.1149210380245, 5403.0, 6012.158297838306, 6019.127226552169, 6055.12200730896, 6056.909146362808, 6061.484773794233, 5995.790147344822, 6070.817875463743, 6071.241094863142, 6089.056196289582, 6091.817245482643, 5415.702136382497, 5501.092319767234, 5615.578831587606, 5678.659280443187, 5713.200151978726, 6138.14066030756, 6139.171211886338, 6142.754289828356, 6152.846178845475, 6168.321484636686, 5649.4633009864, 5970.462658360681, 5975.67133807636, 5976.671283079472, 5982.132590774313, 5868.794592189338, 5870.090136028837, 5875.6474611523445, 5880.14737863016, 5898.085796701518, 5876.500497457679, 5929.0, 5931.0, 5947.107933809876, 5969.582129250911, 5821.27234095738, 5861.296644402699, 5981.252343457068, 5999.370066143079, 6002.939970600238, 4530.456345238593, 4549.340345649863, 4549.499555048942, 4554.0, 4562.498125206224, 4033.233685599727, 4124.237016151961, 4125.97937010319, 4128.587141285908, 4146.834126634961, 5770.163326317745, 5810.854728631818, 5811.564132690065, 5817.127430885401, 5822.33043200029, 6062.939370606326, 6122.112293717386, 6126.0, 6137.754489820357, 6144.631322120687, 5550.0, 5584.804531841366, 5613.7193140342815, 5650.180723795109, 5668.376478587352, 5213.087709650796, 5219.869503262449, 5221.712805795718, 5224.085784987688, 5264.5525130363585, 6099.542534309944, 6160.44555989963, 6167.259928808572, 6171.321154672983, 6171.5988460750805, 5897.321807991964, 5917.289925209003, 6005.309389420273, 6009.098635204754, 6050.878982420415, 6160.137580738789, 6165.0444181152, 6166.229221347332, 6168.0439531872635, 6168.35232300602, 5783.392743761844, 5945.435183657545, 5946.821595352147, 5947.9702601486715, 5951.910721339097, 5919.200907877382, 5968.731407086779, 6007.249093863267, 6007.7596896125415, 6016.157737916607, 5779.653220806765, 5809.622374545641, 5813.534917206698, 5820.82537523875, 5831.154482599914, 5695.686737229326, 5743.1385292206505, 5818.069108942549, 5821.068629019336, 5823.854403639943, 5722.399148089391, 5807.332156801853, 5812.099124635688, 5826.41735826414, 5839.065749480063, 5962.344142144274, 5965.492933100735, 5967.164596956342, 5967.552433567586, 5967.880642387041, 5624.29696287964, 5719.142128680731, 5814.680192589448, 5822.4759771619065, 5824.417558244311], "unit": "ms", "name": "dojo.html", "value": 5519.046551749909}, {"lowerIsBetter": false, "replicates": [581.8138195777353, 627.5669787846386, 634.2072409488139, 635.4693830651406, 667.2793383146203, 30864.228394290152, 40594.188116237696, 40793.59206407935, 40825.79587102068, 40974.18051638969, 44560.55439445613, 44861.55138448623, 44863.0, 44871.55128448715, 44899.77550112245, 8012.158723334024, 8117.107118216992, 8119.959400202991, 8853.247473964704, 9032.05163457834, 8135.918640813576, 8236.958815205915, 10529.2103092268, 10738.08726258269, 10742.248042637018, 10443.947780261087, 10545.156387488982, 10548.947255263713, 10555.261131720783, 10555.366677999305, 10395.844062339032, 10466.52900619474, 10484.899085595978, 10514.0, 10517.053465188093, 10322.071013608811, 10435.79128417435, 10443.68668939933, 10445.686629401054, 10454.163666906628, 41617.167656647, 41926.371104433456, 42038.57961420377, 42137.78931105355, 42150.367744483854, 3441.2601290722614, 3604.7296452766145, 3686.5391826021746, 3700.2229531798353, 3702.407614781649, 36478.08804779875, 36732.81633591815, 36969.44545831814, 37090.814545927096, 37218.25563488715, 4038.454808600831, 4217.493900731901, 4222.683298752575, 4268.509121451042, 4282.785860706952, 278286.0, 284328.578357109, 284738.0, 284935.0, 285076.0, 61634.38365616376, 62697.68651156715, 65851.0, 67135.32864671291, 67854.0, 62512.37487625066, 62990.0, 63003.36996630067, 63101.684491577245, 63718.36281637124, 14839.406423743145, 14864.925675371771, 14899.329530171168, 14937.103773773608, 14952.252387380806, 11288.887111128948, 11398.487068081791, 11413.48639311233, 11424.200305978668, 11424.942875285737, 22552.0, 22837.20069797549, 22839.429014274443, 22852.200172993864, 22901.31296061121, 8699.217070463688, 8789.428687135316, 8896.154865287788, 8921.643134274556, 9027.23268522183, 1047.4291511126455, 1112.9944350278197, 1113.482230762683, 1183.3846399872018, 1191.4570631032834, 29548.0, 29667.85166074156, 29929.102126936228, 30437.543436848464, 30506.0, 1028.5114570578914, 1119.6808909460765, 1134.8354488599107, 1147.185498296219, 1169.6666450061862, 13929.58211253664, 15559.45541906028, 15836.287367068278, 15869.20653967297, 15961.760573591406, 343310.0, 350080.0, 350703.0, 350817.0, 350998.0, 55848.88302233932, 56191.71904140535, 56195.0, 56277.43722562722, 56317.15524267139, 1364.7202323523654, 1365.2764035061434, 1410.964725881861, 1417.9432822687183, 1425.7291114688383, 27950.86024569864, 28958.173081725014, 29001.274968125123, 29396.559051613815, 29919.55120673148, 44793.328100079176, 45110.77444612756, 45181.32228016648, 45204.0, 45286.547134528235, 231975.0, 232096.0, 232585.0, 233111.0, 233625.0, 35328.47007294841, 35714.0, 35963.28073438569, 36122.277554448236, 36136.0, 44910.3263451055, 46308.0, 46388.30417543807, 46453.53546464627, 46537.767311163225, 38801.805990969864, 39018.41472377974, 39215.60784392119, 44085.77957110194, 44504.777476113704, 38394.23211535809, 38452.615473844904, 39229.0125872459, 39425.80287098546, 39912.40131397973, 38376.6973981562, 38637.6136238634, 39094.2181156381, 39103.2179356417, 39166.80416597899, 34066.31867362688, 34344.65655343515, 34348.82825585856, 34514.0, 39292.21415571615, 26498.0, 27223.72776272212, 27393.72606273912, 27781.58327625047, 28033.579496307164], "unit": "ms", "name": "ext.html", "value": 19795.55048371576}, {"lowerIsBetter": false, "replicates": [373.9981300093499, 374.0349897265056, 376.7042871345993, 379.66399736233427, 380.2698818268924, 15426.69146617067, 15663.0, 17207.65584688307, 19502.804971950278, 19885.701714474275, 15191.46829860955, 17395.0, 17772.28910843565, 17845.0, 17851.464456066307, 5167.767450464719, 5487.807926722566, 5493.450654934504, 5613.158026296048, 5616.466435688623, 4418.293073108303, 4556.384888040122, 6134.141220229171, 6335.619862808247, 6343.14367560378, 5133.589312854962, 6151.4463698267145, 6161.599496032766, 6176.938230617704, 6289.213848268966, 5211.296474975887, 5213.400458947212, 5229.372475302988, 5234.319538459976, 5240.397354304266, 5046.369203849519, 5253.4221235664045, 5481.794005318823, 5491.588130890199, 5494.725263736837, 12404.503819847196, 12448.875511244953, 12667.619971400873, 13026.478940842355, 13029.0, 2249.3926639807323, 2256.684064231009, 2300.8849557522058, 2367.857928524294, 2412.0231306320966, 9553.904460955371, 9920.206383489303, 9924.751881203028, 9964.900350996542, 10263.538140783683, 1901.0969789350086, 1936.5933154037668, 1990.7412036435317, 1999.600079984009, 2031.6241495323413, 66067.0, 66993.66503167454, 68151.65924170396, 69111.65444172797, 69765.0, 10760.730981725519, 10771.676849694522, 10778.622748203776, 10792.406417646947, 10818.24272300947, 14031.859681403055, 14049.0, 14053.367598458093, 14057.929710351587, 14077.788833167511, 7127.643617819088, 7291.671874765649, 7376.557406555624, 7402.074740657418, 7457.515261507984, 6942.965285173643, 7035.437165026786, 7045.471589630798, 7070.8939365909555, 7081.433485321162, 4315.525292217853, 4459.643228541709, 4480.283154695233, 4550.795214215368, 4553.931691024637, 1709.487153853839, 1793.973090403645, 1812.7643406357354, 1821.262388732539, 1830.249597664951, 442.6923288314655, 453.62802501948744, 957.1098878043487, 980.8970058143935, 985.4038306824411, 9635.614575417045, 10634.20243481742, 10926.672199834018, 11086.501107450184, 11187.32876027441, 461.7414248021119, 464.1204916682867, 466.53346653346654, 482.95170482952125, 496.3002166944591, 5842.5618078644275, 6190.350013248634, 6231.408016238541, 6284.0, 6342.968285158545, 581167.0941645264, 584054.0, 599844.0, 605038.0, 619198.0, 89448.5527572358, 89647.0, 90566.54716726374, 90737.0, 91612.54193728989, 499.5254508217208, 502.01855372746195, 503.5644167794831, 503.7405736045939, 504.1605726465467, 10692.83960740574, 10854.565817367216, 10863.293885897392, 10906.836397453886, 11104.666859994213, 34724.30551388908, 38304.0, 38439.0, 38997.41503877387, 40007.59992400155, 40207.396889047275, 41574.792126039174, 42590.0, 44544.0, 47652.52347476481, 9570.521473926137, 9862.21102311799, 9995.600615913632, 10009.349392289478, 10212.540435680263, 11459.255148415326, 11641.359725215194, 11672.941635291769, 16006.359745610045, 18222.453326400228, 14736.705265894836, 14824.0, 14865.85134148688, 15130.092194468367, 15307.311170997109, 12490.500379984698, 14881.330340134937, 14994.850051499345, 15548.689026219186, 15762.211889405713, 15191.392344306105, 15301.46444874424, 15525.22373881324, 15558.533244002698, 15676.76484852751, 9642.325037247607, 9883.555240014073, 9967.700968970943, 10210.489475526343, 10211.693649190536, 14937.178455184634, 15092.698146036799, 15252.23738813077, 15334.079955202724, 15777.211139443212], "unit": "ms", "name": "jquery.html", "value": 8295.12986846414}, {"lowerIsBetter": false, "replicates": [1506.209240148921, 1714.4428060880214, 1771.6456708658268, 1777.6711308407946, 1825.260769388398, 8085.948826652534, 8257.587120643959, 8263.173682631734, 8266.958665206666, 8272.296854767337, 7771.533707977525, 7823.256790604898, 7839.9216007839905, 7843.33331666809, 7843.411744119186, 2285.2915596165244, 2286.416963674261, 2318.6985691860036, 2319.9420014499606, 2320.9883950580224, 2391.5934291170456, 2417.6736140620965, 2419.4919066995863, 2419.6128619420897, 2419.9274021779374, 2029.076770069615, 2071.078370125295, 2269.1490690990877, 2284.3261237934767, 2334.82091543771, 2277.3851060213733, 2320.756320586348, 2334.9883250583725, 2338.029717667166, 2339.5671800716923, 2262.264763951722, 2317.9304620861403, 2321.1875843454823, 2322.581935251671, 2323.5236776460783, 6831.28271531487, 7027.227005029443, 7703.229677032308, 7861.253180947858, 7883.36933045355, 4824.469308376076, 4873.074115918, 4910.852674419773, 4911.705297682115, 4917.311576379309, 3037.271054946819, 3064.3871225754715, 3102.534619807047, 3108.160796584932, 3129.264622813637, 1002.3234316836107, 1013.3869009249372, 1031.943243121628, 1032.2928793776211, 1033.2818691009752, 10897.618583349624, 11067.280626759315, 11068.059214966657, 11068.225224234307, 11072.335659860515, 4241.172971270573, 4260.167434487578, 4355.412019377343, 4369.694121411534, 4374.03771170342, 9172.357934944623, 9173.35786494939, 9189.264858811413, 9193.77015574616, 9203.309751768644, 4943.505649435027, 4962.60299176065, 4965.379327584052, 4975.4527002029745, 4977.477364876636, 4578.5421457853945, 4586.197415452282, 4592.70147440422, 4596.678232523691, 4598.471175814757, 3453.205762674599, 3518.3666939950544, 3527.8236088195486, 3537.451694987281, 3546.2375589248436, 1031.6079889642037, 1037.0303765978833, 1043.3479075577764, 1044.1281529922448, 1051.1853313681804, 1367.2616786934943, 1385.0650810702734, 1402.2427888939858, 1416.4688241909284, 1418.5106138382241, 7645.311921926942, 7824.37405007598, 7827.686892524349, 7830.373570114377, 7838.451308408356, 503.9722815245123, 507.1074908161588, 509.2158076562053, 510.35440168187273, 513.0201315487401, 5687.459691329317, 6162.815115546451, 6182.474489668429, 6213.316535181127, 6237.500999919996, 9128.45229286245, 9738.659146929824, 9748.171405430476, 9767.16979056774, 9776.169025632758, 9517.762055948519, 9557.283203759745, 9671.90328096724, 9689.370190937567, 9717.61129554824, 7685.231476852382, 7741.380689544823, 7813.492123011986, 7822.256885595859, 7830.256125668053, 7766.650500727594, 7818.17909119557, 7830.647620857189, 7854.4894581851995, 7904.13054564009, 8739.563021848755, 8751.649934002824, 8783.780405489913, 8827.16141966512, 8847.54110594269, 8887.11128887132, 9047.230985366054, 9109.590068446803, 9282.535873206447, 9297.442153470813, 6717.529772915849, 6751.426128779109, 6758.560693554903, 6786.253512113763, 6793.762218322335, 7333.963330183315, 7548.509346892434, 7579.886301705589, 7583.469157158946, 7829.765107046797, 7388.593627350332, 7398.741044063432, 7471.476996610185, 7509.774706758806, 7600.581967991813, 7339.339459448675, 7360.595167265852, 7401.59291238987, 7455.105387353552, 7468.178500365064, 7147.2852714728115, 7489.775306740807, 7542.622868856425, 7578.734744283924, 7623.199564045917, 7024.121984751906, 7030.753923612649, 7048.8590228194125, 7051.0, 7066.222715501393, 6960.408365289007, 6997.055397521473, 7045.647717614202, 7079.22128565847, 7090.539114957512], "unit": "ms", "name": "mootools.html", "value": 4551.443859140618}, {"lowerIsBetter": false, "replicates": [353.9026767638899, 359.4661927038349, 360.3909393125617, 363.630914621659, 363.6654278064182, 5977.222961015067, 5985.461308482236, 5996.0, 6013.75944962201, 6022.8795424091495, 5932.466078052975, 5939.64362138272, 5946.0, 5957.225560677106, 5962.970185149068, 4583.335416364625, 4618.607418369425, 4659.767011649421, 4661.813527458906, 4669.252919532875, 4643.535646435346, 4645.465771436295, 4645.9767701161445, 4654.208784506625, 4657.8835529111875, 4601.171789077965, 4613.6770426070025, 4620.768961551926, 4625.306204069383, 4633.6061434778085, 4406.184855801671, 4508.481524624677, 4513.977430112861, 4650.372199753024, 4656.4412270527755, 4102.917941641151, 4103.753774773523, 4110.116324990112, 4116.5060192777055, 4128.876133715993, 5499.697516636584, 5631.380548139701, 5687.971560142214, 5697.715114244313, 5718.42815718429, 5310.44240354761, 5418.729063546807, 5427.185922111676, 5429.592780541475, 5429.864253393657, 6333.20834895638, 6342.9365706342805, 6355.110284560165, 6363.268224154235, 6364.299927008072, 4556.362109304667, 4558.49856515783, 4560.908781824345, 4566.474855391639, 4576.702514336557, 6413.102165696806, 6590.637514936677, 6593.934060659428, 6598.505112116611, 6601.537892347537, 4571.474280457724, 4609.9308510372375, 4611.308303754464, 4613.261878099488, 4614.792334344963, 4658.277966915133, 4670.556297151765, 4670.6497012724185, 4674.368960190331, 4674.532546745366, 5252.737363131828, 5347.064263753828, 5348.625596208228, 5361.51746342831, 5369.677819330853, 6376.904346434807, 6390.041493775971, 6392.776252831129, 6392.904106438407, 6395.840103997437, 1613.0402410565694, 1632.3062698353153, 1634.4851371818077, 1634.8038235411827, 1634.959126021859, 1066.664000839733, 1088.455772113943, 1090.1442367741286, 1096.3641088168843, 1118.7426891814928, 6236.750529978841, 6238.033104868752, 6239.750409983641, 6253.093301471352, 6254.562180647311, 6571.342865713486, 6599.406053455212, 6606.966965165144, 6612.305707900698, 6619.50353723463, 6099.054646529795, 6242.937570624326, 6243.720037392323, 6246.718897649616, 6248.781292654735, 3540.291941611688, 3555.5022296878456, 3562.0738607962116, 3563.7327200460068, 3566.589842168184, 6752.324767523209, 6753.425958793558, 6764.458843292525, 6766.932330676728, 6768.781619308474, 6547.410733033955, 6625.801225963229, 6709.261981182066, 6805.489588280899, 6824.0, 6738.561993470409, 6776.898346524806, 6794.320567943166, 6795.660216989131, 6795.72817087311, 6493.2857385688485, 6621.768238111643, 6635.469162467088, 6639.4356479697835, 6641.203055633355, 6512.3162067982175, 6544.0, 6544.214694236532, 6550.508711846535, 6566.606003639797, 6764.120664313607, 6780.355866192704, 6784.525083244125, 6790.626515541692, 6791.0, 4551.340055691972, 4552.954470455253, 4552.9772351138035, 4554.157480866051, 4555.385023021982, 6505.902411463927, 6592.637404942774, 6622.569532980534, 6622.701978410887, 6625.701843417154, 6439.291677915519, 6450.741970321134, 6454.580452270588, 6459.580127291711, 6461.353864613689, 6338.366163383625, 6366.076918846648, 6438.67806609677, 6443.162388889415, 6448.1295025171, 6274.592151510137, 6340.651264180515, 6343.175387199634, 6344.0, 6347.270063942521, 6423.775167869102, 6428.260750013808, 6442.645654489048, 6450.516211284079, 6458.903116453163, 6208.534359922934, 6301.590396624205, 6311.27420346666, 6319.66172516172, 6323.336049714898], "unit": "ms", "name": "prototype.html", "value": 4846.997343484042}, {"lowerIsBetter": false, "replicates": [895.6059333893087, 897.2911399994005, 907.2515174980642, 908.500324821348, 933.5612262236747, 13564.186148831064, 13672.248026358558, 13775.448982040718, 15606.297716602752, 15711.292991815368, 14331.28343582822, 14374.49689260876, 14415.711685766279, 14444.711105777878, 14469.0, 739.2939742545883, 745.9179490256095, 748.3938010211718, 851.3955091884753, 867.6225841758855, 751.0911796725941, 758.1508710244507, 839.0351096239312, 841.4404421060018, 848.5884346092171, 880.366136381804, 884.0584777212281, 886.1802832380023, 886.5257087458211, 906.1799071839995, 770.218228498075, 773.1032002876636, 881.1937077574045, 881.2377293640986, 905.8414777413959, 654.5581732330705, 655.5378458187014, 655.8917778566579, 663.6681659170415, 664.4850241063166, 13083.738325233537, 13258.00564957632, 13258.46966121354, 13268.13757105785, 13270.601881943558, 11674.0, 11703.7074073148, 12001.279923204636, 12125.575604853875, 12331.0, 10987.066099381562, 11079.889201107966, 11123.221374503788, 11268.661940141808, 11331.546738130464, 3793.3930571108767, 3809.904752381213, 3823.6749876260274, 3888.9631246319213, 3920.607939206085, 45564.0, 45817.54182458166, 45820.3126953096, 46179.76910115461, 46201.76899115483, 9481.945684876575, 9494.762630934143, 9647.228221742243, 9817.067378599022, 9971.40171589707, 17913.37303194382, 17978.190981405605, 18084.18621162051, 18102.45692629223, 18150.183241754152, 6444.903326450107, 6556.672166391661, 6560.770373036921, 6660.034295027291, 6691.899621505681, 4549.0, 4587.77061146948, 4611.469680986729, 4636.629069674418, 4659.394278743742, 2936.3980384021224, 2953.4536110819568, 2958.363951750384, 2961.6742158362563, 2966.555016747462, 501.9899602007939, 517.7825313368394, 519.4934938435072, 523.8009556368555, 525.1230445156598, 635.1933045032782, 645.0131299112365, 683.3200965039817, 733.4205977277998, 737.881938889775, 8954.507502087317, 9268.35121541499, 9333.626654933862, 9334.25325973934, 9365.203957663538, 604.4559896093463, 612.5405945540845, 744.3189481624253, 756.4629113329597, 771.1093686791764, 4303.827846886152, 4392.38506609075, 4557.361969324297, 4590.104929538707, 4699.835505757282, 52633.0, 60438.09342859861, 62023.0, 62195.37804621896, 62315.6884215576, 49824.75087624539, 51268.487315126375, 54889.72555137199, 55747.0, 56393.718031410404, 49392.50607493951, 49611.75970600692, 50727.0, 51566.226506602434, 53474.7326263374, 9277.6288948444, 9339.53302334867, 9344.813103737752, 9503.19222866064, 9560.808783824146, 36804.26391472102, 36845.63154368422, 37573.06067348338, 38051.238975219785, 38248.61751382451, 45322.0, 46752.29871551997, 46859.0, 47450.0, 47523.0, 11278.492467838803, 11699.181057325906, 11744.119191060876, 11961.641150765143, 12071.39643017863, 11428.48571814287, 11477.253978491372, 11603.941980290383, 11721.941390293336, 12100.273983561014, 12118.272903625812, 12519.749605007666, 12552.309622970824, 12720.491180352683, 13071.738565228832, 11906.107041971714, 12066.396680166132, 12079.27524348542, 12132.272063676208, 12587.055970802043, 11573.13201509907, 11652.53389864396, 11932.403379830801, 11933.641990740292, 12348.3825808711, 9444.905550944402, 9477.801701528266, 9499.287553433382, 9699.0, 10262.897371026194, 9843.70468885906, 9858.802823943624, 10017.749556261151, 10203.336783109075, 10219.335743176669], "unit": "ms", "name": "yui.html", "value": 6514.300880716889}], "name": "dromaeo_css", "value": 7119.735847811286}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 104.0, 109.0, 111.0, 109.0, 98.0, 110.0, 110.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.1}, {"lowerIsBetter": true, "replicates": [157.0, 120.0, 143.0, 154.0, 122.0, 150.0, 148.0, 122.0, 160.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 139.8}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 160.0, 161.0, 159.0, 162.0, 162.0, 160.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [70.0, 70.0, 72.0, 158.0, 71.0, 71.0, 71.0, 71.0, 152.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 87.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 135.0, 92.0, 93.0, 92.0, 144.0, 91.0, 92.0, 147.0], "unit": "ms", "name": "audio-oscillator", "value": 107.0}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [156.0, 156.0, 156.0, 157.0, 158.0, 157.0, 161.0, 159.0, 156.0, 159.0], "unit": "ms", "name": "imaging-darkroom", "value": 157.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.2}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 56.0, 58.0, 58.0, 56.0, 60.0, 56.0, 55.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 56.4}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.6}, {"lowerIsBetter": true, "replicates": [77.0, 76.0, 78.0, 79.0, 78.0, 76.0, 79.0, 86.0, 79.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.7}, {"lowerIsBetter": true, "replicates": [116.0, 120.0, 116.0, 115.0, 126.0, 117.0, 127.0, 117.0, 118.0, 118.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.0}, {"lowerIsBetter": true, "replicates": [134.0, 144.0, 144.0, 143.0, 145.0, 144.0, 137.0, 156.0, 144.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.0}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 63.0, 65.0, 64.0, 65.0, 64.0, 63.0, 64.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.1}], "name": "kraken", "value": 1490.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.78523489932886], "unit": "score", "name": "Richards", "value": 210.39992}, {"lowerIsBetter": false, "replicates": [164.63615409944023], "unit": "score", "name": "DeltaBlue", "value": 401.60073199999994}, {"lowerIsBetter": false, "replicates": [279.17364600781684], "unit": "score", "name": "Encrypt", "value": 953.4603419999996}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [938.9671361502348], "unit": "score", "name": "RayTrace", "value": 788.0882850000002}, {"lowerIsBetter": false, "replicates": [541.4185165132648], "unit": "score", "name": "Earley", "value": 1230.9571610000005}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24357.14285714286], "unit": "score", "name": "RegExp", "value": 37.40114369501463}, {"lowerIsBetter": false, "replicates": [531.9148936170212], "unit": "score", "name": "Splay", "value": 153.20307999999997}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23264.853866423276}]} 08:50:51 INFO - RETURN: dromaeo_css: 7119.74 08:50:51 INFO - RETURN: kraken: 1490.30 08:50:51 INFO - RETURN: v8_7: 23264.85 08:50:51 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23264.85"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1490.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7119.74"}}} 08:50:51 INFO - Return code: 0 08:50:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:50:51 INFO - # TBPL SUCCESS # 08:50:51 INFO - Running post-action listener: _resource_record_post_action 08:50:51 INFO - Running post-run listener: _resource_record_post_run 08:50:52 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 286720; Write bytes: 506982400; Read time: 56; Write time: 489100 08:50:52 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 239353856; Read time: 0; Write time: 325688 08:50:52 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 286720; Write bytes: 260399104; Read time: 56; Write time: 160940 08:50:52 INFO - Running post-run listener: _upload_blobber_files 08:50:52 INFO - Blob upload gear active. 08:50:52 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:50:52 INFO - Copying logs to upload dir... 08:50:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1252.742141 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-02-04 08:50:52.939326) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-04 08:50:52.943236) ========= 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009842 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454594667/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-04 08:50:52.995134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:50:52.995478) ========= 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=3a3620fb28aea1604ab609ef000001d2-1454603397.710133-292633780 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004625 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-04 08:50:53.045019) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-04 08:50:53.045356) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-04 08:50:53.045704) ========= ========= Total master_lag: 0.24 =========