builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1455209369.76 results: success (0) buildid: 20160211062134 builduid: 84c98fcbee7f4b0682e21d9c8247a3a2 revision: 9321c2755a3a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.757707) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.758224) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.758538) ========= 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006305 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.781365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.781639) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.794752) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.795027) ========= 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004707 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.844468) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.844773) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.845126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:29.845448) ========= 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-11 08:49:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 46.8K=0.3s 2016-02-11 08:49:31 (46.8 KB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.405736 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:30.262898) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:30.263197) ========= 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018574 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:30.313082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-11 08:49:30.313442) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9321c2755a3a --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 9321c2755a3a --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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-11 08:49:31,304 Setting DEBUG logging. 2016-02-11 08:49:31,304 attempt 1/10 2016-02-11 08:49:31,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9321c2755a3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-11 08:49:32,177 unpacking tar archive at: mozilla-beta-9321c2755a3a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.154925 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-11 08:49:31.481019) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:31.481380) ========= echo 9321c2755a3a in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'9321c2755a3a'] 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 9321c2755a3a program finished with exit code 0 elapsedTime=0.004055 script_repo_revision: '9321c2755a3a' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:31.530622) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:31.530898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-11 08:49:31.541540) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-02-11 08:49:31.541827) ========= /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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:49:32 INFO - MultiFileLogger online at 20160211 08:49:32 in /builds/slave/test-pgo 08:49:32 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:49:32 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:49:32 INFO - {'append_to_log': False, 08:49:32 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:49:32 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:49:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:49:32 INFO - 'branch': 'Mozilla-Beta', 08:49:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:32 INFO - 'config_files': ('talos/linux_config.py',), 08:49:32 INFO - 'default_actions': ('clobber', 08:49:32 INFO - 'read-buildbot-config', 08:49:32 INFO - 'download-and-extract', 08:49:32 INFO - 'populate-webroot', 08:49:32 INFO - 'create-virtualenv', 08:49:32 INFO - 'install', 08:49:32 INFO - 'run-tests'), 08:49:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:32 INFO - 'download_minidump_stackwalk': True, 08:49:32 INFO - 'download_symbols': 'ondemand', 08:49:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:32 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:32 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:32 INFO - 'installer_path': 'installer.exe', 08:49:32 INFO - 'log_level': 'info', 08:49:32 INFO - 'log_name': 'talos', 08:49:32 INFO - 'log_to_console': True, 08:49:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:49:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:49:32 INFO - 'opt_config_files': (), 08:49:32 INFO - 'pip_index': False, 08:49:32 INFO - 'sps_profile': False, 08:49:32 INFO - 'sps_profile_interval': 0, 08:49:32 INFO - 'suite': 'dromaeojs', 08:49:32 INFO - 'system_bits': '32', 08:49:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:32 INFO - 'title': 'talos-linux64-ix-021', 08:49:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:32 INFO - 'use_talos_json': True, 08:49:32 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:49:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:32 INFO - 'work_dir': 'build'} 08:49:32 INFO - ##### 08:49:32 INFO - ##### Running clobber step. 08:49:32 INFO - ##### 08:49:32 INFO - Running pre-action listener: _resource_record_pre_action 08:49:32 INFO - Running main action method: clobber 08:49:32 INFO - rmtree: /builds/slave/test-pgo/build 08:49:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:49:33 INFO - Running post-action listener: _resource_record_post_action 08:49:33 INFO - ##### 08:49:33 INFO - ##### Running read-buildbot-config step. 08:49:33 INFO - ##### 08:49:33 INFO - Running pre-action listener: _resource_record_pre_action 08:49:33 INFO - Running main action method: read_buildbot_config 08:49:33 INFO - Using buildbot properties: 08:49:33 INFO - { 08:49:33 INFO - "properties": { 08:49:33 INFO - "buildnumber": 116, 08:49:33 INFO - "product": "firefox", 08:49:33 INFO - "script_repo_revision": "production", 08:49:33 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:49:33 INFO - "repository": "", 08:49:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:49:33 INFO - "buildid": "20160211062134", 08:49:33 INFO - "slavename": "talos-linux64-ix-021", 08:49:33 INFO - "pgo_build": "True", 08:49:33 INFO - "basedir": "/builds/slave/test-pgo", 08:49:33 INFO - "project": "", 08:49:33 INFO - "platform": "ubuntu64_hw", 08:49:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:49:33 INFO - "slavebuilddir": "test", 08:49:33 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:49:33 INFO - "branch": "mozilla-beta", 08:49:33 INFO - "repo_path": "releases/mozilla-beta", 08:49:33 INFO - "stage_platform": "linux64-pgo", 08:49:33 INFO - "builduid": "84c98fcbee7f4b0682e21d9c8247a3a2", 08:49:33 INFO - "revision": "9321c2755a3a" 08:49:33 INFO - }, 08:49:33 INFO - "sourcestamp": { 08:49:33 INFO - "repository": "", 08:49:33 INFO - "hasPatch": false, 08:49:33 INFO - "project": "", 08:49:33 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:49:33 INFO - "changes": [ 08:49:33 INFO - { 08:49:33 INFO - "category": null, 08:49:33 INFO - "files": [ 08:49:33 INFO - { 08:49:33 INFO - "url": null, 08:49:33 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.tar.bz2" 08:49:33 INFO - } 08:49:33 INFO - ], 08:49:33 INFO - "repository": "", 08:49:33 INFO - "rev": "9321c2755a3a", 08:49:33 INFO - "who": "sendchange", 08:49:33 INFO - "when": 1455209288, 08:49:33 INFO - "number": 7594292, 08:49:33 INFO - "comments": "Backed out changeset 4febbf1c7a8e (bug 1240848) for causing bug 1241921 to reappear", 08:49:33 INFO - "project": "", 08:49:33 INFO - "at": "Thu 11 Feb 2016 08:48:08", 08:49:33 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 08:49:33 INFO - "revlink": "", 08:49:33 INFO - "properties": [ 08:49:33 INFO - [ 08:49:33 INFO - "buildid", 08:49:33 INFO - "20160211062134", 08:49:33 INFO - "Change" 08:49:33 INFO - ], 08:49:33 INFO - [ 08:49:33 INFO - "builduid", 08:49:33 INFO - "84c98fcbee7f4b0682e21d9c8247a3a2", 08:49:33 INFO - "Change" 08:49:33 INFO - ], 08:49:33 INFO - [ 08:49:33 INFO - "pgo_build", 08:49:33 INFO - "True", 08:49:33 INFO - "Change" 08:49:33 INFO - ] 08:49:33 INFO - ], 08:49:33 INFO - "revision": "9321c2755a3a" 08:49:33 INFO - } 08:49:33 INFO - ], 08:49:33 INFO - "revision": "9321c2755a3a" 08:49:33 INFO - } 08:49:33 INFO - } 08:49:33 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.tar.bz2. 08:49:33 INFO - Running post-action listener: _resource_record_post_action 08:49:33 INFO - ##### 08:49:33 INFO - ##### Running download-and-extract step. 08:49:33 INFO - ##### 08:49:33 INFO - Running pre-action listener: _resource_record_pre_action 08:49:33 INFO - Running main action method: download_and_extract 08:49:33 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:49:33 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:49:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/test_packages.json 08:49:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:49:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:49:33 INFO - Downloaded 1270 bytes. 08:49:33 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:49:33 INFO - Using the following test package requirements: 08:49:33 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:49:33 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:49:33 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'jsshell-linux-x86_64.zip'], 08:49:33 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:49:33 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:49:33 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 08:49:33 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 08:49:33 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:49:33 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 08:49:33 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 08:49:33 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:49:33 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:49:33 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:49:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:49:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:33 INFO - Downloaded 21581551 bytes. 08:49:33 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:49:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:49:33 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:49:34 INFO - Return code: 0 08:49:34 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:49:34 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:49:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:49:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:35 INFO - Downloaded 21581551 bytes. 08:49:35 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:49:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 08:49:35 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:49:36 INFO - Return code: 0 08:49:36 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:49:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:49:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:37 INFO - Downloaded 11132038 bytes. 08:49:37 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:49:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 08:49:37 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:49:37 INFO - Return code: 0 08:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:37 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:49:37 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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:49:38 INFO - Downloaded 57943596 bytes. 08:49:38 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:49:38 INFO - mkdir: /builds/slave/test-pgo/properties 08:49:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:49:38 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:49:38 INFO - Contents: 08:49:38 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.tar.bz2 08:49:38 INFO - Running post-action listener: _resource_record_post_action 08:49:38 INFO - Running post-action listener: set_extra_try_arguments 08:49:38 INFO - ##### 08:49:38 INFO - ##### Running populate-webroot step. 08:49:38 INFO - ##### 08:49:38 INFO - Running pre-action listener: _resource_record_pre_action 08:49:38 INFO - Running main action method: populate_webroot 08:49:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:38 INFO - '--tppagecycles', 08:49:38 INFO - '7'], 08:49:38 INFO - 'tests': ['tsvgm']}}, 08:49:38 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:49:38 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:38 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:49:38 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:49:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'tests': ['damp', 'tps']}, 08:49:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['damp', 'tps']}, 08:49:38 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:49:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:38 INFO - 'other': {'tests': ['a11yr', 08:49:38 INFO - 'ts_paint', 08:49:38 INFO - 'tpaint', 08:49:38 INFO - 'sessionrestore', 08:49:38 INFO - 'sessionrestore_no_auto_restore']}, 08:49:38 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['a11yr', 08:49:38 INFO - 'ts_paint', 08:49:38 INFO - 'tpaint', 08:49:38 INFO - 'sessionrestore', 08:49:38 INFO - 'sessionrestore_no_auto_restore']}, 08:49:38 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['a11yr', 08:49:38 INFO - 'ts_paint', 08:49:38 INFO - 'tpaint', 08:49:38 INFO - 'sessionrestore', 08:49:38 INFO - 'sessionrestore_no_auto_restore']}, 08:49:38 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['a11yr', 08:49:38 INFO - 'ts_paint', 08:49:38 INFO - 'tpaint', 08:49:38 INFO - 'sessionrestore', 08:49:38 INFO - 'sessionrestore_no_auto_restore']}, 08:49:38 INFO - 'other_l64': {'tests': ['a11yr', 08:49:38 INFO - 'ts_paint', 08:49:38 INFO - 'tpaint', 08:49:38 INFO - 'sessionrestore', 08:49:38 INFO - 'sessionrestore_no_auto_restore']}, 08:49:38 INFO - 'other_nol64': {'tests': ['a11yr', 08:49:38 INFO - 'ts_paint', 08:49:38 INFO - 'tpaint', 08:49:38 INFO - 'sessionrestore', 08:49:38 INFO - 'sessionrestore_no_auto_restore']}, 08:49:38 INFO - 'svgr': {'tests': ['tsvgx', 08:49:38 INFO - 'tsvgr_opacity', 08:49:38 INFO - 'tart', 08:49:38 INFO - 'tscrollx', 08:49:38 INFO - 'cart']}, 08:49:38 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['tsvgx', 08:49:38 INFO - 'tsvgr_opacity', 08:49:38 INFO - 'tart', 08:49:38 INFO - 'tscrollx', 08:49:38 INFO - 'cart']}, 08:49:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'tests': ['tp5o']}, 08:49:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'talos_options': ['--e10s'], 08:49:38 INFO - 'tests': ['tp5o']}, 08:49:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'talos_options': ['--xperf_path', 08:49:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:38 INFO - 'tests': ['tp5n']}, 08:49:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:38 INFO - 'talos_options': ['--e10s', 08:49:38 INFO - '--xperf_path', 08:49:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:38 INFO - 'tests': ['tp5n']}}, 08:49:38 INFO - 'talos.zip': {'path': '', 08:49:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:38 INFO - Running post-action listener: _resource_record_post_action 08:49:38 INFO - ##### 08:49:38 INFO - ##### Running create-virtualenv step. 08:49:38 INFO - ##### 08:49:38 INFO - Running pre-action listener: _resource_record_pre_action 08:49:38 INFO - Running main action method: create_virtualenv 08:49:38 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:49:38 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:49:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:49:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:38 INFO - Using real prefix '/usr' 08:49:38 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:49:39 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:41 INFO - Installing pip.................done. 08:49:41 INFO - Return code: 0 08:49:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:41 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:49:41 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:49:41 INFO - Using env: {'DISPLAY': ':0', 08:49:41 INFO - 'HOME': '/home/cltbld', 08:49:41 INFO - 'LANG': 'en_US.UTF-8', 08:49:41 INFO - 'LANGUAGE': 'en_US:en', 08:49:41 INFO - 'LOGNAME': 'cltbld', 08:49:41 INFO - 'MAIL': '/var/mail/cltbld', 08:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:41 INFO - 'MOZ_NO_REMOTE': '1', 08:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:41 INFO - 'NO_EM_RESTART': '1', 08:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:41 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:41 INFO - 'SHELL': '/bin/bash', 08:49:41 INFO - 'SHLVL': '1', 08:49:41 INFO - 'TERM': 'linux', 08:49:41 INFO - 'TMOUT': '86400', 08:49:41 INFO - 'USER': 'cltbld', 08:49:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:41 INFO - '_': '/tools/buildbot/bin/python'} 08:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:41 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:49:41 INFO - Cleaning up... 08:49:41 INFO - Return code: 0 08:49:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:41 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:49:41 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:49:41 INFO - Using env: {'DISPLAY': ':0', 08:49:41 INFO - 'HOME': '/home/cltbld', 08:49:41 INFO - 'LANG': 'en_US.UTF-8', 08:49:41 INFO - 'LANGUAGE': 'en_US:en', 08:49:41 INFO - 'LOGNAME': 'cltbld', 08:49:41 INFO - 'MAIL': '/var/mail/cltbld', 08:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:41 INFO - 'MOZ_NO_REMOTE': '1', 08:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:41 INFO - 'NO_EM_RESTART': '1', 08:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:41 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:41 INFO - 'SHELL': '/bin/bash', 08:49:41 INFO - 'SHLVL': '1', 08:49:41 INFO - 'TERM': 'linux', 08:49:41 INFO - 'TMOUT': '86400', 08:49:41 INFO - 'USER': 'cltbld', 08:49:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:41 INFO - '_': '/tools/buildbot/bin/python'} 08:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:41 INFO - Downloading/unpacking psutil>=0.7.1 08:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:43 INFO - Installing collected packages: psutil 08:49:43 INFO - Running setup.py install for psutil 08:49:43 INFO - building 'psutil._psutil_linux' extension 08:49:43 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:49:43 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:49:43 INFO - building 'psutil._psutil_posix' extension 08:49:43 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:49:43 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:49:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:44 INFO - Successfully installed psutil 08:49:44 INFO - Cleaning up... 08:49:44 INFO - Return code: 0 08:49:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:44 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:44 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:49:44 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:49:44 INFO - Using env: {'DISPLAY': ':0', 08:49:44 INFO - 'HOME': '/home/cltbld', 08:49:44 INFO - 'LANG': 'en_US.UTF-8', 08:49:44 INFO - 'LANGUAGE': 'en_US:en', 08:49:44 INFO - 'LOGNAME': 'cltbld', 08:49:44 INFO - 'MAIL': '/var/mail/cltbld', 08:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:44 INFO - 'MOZ_NO_REMOTE': '1', 08:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:44 INFO - 'NO_EM_RESTART': '1', 08:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:44 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:44 INFO - 'SHELL': '/bin/bash', 08:49:44 INFO - 'SHLVL': '1', 08:49:44 INFO - 'TERM': 'linux', 08:49:44 INFO - 'TMOUT': '86400', 08:49:44 INFO - 'USER': 'cltbld', 08:49:44 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:44 INFO - '_': '/tools/buildbot/bin/python'} 08:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:44 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:49:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:49:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:46 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:49:46 INFO - Installing collected packages: mozsystemmonitor 08:49:46 INFO - Running setup.py install for mozsystemmonitor 08:49:46 INFO - Successfully installed mozsystemmonitor 08:49:46 INFO - Cleaning up... 08:49:46 INFO - Return code: 0 08:49:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:49:46 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:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:46 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:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:46 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:46 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:49:46 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:49:46 INFO - Using env: {'DISPLAY': ':0', 08:49:46 INFO - 'HOME': '/home/cltbld', 08:49:46 INFO - 'LANG': 'en_US.UTF-8', 08:49:46 INFO - 'LANGUAGE': 'en_US:en', 08:49:46 INFO - 'LOGNAME': 'cltbld', 08:49:46 INFO - 'MAIL': '/var/mail/cltbld', 08:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:46 INFO - 'MOZ_NO_REMOTE': '1', 08:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:46 INFO - 'NO_EM_RESTART': '1', 08:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:46 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:46 INFO - 'SHELL': '/bin/bash', 08:49:46 INFO - 'SHLVL': '1', 08:49:46 INFO - 'TERM': 'linux', 08:49:46 INFO - 'TMOUT': '86400', 08:49:46 INFO - 'USER': 'cltbld', 08:49:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:46 INFO - '_': '/tools/buildbot/bin/python'} 08:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:46 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:49:46 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:49:46 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:49:46 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:49:46 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:49:46 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:49:48 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:48 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:49:48 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:49:48 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:49:48 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:49:48 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:49:48 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:49:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:49:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:48 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:49:48 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:49:48 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:49:48 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:49:48 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:49:48 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:49:48 INFO - Downloading docopt-0.6.1.tar.gz 08:49:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:49 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:49 INFO - Running setup.py install for blobuploader 08:49:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:49 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:49:49 INFO - Running setup.py install for requests 08:49:49 INFO - Running setup.py install for docopt 08:49:49 INFO - Successfully installed blobuploader requests docopt 08:49:49 INFO - Cleaning up... 08:49:49 INFO - Return code: 0 08:49:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:49 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:49 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:49:49 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:49:49 INFO - Using env: {'DISPLAY': ':0', 08:49:49 INFO - 'HOME': '/home/cltbld', 08:49:49 INFO - 'LANG': 'en_US.UTF-8', 08:49:49 INFO - 'LANGUAGE': 'en_US:en', 08:49:49 INFO - 'LOGNAME': 'cltbld', 08:49:49 INFO - 'MAIL': '/var/mail/cltbld', 08:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:49 INFO - 'MOZ_NO_REMOTE': '1', 08:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:49 INFO - 'NO_EM_RESTART': '1', 08:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:49 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:49 INFO - 'SHELL': '/bin/bash', 08:49:49 INFO - 'SHLVL': '1', 08:49:49 INFO - 'TERM': 'linux', 08:49:49 INFO - 'TMOUT': '86400', 08:49:49 INFO - 'USER': 'cltbld', 08:49:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:49 INFO - '_': '/tools/buildbot/bin/python'} 08:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:49:49 INFO - Running setup.py (path:/tmp/pip-Kt1A0h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:49:49 INFO - Running setup.py (path:/tmp/pip-vK2LkY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:49:49 INFO - Running setup.py (path:/tmp/pip-5cjj57-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:49:50 INFO - Running setup.py (path:/tmp/pip-4EVtKj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:49:50 INFO - Running setup.py (path:/tmp/pip-7UKZwz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:49:50 INFO - Running setup.py (path:/tmp/pip-NjLjYG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:49:50 INFO - Running setup.py (path:/tmp/pip-ifbuy5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:49:50 INFO - Running setup.py (path:/tmp/pip-NE39_o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:49:50 INFO - Running setup.py (path:/tmp/pip-E33wou-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:49:50 INFO - Running setup.py (path:/tmp/pip-5BmftW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:49:50 INFO - Running setup.py (path:/tmp/pip-Qdc1B8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:49:50 INFO - Running setup.py (path:/tmp/pip-XYHljr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:49:50 INFO - Running setup.py (path:/tmp/pip-w3mpi_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:49:50 INFO - Running setup.py (path:/tmp/pip-TuOqpp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:49:50 INFO - Running setup.py (path:/tmp/pip-2X1sDf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:49:50 INFO - Running setup.py (path:/tmp/pip-syIjxf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:49:50 INFO - Running setup.py (path:/tmp/pip-gOl64g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:49:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:51 INFO - Running setup.py install for manifestparser 08:49:51 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Running setup.py install for mozcrash 08:49:51 INFO - Running setup.py install for mozdebug 08:49:51 INFO - Running setup.py install for mozdevice 08:49:51 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Running setup.py install for mozfile 08:49:51 INFO - Running setup.py install for mozhttpd 08:49:51 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Running setup.py install for mozinfo 08:49:51 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Running setup.py install for mozInstall 08:49:51 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:49:51 INFO - Running setup.py install for mozleak 08:49:51 INFO - Running setup.py install for mozlog 08:49:52 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Running setup.py install for moznetwork 08:49:52 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Running setup.py install for mozprocess 08:49:52 INFO - Running setup.py install for mozprofile 08:49:52 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Running setup.py install for mozrunner 08:49:52 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Running setup.py install for mozscreenshot 08:49:52 INFO - Running setup.py install for moztest 08:49:52 INFO - Running setup.py install for mozversion 08:49:52 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:49:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:52 INFO - Cleaning up... 08:49:52 INFO - Return code: 0 08:49:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:52 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:52 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:49:52 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:49:52 INFO - Using env: {'DISPLAY': ':0', 08:49:52 INFO - 'HOME': '/home/cltbld', 08:49:52 INFO - 'LANG': 'en_US.UTF-8', 08:49:52 INFO - 'LANGUAGE': 'en_US:en', 08:49:52 INFO - 'LOGNAME': 'cltbld', 08:49:52 INFO - 'MAIL': '/var/mail/cltbld', 08:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:52 INFO - 'MOZ_NO_REMOTE': '1', 08:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:52 INFO - 'NO_EM_RESTART': '1', 08:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:52 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:52 INFO - 'SHELL': '/bin/bash', 08:49:52 INFO - 'SHLVL': '1', 08:49:52 INFO - 'TERM': 'linux', 08:49:52 INFO - 'TMOUT': '86400', 08:49:52 INFO - 'USER': 'cltbld', 08:49:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:52 INFO - '_': '/tools/buildbot/bin/python'} 08:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:49:53 INFO - Running setup.py (path:/tmp/pip-hXcu49-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:49:53 INFO - Running setup.py (path:/tmp/pip-Str_Vh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:49:53 INFO - Running setup.py (path:/tmp/pip-AmcDsJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:49:53 INFO - Running setup.py (path:/tmp/pip-XRVu4F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:49:53 INFO - Running setup.py (path:/tmp/pip-hzJy3t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:49:53 INFO - Running setup.py (path:/tmp/pip-F8kzVc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:49:53 INFO - Running setup.py (path:/tmp/pip-QX1FDl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:49:53 INFO - Running setup.py (path:/tmp/pip-vVbxeA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:49:53 INFO - Running setup.py (path:/tmp/pip-dLaR5o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:49:53 INFO - Running setup.py (path:/tmp/pip-PpbKHa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:49:53 INFO - Running setup.py (path:/tmp/pip-xSsafo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:49:53 INFO - Running setup.py (path:/tmp/pip-JpQbQJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:49:53 INFO - Running setup.py (path:/tmp/pip-BYU3nq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:49:54 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:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:49:54 INFO - Running setup.py (path:/tmp/pip-EbiS_K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:49:54 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:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:49:54 INFO - Running setup.py (path:/tmp/pip-jLkPWD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:49:54 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:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:49:54 INFO - Running setup.py (path:/tmp/pip-MFbHbB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:49:54 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:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:49:54 INFO - Running setup.py (path:/tmp/pip-aQl65t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:49:54 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:49:54 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:49:54 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:49:54 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:49:54 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:49:54 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:49:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:56 INFO - Downloading blessings-1.6.tar.gz 08:49:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:56 INFO - Installing collected packages: blessings 08:49:56 INFO - Running setup.py install for blessings 08:49:56 INFO - Successfully installed blessings 08:49:56 INFO - Cleaning up... 08:49:56 INFO - Return code: 0 08:49:56 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:49:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:49:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:49:56 INFO - Reading from file tmpfile_stdout 08:49:56 INFO - Current package versions: 08:49:56 INFO - argparse == 1.2.1 08:49:56 INFO - blessings == 1.6 08:49:56 INFO - blobuploader == 1.2.4 08:49:56 INFO - docopt == 0.6.1 08:49:56 INFO - manifestparser == 1.1 08:49:56 INFO - mozInstall == 1.12 08:49:56 INFO - mozcrash == 0.16 08:49:56 INFO - mozdebug == 0.1 08:49:56 INFO - mozdevice == 0.47 08:49:56 INFO - mozfile == 1.2 08:49:56 INFO - mozhttpd == 0.7 08:49:56 INFO - mozinfo == 0.9 08:49:56 INFO - mozleak == 0.1 08:49:56 INFO - mozlog == 3.1 08:49:56 INFO - moznetwork == 0.27 08:49:56 INFO - mozprocess == 0.22 08:49:56 INFO - mozprofile == 0.27 08:49:56 INFO - mozrunner == 6.11 08:49:56 INFO - mozscreenshot == 0.1 08:49:56 INFO - mozsystemmonitor == 0.0 08:49:56 INFO - moztest == 0.7 08:49:56 INFO - mozversion == 1.4 08:49:56 INFO - psutil == 3.1.1 08:49:56 INFO - requests == 1.2.3 08:49:56 INFO - wsgiref == 0.1.2 08:49:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:56 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:56 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:49:56 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:49:56 INFO - Using env: {'DISPLAY': ':0', 08:49:56 INFO - 'HOME': '/home/cltbld', 08:49:56 INFO - 'LANG': 'en_US.UTF-8', 08:49:56 INFO - 'LANGUAGE': 'en_US:en', 08:49:56 INFO - 'LOGNAME': 'cltbld', 08:49:56 INFO - 'MAIL': '/var/mail/cltbld', 08:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:56 INFO - 'MOZ_NO_REMOTE': '1', 08:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:56 INFO - 'NO_EM_RESTART': '1', 08:49:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:56 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:56 INFO - 'SHELL': '/bin/bash', 08:49:56 INFO - 'SHLVL': '1', 08:49:56 INFO - 'TERM': 'linux', 08:49:56 INFO - 'TMOUT': '86400', 08:49:56 INFO - 'USER': 'cltbld', 08:49:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:56 INFO - '_': '/tools/buildbot/bin/python'} 08:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 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:49:56 INFO - Cleaning up... 08:49:56 INFO - Return code: 0 08:49:56 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:56 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:56 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:49:56 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:49:56 INFO - Using env: {'DISPLAY': ':0', 08:49:56 INFO - 'HOME': '/home/cltbld', 08:49:56 INFO - 'LANG': 'en_US.UTF-8', 08:49:56 INFO - 'LANGUAGE': 'en_US:en', 08:49:56 INFO - 'LOGNAME': 'cltbld', 08:49:56 INFO - 'MAIL': '/var/mail/cltbld', 08:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:56 INFO - 'MOZ_NO_REMOTE': '1', 08:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:56 INFO - 'NO_EM_RESTART': '1', 08:49:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:56 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:56 INFO - 'SHELL': '/bin/bash', 08:49:56 INFO - 'SHLVL': '1', 08:49:56 INFO - 'TERM': 'linux', 08:49:56 INFO - 'TMOUT': '86400', 08:49:56 INFO - 'USER': 'cltbld', 08:49:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:56 INFO - '_': '/tools/buildbot/bin/python'} 08:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:56 INFO - Downloading/unpacking jsonschema 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:58 INFO - Downloading/unpacking functools32 (from jsonschema) 08:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:58 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:59 INFO - warning: no files found matching '*.txt' 08:49:59 INFO - no previously-included directories found matching 'build' 08:49:59 INFO - no previously-included directories found matching 'dist' 08:49:59 INFO - no previously-included directories found matching '.git*' 08:49:59 INFO - Installing collected packages: jsonschema, functools32 08:49:59 INFO - Running setup.py install for functools32 08:49:59 INFO - warning: no files found matching '*.txt' 08:49:59 INFO - no previously-included directories found matching 'build' 08:49:59 INFO - no previously-included directories found matching 'dist' 08:49:59 INFO - no previously-included directories found matching '.git*' 08:49:59 INFO - Successfully installed jsonschema functools32 08:49:59 INFO - Cleaning up... 08:49:59 INFO - Return code: 0 08:49:59 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:49:59 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:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:59 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:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:59 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 0x7faace93cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18568a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1923600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, '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': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', '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:49:59 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:49:59 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:49:59 INFO - Using env: {'DISPLAY': ':0', 08:49:59 INFO - 'HOME': '/home/cltbld', 08:49:59 INFO - 'LANG': 'en_US.UTF-8', 08:49:59 INFO - 'LANGUAGE': 'en_US:en', 08:49:59 INFO - 'LOGNAME': 'cltbld', 08:49:59 INFO - 'MAIL': '/var/mail/cltbld', 08:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:59 INFO - 'MOZ_NO_REMOTE': '1', 08:49:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:59 INFO - 'NO_EM_RESTART': '1', 08:49:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:49:59 INFO - 'PWD': '/builds/slave/test-pgo', 08:49:59 INFO - 'SHELL': '/bin/bash', 08:49:59 INFO - 'SHLVL': '1', 08:49:59 INFO - 'TERM': 'linux', 08:49:59 INFO - 'TMOUT': '86400', 08:49:59 INFO - 'USER': 'cltbld', 08:49:59 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:59 INFO - '_': '/tools/buildbot/bin/python'} 08:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:59 INFO - Downloading/unpacking flake8 08:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:50:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:50:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:50:01 INFO - no previously-included directories found matching 'docs/_build' 08:50:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:50:01 INFO - Running setup.py install for pep8 08:50:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:50:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:50:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:50:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:50:02 INFO - no previously-included directories found matching 'docs/_build' 08:50:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:50:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:50:02 INFO - Cleaning up... 08:50:02 INFO - Return code: 0 08:50:02 INFO - Running post-action listener: _resource_record_post_action 08:50:02 INFO - Running post-action listener: _start_resource_monitoring 08:50:02 INFO - Starting resource monitoring. 08:50:02 INFO - ##### 08:50:02 INFO - ##### Running install step. 08:50:02 INFO - ##### 08:50:02 INFO - Running pre-action listener: _resource_record_pre_action 08:50:02 INFO - Running main action method: install 08:50:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:50:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:50:02 INFO - Reading from file tmpfile_stdout 08:50:02 INFO - Detecting whether we're running mozinstall >=1.0... 08:50:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:50:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:50:02 INFO - Reading from file tmpfile_stdout 08:50:02 INFO - Output received: 08:50:02 INFO - Usage: mozinstall [options] installer 08:50:02 INFO - Options: 08:50:02 INFO - -h, --help show this help message and exit 08:50:02 INFO - -d DEST, --destination=DEST 08:50:02 INFO - Directory to install application into. [default: 08:50:02 INFO - "/builds/slave/test-pgo"] 08:50:02 INFO - --app=APP Application being installed. [default: firefox] 08:50:02 INFO - mkdir: /builds/slave/test-pgo/build/application 08:50:02 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:50:02 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:50:19 INFO - Reading from file tmpfile_stdout 08:50:19 INFO - Output received: 08:50:19 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:50:19 INFO - Running post-action listener: _resource_record_post_action 08:50:19 INFO - ##### 08:50:19 INFO - ##### Running run-tests step. 08:50:19 INFO - ##### 08:50:19 INFO - Running pre-action listener: _resource_record_pre_action 08:50:19 INFO - Running main action method: run_tests 08:50:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:50:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:50:19 INFO - Python 2.7.3 08:50:19 INFO - Return code: 0 08:50:19 INFO - grabbing minidump binary from tooltool 08:50: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:50:19 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 0x19b2350>, '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 0x19b3250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19b36e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:50:19 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:50:19 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:50:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:19 INFO - Return code: 0 08:50:19 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:50:19 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:50:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:50:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:50:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:50:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:50:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:50:20 INFO - Return code: 0 08:50:20 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/1455200494/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 08:50:20 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/1455200494/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost 08:50:20 INFO - Using env: {'DISPLAY': ':0', 08:50:20 INFO - 'HOME': '/home/cltbld', 08:50:20 INFO - 'LANG': 'en_US.UTF-8', 08:50:20 INFO - 'LANGUAGE': 'en_US:en', 08:50:20 INFO - 'LOGNAME': 'cltbld', 08:50:20 INFO - 'MAIL': '/var/mail/cltbld', 08:50:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:50:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:20 INFO - 'MOZ_NO_REMOTE': '1', 08:50:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:50:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:20 INFO - 'NO_EM_RESTART': '1', 08:50:20 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:50:20 INFO - 'PWD': '/builds/slave/test-pgo', 08:50:20 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:50:20 INFO - 'SHELL': '/bin/bash', 08:50:20 INFO - 'SHLVL': '1', 08:50:20 INFO - 'TERM': 'linux', 08:50:20 INFO - 'TMOUT': '86400', 08:50:20 INFO - 'USER': 'cltbld', 08:50:20 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095', 08:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:20 INFO - '_': '/tools/buildbot/bin/python'} 08:50:20 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/1455200494/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost'] with output_timeout 3600 08:50:20 INFO - mozversion INFO | application_buildid: 20160211062134 08:50:20 INFO - mozversion INFO | application_changeset: 9321c2755a3a7c53cf91d39fae39d6abf90a009e 08:50:20 INFO - mozversion INFO | application_display_name: Nightly 08:50:20 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:20 INFO - mozversion INFO | application_name: Firefox 08:50:20 INFO - mozversion INFO | application_remotingname: firefox 08:50:20 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:50:20 INFO - mozversion INFO | application_vendor: Mozilla 08:50:20 INFO - mozversion INFO | application_version: 45.0 08:50:20 INFO - mozversion INFO | platform_buildid: 20160211062134 08:50:20 INFO - mozversion INFO | platform_changeset: 9321c2755a3a7c53cf91d39fae39d6abf90a009e 08:50:20 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:50:20 INFO - mozversion INFO | platform_version: 45.0 08:50:20 INFO - 2016-02-11 08:50:20,502 DEBUG : using testdate: 1455209420 08:50:20 INFO - 2016-02-11 08:50:20,502 DEBUG : actual date: 1455209420 08:50:20 INFO - 2016-02-11 08:50:20,580 INFO : starting webserver on 'localhost:41102' 08:50:20 INFO - 2016-02-11 08:50:20,581 INFO : Starting test suite talos-linux64-ix-021 08:50:20 INFO - 2016-02-11 08:50:20,581 INFO : Starting test dromaeo_css 08:50:20 INFO - 2016-02-11 08:50:20,581 DEBUG : operating with platform_type : linux_ 08:50:20 INFO - 2016-02-11 08:50:20,582 INFO : Initialising browser for dromaeo_css test... 08:50:20 INFO - 2016-02-11 08:50:20,595 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVUuxEA/profile http://localhost:41102/getInfo.html 08:50:25 INFO - 2016-02-11 08:50:25,099 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:50:25 INFO - 2016-02-11 08:50:25,099 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:50:25 INFO - 2016-02-11 08:50:25,099 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:50:25 INFO - 2016-02-11 08:50:25,099 DEBUG : BROWSER_OUTPUT: __metrics 08:50:25 INFO - 2016-02-11 08:50:25,348 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:26 INFO - 2016-02-11 08:50:26,737 INFO : Browser initialized. 08:50:26 INFO - 2016-02-11 08:50:26,738 INFO : Running cycle 1/1 for dromaeo_css test... 08:50:26 INFO - 2016-02-11 08:50:26,738 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVUuxEA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:50:38 INFO - 2016-02-11 08:50:38,439 DEBUG : BROWSER_OUTPUT: RSS: Main: 133697536 08:50:38 INFO - 2016-02-11 08:50:38,439 DEBUG : BROWSER_OUTPUT: 08:51:29 INFO - 2016-02-11 08:51:29,663 DEBUG : BROWSER_OUTPUT: 1455209489660 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:53:38 INFO - 2016-02-11 08:53:38,299 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41102/tests/dromaeo/cssquery-ext.html) 08:53:39 INFO - 2016-02-11 08:53:39,295 DEBUG : BROWSER_OUTPUT: RSS: Main: 233005056 08:53:39 INFO - 2016-02-11 08:53:39,295 DEBUG : BROWSER_OUTPUT: 08:56:41 INFO - 2016-02-11 08:56:41,451 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/dromaeo/cssquery-ext.html (next: http://localhost:41102/tests/dromaeo/cssquery-jquery.html) 08:56:42 INFO - 2016-02-11 08:56:42,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 209317888 08:56:42 INFO - 2016-02-11 08:56:42,016 DEBUG : BROWSER_OUTPUT: 08:59:43 INFO - 2016-02-11 08:59:43,759 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41102/tests/dromaeo/cssquery-mootools.html) 08:59:44 INFO - 2016-02-11 08:59:44,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 210939904 08:59:44 INFO - 2016-02-11 08:59:44,320 DEBUG : BROWSER_OUTPUT: 09:02:45 INFO - 2016-02-11 09:02:45,991 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41102/tests/dromaeo/cssquery-prototype.html) 09:02:46 INFO - 2016-02-11 09:02:46,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 195440640 09:02:46 INFO - 2016-02-11 09:02:46,573 DEBUG : BROWSER_OUTPUT: 09:05:48 INFO - 2016-02-11 09:05:48,989 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41102/tests/dromaeo/cssquery-yui.html) 09:05:49 INFO - 2016-02-11 09:05:49,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 198053888 09:05:49 INFO - 2016-02-11 09:05:49,562 DEBUG : BROWSER_OUTPUT: 09:08:51 INFO - 2016-02-11 09:08:51,853 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/dromaeo/cssquery-yui.html (next: http://localhost:41102/tests/dromaeo/cssquery-dojo.html) 09:08:52 INFO - 2016-02-11 09:08:52,099 DEBUG : BROWSER_OUTPUT: RSS: Main: 243064832 09:08:52 INFO - 2016-02-11 09:08:52,099 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,099 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:08:52 INFO - 2016-02-11 09:08:52,099 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:08:52 INFO - 2016-02-11 09:08:52,099 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:08:52 INFO - 2016-02-11 09:08:52,099 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:08:52 INFO - 2016-02-11 09:08:52,100 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3305.8677652893884;3360.2775403288288;3395.270016946356;3395.7283417326616;3402.4215883299835;5558.110702287635;5567.164925261213;5570.220169176314;5574.9721251393785;5594.104943209082;5556.972215138919;5577.330720313558;5579.163125531172;5590.273264475622;5592.021396255659;5009.57418619416;5074.594032477411;5094.974525127388;5111.2333150027425;5159.38087429509;5024.974875125619;5106.668066575679;5177.689338639675;5190.532852043315;5193.143131383336;5015.523525265112;5122.795088196468;5133.281340612317;5143.09995750744;5153.201253805666;5140.203268493389;5207.609429292799;5207.6354655174155;5212.4266330703595;5236.581073514109;4928.950710492921;4937.629677774145;5020.949790502122;5094.77073531692;5105.234214867763;5368.543673787733;5378.354597448292;5380.596455265871;5384.5692344612335;5396.028714831329;5873.941260587425;5904.675242861641;5967.16459695643;5974.044152935553;5984.970075149597;5847.970760146172;5931.377205393458;5973.790917317917;6004.219451471325;6037.879242415127;5542.334919809648;5682.801101961411;5708.600397972144;5716.485516303553;5719.971400142973;6152.969235153795;6188.4739797117745;6195.31851496335;6198.969005154945;6203.410675985774;5721;5748.45389687979;5824.417558244142;5843.474087332161;5878.353381128072;5955.52355811534;5962.5826192167;5964.314103878023;5964.940350596526;5968.522518198533;5794.971025144931;5870.5890587659305;5926.140709597084;5926.525877929756;5928.199693041471;5888.263967004124;5947.04847224442;5971.940280597225;6020.488258497989;6053.545984051126;4609.193391156601;4611.654125940568;4613.400257966443;4613.5847773700525;4641.118187544424;3997.4603428537357;4128.298189307765;4155.439015732898;4161;4192.098698779777;5553.72231388437;5607.327120745536;5674.829755107354;5677.801276955287;5699.715014249271;5969.134475501115;6034.909476357858;6037;6041.365656605993;6048.6975651218145;5537.889242215133;5644.85887852807;5761.193432919395;5762.078067509178;5762.538996880239;5092.28707980883;5170.974145129326;5171.172612382001;5172.7413629319135;5177.119889618773;6093.390660933871;6154.753809847645;6157.199564056644;6161.199044124325;6161.322254551996;5822.796202132905;5973.581849270509;5984.251968503937;5986.880262394815;6016.2479690038745;6128.655903792346;6147.754089836357;6153.046277826944;6161.199044124235;6164.198654174928;5905.852353691193;5907.172995780508;5914.763409463573;5916.822495325147;5958.672273025025;5869.8239052828485;5875.20684707559;5930.466258036798;5973.671448070398;6015.849603759941;5919.5856290061;5946.672932988728;5972.731227094878;5975.37258587842;5977.432143946318;5957.225560677258;5966.3138739043825;5967.91048134287;5972.910406343995;5974.342822289458;5505.421930697379;5602.887942241051;5650.886982260414;5711.914321285267;5738.282714660668;5835.299764028174;5883.882322353615;5896.705164741831;5922.496587789914;5923.318818335774;5577.470140336662;5785.971070144622;5790.131480277992;5791.218185545066;5794.913076303775 09:08:52 INFO - 2016-02-11 09:08:52,101 DEBUG : BROWSER_OUTPUT: |1;ext.html;562.9043062679344;590.8641012567109;614.3089024847047;642.0209180998979;703.9824004399893;31717.682823171763;31880.521792173105;39677.60322396779;40231.59768402315;40346.19307613846;45668.771656141675;46098;46181.30728039082;46257.30614040792;46263;7978.321842643382;8106.351491880635;9164.679236226733;9354.953225233865;9356.812863742722;10807.67576972692;11028.779424411507;11106.61126860556;11112.16658750593;11120.499577518991;10832.78334433311;10932.344059356425;10942.890571094307;10956.835647465294;10961.342319460859;8372.120927302636;8518.27594654455;8521.573921303941;10776.622818201364;10963.616273430393;10436.895631043668;10551.683449496528;10563;10567.365958042465;10570.365778053341;41553;41909;41924.1615167698;41983.370249446285;42042;3688.8340024698955;3696.4105434663084;3708.5735140458924;3788.71584631151;4344.804483798237;32046.519302210487;32093.358132837213;32312;32701.83649081763;32967.83516082404;4374.803133858984;4396.736195828228;4435.223835828717;4435.379046933432;4455.131249406367;284391;286049.56975215196;286083;286194;286295;54923.176152357744;55851;60232;65110.34889651138;68437.3156268441;59925.70037149874;60072.399276007556;60259;60300.39699603036;60491.69754151201;15561.610959726097;15650.13924234178;15659.138747369005;15667.764983525256;15668.373265069498;11554.942225288987;11626.418679066013;11633.47649355781;11697.824532632018;11740.882591173979;19632.018399080276;19812.405627831187;20014.099365528586;20038;20135;7673.961630191813;7751.3411360035025;7809.726659566888;7897.526148431112;7932.365410767125;1073.613499140309;1084.9530203353643;1101.553870682375;1107.6566264458045;1125.4597793059377;29418.411631767245;29756.2560935974;29774.851125744233;30014.84992575023;30451.2387190322;1137.175547727907;1144.1658990763956;1191.7199458127334;1230.2556953043459;1251.2304932466468;13761.518346858013;15944.202789860692;15948.76076858848;15988.920055399882;16392.918035409748;331796;332194;332681;332959;334008;54367.72816135894;54538.18192727112;54607.72696136494;54617.453825462035;54835.90328193414;1421.6374824419706;1430.427828868461;1430.6537278862675;1432.3196481671123;1449.8840092792552;29607.407851843273;29618.703812961594;30007.69992300049;30607.846960765055;32760;50526.747366262935;50974.745126275615;51054;51064;51079;264363;264774;265694;266248;267175;30262.394752105276;35754.64245357617;35914.10214744652;36113;36674.8166259167;43324;43700.56299436965;44132.33801492916;44254.55745442633;44638.77680611576;36671.63328366682;37021.6297837029;37197.6280237205;37551.43672844855;37611.81194094012;37158.25683486369;37323.253534929696;37469.81265093657;37853;38118.42822357611;36575.817120914224;36584.81707591445;37205.62794372022;37206.06984825401;37327;33699.49450758191;33881.2672107964;34203.828980855935;34460.13849653779;34590.65409345975;26173.73826261789;26201.73798261993;26525.204243872715;26545.867270663523;27708.30729231785 09:08:52 INFO - 2016-02-11 09:08:52,104 DEBUG : BROWSER_OUTPUT: |2;jquery.html;390.6054884566588;392.29582898696856;395.5411722402014;397.5190020075708;400.3474336830965;13768.58694239172;17805;20278.594428111446;20312.69530957037;20772.376828695164;18208.817911820912;18350.265989360443;19601;19619.705704414446;19625.509362265915;6310.905336419946;6414.486841052704;6423.807285781434;6459.224893012845;6464.547481676284;6278.717457714414;6408.070829729686;6421.967890160543;6426.0360945858265;6469.611823290617;4668.416447944007;4788.592969597588;6394.232692076957;6404.167458230423;6408.583442076273;6825.453963682919;6932.965335173342;7042.0493233413345;7045.89431158533;7051.541649792747;6561.835954101138;6858.417034552069;6858.51990360675;6862.279460656659;6863.862722745517;23074;23152.18967336135;23313.417164570852;23575.528489430115;23608;2226.0984301357976;2464.8521088734733;2569.524637941987;2575.9871200644066;2580.729023452548;9236.861447078232;9411.43531388119;9569.425834449885;9725.97031510439;9747.3664211826;1890.2816929566814;1897.3169658922777;1937.6318499485053;1940.0396803582316;1972.2998335590803;65430;67327.66336168288;68083.97874031944;68468.65765671189;69761.65119174423;11441;11462.936671399062;11474.196806223485;11510.827337589943;11538.76922461563;15019.474318398803;15061.698766024618;15140.091594504365;15187.620309492351;15205.695886082216;8444.324454043663;8447.36644751646;8449.95775021121;8479.491230526188;8487.618057187441;8239.382046346547;8251.83496330082;8274.13121622221;8275.793105172419;8321.708740194064;4855.077535268288;4892.437369702458;4897.216445368796;4903.5586797188425;4923.876903077452;1757.6252021698292;1883.604443066944;1943.232423192831;1957.9706304405445;2025.8683185593184;919.4023884475012;976.5507866381402;985.7387792235063;1059.0204061243473;1088.553692985872;17143.91428042852;17315.740263896052;17348.04585747796;17617;18044.368447104287;1021.8262895307815;1070.9518071686794;1104.9337039777638;1118.5637601335486;1130.8473356096986;10122.190224782;10172.389656620626;10271.383716977005;10312.639057632947;10331.94834025825;450921;457533;465557;558673;560144;85407.57296213479;87925.56037219772;88166.55916720376;91012;91160.54419727859;1186.5669030803695;1194.814803705427;1221.3648902570792;1232.5377983256278;1254.7615952968906;15958.760618590499;15993.1203783793;16080.678386431971;16242.918785405996;16288.592785180464;36166.457503138;36275.81862090673;36444.45333319949;37132.628673713996;37652.246955060196;49964.50035499599;50717.23924141215;50934;51081.489185107675;52039.219411708094;9773.364731292728;9899.158571521213;10186.439745814052;10233.74415639615;10295.794084118426;11746.76506469883;12254;17892.910535447238;17973.910130449265;17984.820151798314;14740.189289589176;14862.405503779728;15055.397784088951;15289.694206115593;15384.615384615026;15011.17438540847;15386.692266154838;15473.613159671098;15577;15651.686966260839;15452.613684657974;15780.132092734551;15873.285702143201;16246.67506649884;16252.187390630657;10248.436336001592;10249.795004099728;10353;10624.628138015132;10850.240483166102;10862.34825910448;10946.069584085442;13934.721305574034;15282.694346112794;15558.455454059507 09:08:52 INFO - 2016-02-11 09:08:52,104 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1554.5025591810622;1610.5892997285691;1620.9189540522975;1790.7492950986864;1881.4261650196702;8222.3833212509;8459.91540084599;8462.703805366813;8558.101399353072;8567.65729370826;8366.37252206084;8450.49297042178;8462.196091371325;8464.576771161433;8466.322694184466;2415.4323733922515;2420.854748715074;2453.264020793764;2456.8403053801535;2458.9262322130276;2446.865422401776;2448.114911920328;2477.938051548717;2478.4175718706088;2480.8015358771345;2092.5814837032576;2146.0879126371306;2149.269248455525;2428.7085549734056;2470.209532949457;2367.9408014799683;2430.890609922558;2434.9634755478683;2448.045262347686;2449.8285120041605;2385.630227314769;2401.1595941420537;2418.8548687078655;2419.129113519132;2425.357280320716;7373.152087509951;7546.849063018763;8101.311388531982;8338.24955753979;8414.621342039623;5002.574781143607;5067.898642027176;5078.466760990117;5078.923816142761;5080;3260.8858689945973;3265.477523596237;3338.4992251162275;3377.864885404581;3392.2876195998624;1077.8221593437074;1093.3986307530906;1100.4882729530727;1119.13826353708;1131.490829126888;11676.591319303869;11844.230125041931;11859;11869.584564540286;11892.286462812259;4512.503624601291;4540.818367265339;4550.681452298308;4567.1550763108935;4599.471060827981;10059.29584929048;10097;10100;10116.797664046826;10119.44343061124;5029.371328583927;5046.091703493406;5047.141985862412;5061.316722242524;5068.189089745623;4591.701539399928;4594.908101837945;4597.241455159928;4598;4598.149342371672;3869.9226015479535;3935.5670876203594;3937.8818635440985;3949.881503554898;3976.204759048202;1201.1591885679884;1211.9091068169923;1225.4607972492074;1233.1614502138452;1238.975220495603;1151.7696460707725;1155.9075273978062;1174.48322737995;1175.3359351966112;1178.0281267953972;8086.7169649062;8098.190180981973;8167.060788009455;8212.055613604392;8251.711190108437;1117.4915413486844;1144.1247445704043;1177.6937996120898;1183.9822402663967;1187.4062968515743;6003.849903752441;6084.361142080106;6426.196725409324;6494.577852439576;6501.414872661483;9686.564104615309;9799.216062714966;10018.248631352677;10020.298579099393;10022.74943126413;9790.363626364264;9849.409035457895;10443.582256709798;10570.735731606772;10577.894221057692;7929.762107136795;8043.39674524413;8122.228388303101;8125.715599953974;8136.715214967566;8092.9190708092165;8193.83612327762;8235.341172706288;8244.340452763645;8250.669973201006;9202.355835091746;9209.769755755891;9299.768055666456;9347.158755712018;9360.4383736976;9441.073267287164;9552.42685438876;9638.518074096408;9688.70933871985;9712.951435242778;7076.221615622175;7129.5365801224825;7139.571625702474;7210.170830354366;7372;8374.330053595575;8403.369747268856;8403.537805420761;8411.873821892554;8416.83166336682;7474.962625186839;7619.390448764197;7734.2265773422205;7846.215378462108;7984.920150798418;7554.2445755426215;7642.1593624702355;7677;7721.884171737541;7737.342325902361;7798.25916537928;7818.6481608328895;7825.960870195612;7827.0607527094835;7834.412419068479;6819.761308354184;7015;7015.298470152944;7017.789466316019;7023.543469674455;6923;6957.721691132298;6958.965205173941;6961.164660240804;6971.163460384786 09:08:52 INFO - 2016-02-11 09:08:52,105 DEBUG : BROWSER_OUTPUT: |4;prototype.html;360.9458581212816;364.82123759357904;373.2032111442071;373.3186933845731;386.72735721316474;5986.161937328778;6054.182685337478;6066.605670631417;6127.050307202385;6144.69276536173;5896.941030589693;6003;6032;6035.486983606399;6039.365866584011;4737.313089602006;4757;4764.071006153801;4774.379330687006;4775.4985726498735;4766.76166191689;4773;4775.426948766153;4776.211925032366;4776.402949631296;4551;4626.259798432252;4643.303504474339;4653.7440440775745;4741.502142275063;4627.9074418511445;4631;4632.698874573142;4641.675082744209;4680.672352935296;3842.9423558646645;3956.485656864618;4140.985458562642;4146.91706165875;4151.377293405983;5648.293963254593;5726.513246374063;5776.768929242825;5800;5800.926828536734;5404.32445944257;5417.891642167135;5434.701091439969;5435.891282174334;5440.727963601844;6317.873642527124;6427.485801135898;6429.678516074177;6437.903431448532;6440.323766004549;4553.112143132091;4577.244754615518;4577.748223847687;4578.336141259532;4581.908361832778;6561.639109848957;6604.900926486107;6633.5024873134225;6634.336566343375;6647.501437392263;4629.745364004944;4633.72197668141;4642.51253618372;4645.187092258841;4651.069786042805;4623.9075218495445;4694.976525117352;4701.3888194535175;4703.129920964564;4707.176244157327;5435.347758268954;5448.101063324586;5449.754761035763;5452.59105567084;5454.10007348791;6467.773627923;6471.126397936312;6477;6480.125183100221;6484.481241500669;1662.4929396534174;1672.230773844021;1686.3170415981301;1687.1985806741945;1687.6962146813446;1139.2651739627893;1141.3494308244221;1144.2905398652888;1156.2368836567994;1183.816508441193;6278.488604558122;6304.211973503312;6310.652914089769;6313.43179113882;6315.084312774622;6617.239017512951;6652.401283884474;6663.400293973566;6667;6685.431738302299;6193.1329613854095;6299.716512756937;6302.968485157545;6307.716152773136;6311.684415779193;3590.1922067534597;3613.638636136418;3613.891583252454;3622.3117607654463;3625.7099432045375;6635.7438470920115;6722.092517510171;6851.2121106072445;6864.622445765531;6883.139607549057;6852.45180385568;6860.3139686031;6887.586744795228;6891.138607674041;6895.55178913369;6695.933040669628;6889.758858439931;6915.965420172867;6930.06444130036;6937.132858392701;6729.528932974645;6743.393094621508;6755.256921738506;6757.087793147862;6758.662066896537;6653.5009874260595;6654;6661.267260601233;6671.766488172891;6674;6771.119754431892;6909.7581584644295;6916.965415172892;6922.688479018557;6929.376356127973;4704.317873908332;4731.479537250969;4736.455307639666;4739.905201896012;4740.597049250852;6691.5650482718465;6710.16122984627;6714.798556043326;6715.05989161508;6727.495437842279;6407.455366293731;6542.018697195459;6545.901811472737;6547.279799222177;6559.508036897348;6542;6568;6571.080048793268;6576.309487503743;6588.176477940257;6382.744690212339;6395.42441180296;6400.871982560416;6405.8718825624155;6411.743530258924;6379.712912918837;6592.571482853599;6610.206775187008;6610.8016759497295;6616.702248398929;6443.67781610927;6445.806625801233;6453.967730161319;6455.128557644845;6459.257185423548 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: |5;yui.html;783.7883771381727;835.2983493865152;921.460945346972;938.3759799733177;948.630034286628;13915.582532524028;14396.64008399788;14405.495807646734;14416;14419.279036048209;13680.726385472284;13701.588952331422;13746.793798093011;13750.793738093937;13773.655658608539;723.6526467295702;732.0081289852253;879.9164079412445;899.4468401932829;899.6311512279966;880.9867851982225;885.2431171348501;886.175856453498;899.4063917814244;899.9640014399416;901.4861528928514;908.9818203635923;911.6171208092617;915.455304094063;915.9496227707474;769.0233403577454;778.4823092643413;911.0252030327553;915.0803442540238;930.9953450232706;684.5139950635055;685.3112621815044;690.2821066091259;690.9412699920513;691.0636088100651;12728.80906786399;12731.55439559611;12761.361931903368;12862.485500579965;12939.87060129396;12406.62780116598;12733.172343797623;12807.167534110253;13215.933920330337;13362.532311369152;10777.78444431107;11033.724156896142;11042.447877606088;11098.389588572625;12366.258024518558;3915.197384536163;3945.5265368156006;3945.6448919597087;3973.264945985002;4037.555868854424;51850.740746296025;52524;52738.208926866515;53010.20484692733;53090.46909530894;11159.27464714791;11220.66338009861;11354.091672666167;11357.716057098474;11728.354940478184;20285.59428811445;20783.480412989797;20914.37256882296;20988.055537500848;21007;8340.62467188978;8461.619227134794;8622.439541429787;8683.609237584324;8756.51839148853;4657.767111644404;4750.596199323097;4778.737169455644;4792.904141917142;4833.806647734122;3279.0654663421287;3307.4873394623874;3308.7353011759;3339.532465454838;3346.1300061983575;579.490048757091;606.4081785313552;609.5489337890018;622.8349487385847;634.0172732264971;691.8443350246155;700.6916956539106;731.6670914733784;747.9364254038359;773.3465221887496;8402.579871006425;8583.35624828128;8622.267107295826;8683.652653893774;8697.869531957025;487.28961345676225;570.2586637371401;647.0262255305735;657.8322527755391;711.9822004449827;4176.081262122329;4660.557247061523;4705.294205869147;4723.48041715411;4764.213904705754;61075.38924610786;61768.69115654393;62213.68893155505;62521.37478625155;62882.371176288565;55707.16439253414;58109.70945145332;58470.12294815581;58841;58865.70567147137;52779.20831187535;52827;53128;53257.73371133197;53720;9312.394694345117;9558.761030974281;9628.807423851344;9648.179904707898;9740.17208537254;36084.27831443304;36453.27093458169;36748;37116.81441592775;37200.81399592985;44472.55527444814;45806;49648.25527617161;50207.49792502028;50693.746531267105;11962.989408544388;12160.939195303967;12184.147109702235;12254.632361029184;12414.379281035732;12927;12959.546415875398;13040;13073.542426015043;13401.396937138048;11464.25482343645;11804.586839460577;11847.111466640208;12016.699582510157;12325.383730813603;12306.323152226714;12451.688707782016;12533.37333133358;12747;12786.552470663853;10312;11828.822567661664;12019.278843269432;12136.150469467053;12311.56909508163;10309.948450257702;10956;10972.067374273276;11072.391018493738;11591.304521728724;9413.811723765622;10186.439745814052;10404.323718958243;10674;10763.085137763377 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,766 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: __startTimestamp1455210532098__endTimestamp 09:08:52 INFO - 2016-02-11 09:08:52,107 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5558.76 Median:5762.54 stddev:606.84 (10.5%) stddev-sans-first:582.40 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: Values: 3305.9 3360.3 3395.3 3395.7 3402.4 5558.1 5567.2 5570.2 5575.0 5594.1 5557.0 5577.3 5579.2 5590.3 5592.0 5009.6 5074.6 5095.0 5111.2 5159.4 5025.0 5106.7 5177.7 5190.5 5193.1 5015.5 5122.8 5133.3 5143.1 5153.2 5140.2 5207.6 5207.6 5212.4 5236.6 4929.0 4937.6 5020.9 5094.8 5105.2 5368.5 5378.4 5380.6 5384.6 5396.0 5873.9 5904.7 5967.2 5974.0 5985.0 5848.0 5931.4 5973.8 6004.2 6037.9 5542.3 5682.8 5708.6 5716.5 5720.0 6153.0 6188.5 6195.3 6199.0 6203.4 5721.0 5748.5 5824.4 5843.5 5878.4 5955.5 5962.6 5964.3 5964.9 5968.5 5795.0 5870.6 5926.1 5926.5 5928.2 5888.3 5947.0 5971.9 6020.5 6053.5 4609.2 4611.7 4613.4 4613.6 4641.1 3997.5 4128.3 4155.4 4161.0 4192.1 5553.7 5607.3 5674.8 5677.8 5699.7 5969.1 6034.9 6037.0 6041.4 6048.7 5537.9 5644.9 5761.2 5762.1 5762.5 5092.3 5171.0 5171.2 5172.7 5177.1 6093.4 6154.8 6157.2 6161.2 6161.3 5822.8 5973.6 5984.3 5986.9 6016.2 6128.7 6147.8 6153.0 6161.2 6164.2 5905.9 5907.2 5914.8 5916.8 5958.7 5869.8 5875.2 5930.5 5973.7 6015.8 5919.6 5946.7 5972.7 5975.4 5977.4 5957.2 5966.3 5967.9 5972.9 5974.3 5505.4 5602.9 5650.9 5711.9 5738.3 5835.3 5883.9 5896.7 5922.5 5923.3 5577.5 5786.0 5790.1 5791.2 5794.9 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,108 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47608.50 Median:33790.38 stddev:77184.77 (228.4%) stddev-sans-first:77320.47 09:08:52 INFO - 2016-02-11 09:08:52,110 DEBUG : BROWSER_OUTPUT: Values: 562.9 590.9 614.3 642.0 704.0 31717.7 31880.5 39677.6 40231.6 40346.2 45668.8 46098.0 46181.3 46257.3 46263.0 7978.3 8106.4 9164.7 9355.0 9356.8 10807.7 11028.8 11106.6 11112.2 11120.5 10832.8 10932.3 10942.9 10956.8 10961.3 8372.1 8518.3 8521.6 10776.6 10963.6 10436.9 10551.7 10563.0 10567.4 10570.4 41553.0 41909.0 41924.2 41983.4 42042.0 3688.8 3696.4 3708.6 3788.7 4344.8 32046.5 32093.4 32312.0 32701.8 32967.8 4374.8 4396.7 4435.2 4435.4 4455.1 284391.0 286049.6 286083.0 286194.0 286295.0 54923.2 55851.0 60232.0 65110.3 68437.3 59925.7 60072.4 60259.0 60300.4 60491.7 15561.6 15650.1 15659.1 15667.8 15668.4 11554.9 11626.4 11633.5 11697.8 11740.9 19632.0 19812.4 20014.1 20038.0 20135.0 7674.0 7751.3 7809.7 7897.5 7932.4 1073.6 1085.0 1101.6 1107.7 1125.5 29418.4 29756.3 29774.9 30014.8 30451.2 1137.2 1144.2 1191.7 1230.3 1251.2 13761.5 15944.2 15948.8 15988.9 16392.9 331796.0 332194.0 332681.0 332959.0 334008.0 54367.7 54538.2 54607.7 54617.5 54835.9 1421.6 1430.4 1430.7 1432.3 1449.9 29607.4 29618.7 30007.7 30607.8 32760.0 50526.7 50974.7 51054.0 51064.0 51079.0 264363.0 264774.0 265694.0 266248.0 267175.0 30262.4 35754.6 35914.1 36113.0 36674.8 43324.0 43700.6 44132.3 44254.6 44638.8 36671.6 37021.6 37197.6 37551.4 37611.8 37158.3 37323.3 37469.8 37853.0 38118.4 36575.8 36584.8 37205.6 37206.1 37327.0 33699.5 33881.3 34203.8 34460.1 34590.7 26173.7 26201.7 26525.2 26545.9 27708.3 09:08:52 INFO - 2016-02-11 09:08:52,110 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,110 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28989.81 Median:12650.05 stddev:82110.10 (649.1%) stddev-sans-first:82312.36 09:08:52 INFO - 2016-02-11 09:08:52,110 DEBUG : BROWSER_OUTPUT: Values: 390.6 392.3 395.5 397.5 400.3 13768.6 17805.0 20278.6 20312.7 20772.4 18208.8 18350.3 19601.0 19619.7 19625.5 6310.9 6414.5 6423.8 6459.2 6464.5 6278.7 6408.1 6422.0 6426.0 6469.6 4668.4 4788.6 6394.2 6404.2 6408.6 6825.5 6933.0 7042.0 7045.9 7051.5 6561.8 6858.4 6858.5 6862.3 6863.9 23074.0 23152.2 23313.4 23575.5 23608.0 2226.1 2464.9 2569.5 2576.0 2580.7 9236.9 9411.4 9569.4 9726.0 9747.4 1890.3 1897.3 1937.6 1940.0 1972.3 65430.0 67327.7 68084.0 68468.7 69761.7 11441.0 11462.9 11474.2 11510.8 11538.8 15019.5 15061.7 15140.1 15187.6 15205.7 8444.3 8447.4 8450.0 8479.5 8487.6 8239.4 8251.8 8274.1 8275.8 8321.7 4855.1 4892.4 4897.2 4903.6 4923.9 1757.6 1883.6 1943.2 1958.0 2025.9 919.4 976.6 985.7 1059.0 1088.6 17143.9 17315.7 17348.0 17617.0 18044.4 1021.8 1071.0 1104.9 1118.6 1130.8 10122.2 10172.4 10271.4 10312.6 10331.9 450921.0 457533.0 465557.0 558673.0 560144.0 85407.6 87925.6 88166.6 91012.0 91160.5 1186.6 1194.8 1221.4 1232.5 1254.8 15958.8 15993.1 16080.7 16242.9 16288.6 36166.5 36275.8 36444.5 37132.6 37652.2 49964.5 50717.2 50934.0 51081.5 52039.2 9773.4 9899.2 10186.4 10233.7 10295.8 11746.8 12254.0 17892.9 17973.9 17984.8 14740.2 14862.4 15055.4 15289.7 15384.6 15011.2 15386.7 15473.6 15577.0 15651.7 15452.6 15780.1 15873.3 16246.7 16252.2 10248.4 10249.8 10353.0 10624.6 10850.2 10862.3 10946.1 13934.7 15282.7 15558.5 09:08:52 INFO - 2016-02-11 09:08:52,110 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,110 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5949.33 Median:5064.61 stddev:3128.34 (61.8%) stddev-sans-first:3119.68 09:08:52 INFO - 2016-02-11 09:08:52,111 DEBUG : BROWSER_OUTPUT: Values: 1554.5 1610.6 1620.9 1790.7 1881.4 8222.4 8459.9 8462.7 8558.1 8567.7 8366.4 8450.5 8462.2 8464.6 8466.3 2415.4 2420.9 2453.3 2456.8 2458.9 2446.9 2448.1 2477.9 2478.4 2480.8 2092.6 2146.1 2149.3 2428.7 2470.2 2367.9 2430.9 2435.0 2448.0 2449.8 2385.6 2401.2 2418.9 2419.1 2425.4 7373.2 7546.8 8101.3 8338.2 8414.6 5002.6 5067.9 5078.5 5078.9 5080.0 3260.9 3265.5 3338.5 3377.9 3392.3 1077.8 1093.4 1100.5 1119.1 1131.5 11676.6 11844.2 11859.0 11869.6 11892.3 4512.5 4540.8 4550.7 4567.2 4599.5 10059.3 10097.0 10100.0 10116.8 10119.4 5029.4 5046.1 5047.1 5061.3 5068.2 4591.7 4594.9 4597.2 4598.0 4598.1 3869.9 3935.6 3937.9 3949.9 3976.2 1201.2 1211.9 1225.5 1233.2 1239.0 1151.8 1155.9 1174.5 1175.3 1178.0 8086.7 8098.2 8167.1 8212.1 8251.7 1117.5 1144.1 1177.7 1184.0 1187.4 6003.8 6084.4 6426.2 6494.6 6501.4 9686.6 9799.2 10018.2 10020.3 10022.7 9790.4 9849.4 10443.6 10570.7 10577.9 7929.8 8043.4 8122.2 8125.7 8136.7 8092.9 8193.8 8235.3 8244.3 8250.7 9202.4 9209.8 9299.8 9347.2 9360.4 9441.1 9552.4 9638.5 9688.7 9713.0 7076.2 7129.5 7139.6 7210.2 7372.0 8374.3 8403.4 8403.5 8411.9 8416.8 7475.0 7619.4 7734.2 7846.2 7984.9 7554.2 7642.2 7677.0 7721.9 7737.3 7798.3 7818.6 7826.0 7827.1 7834.4 6819.8 7015.0 7015.3 7017.8 7023.5 6923.0 6957.7 6959.0 6961.2 6971.2 09:08:52 INFO - 2016-02-11 09:08:52,112 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,112 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5439.59 Median:6235.81 stddev:1619.26 (26.0%) stddev-sans-first:1578.30 09:08:52 INFO - 2016-02-11 09:08:52,112 DEBUG : BROWSER_OUTPUT: Values: 360.9 364.8 373.2 373.3 386.7 5986.2 6054.2 6066.6 6127.1 6144.7 5896.9 6003.0 6032.0 6035.5 6039.4 4737.3 4757.0 4764.1 4774.4 4775.5 4766.8 4773.0 4775.4 4776.2 4776.4 4551.0 4626.3 4643.3 4653.7 4741.5 4627.9 4631.0 4632.7 4641.7 4680.7 3842.9 3956.5 4141.0 4146.9 4151.4 5648.3 5726.5 5776.8 5800.0 5800.9 5404.3 5417.9 5434.7 5435.9 5440.7 6317.9 6427.5 6429.7 6437.9 6440.3 4553.1 4577.2 4577.7 4578.3 4581.9 6561.6 6604.9 6633.5 6634.3 6647.5 4629.7 4633.7 4642.5 4645.2 4651.1 4623.9 4695.0 4701.4 4703.1 4707.2 5435.3 5448.1 5449.8 5452.6 5454.1 6467.8 6471.1 6477.0 6480.1 6484.5 1662.5 1672.2 1686.3 1687.2 1687.7 1139.3 1141.3 1144.3 1156.2 1183.8 6278.5 6304.2 6310.7 6313.4 6315.1 6617.2 6652.4 6663.4 6667.0 6685.4 6193.1 6299.7 6303.0 6307.7 6311.7 3590.2 3613.6 3613.9 3622.3 3625.7 6635.7 6722.1 6851.2 6864.6 6883.1 6852.5 6860.3 6887.6 6891.1 6895.6 6695.9 6889.8 6916.0 6930.1 6937.1 6729.5 6743.4 6755.3 6757.1 6758.7 6653.5 6654.0 6661.3 6671.8 6674.0 6771.1 6909.8 6917.0 6922.7 6929.4 4704.3 4731.5 4736.5 4739.9 4740.6 6691.6 6710.2 6714.8 6715.1 6727.5 6407.5 6542.0 6545.9 6547.3 6559.5 6542.0 6568.0 6571.1 6576.3 6588.2 6382.7 6395.4 6400.9 6405.9 6411.7 6379.7 6592.6 6610.2 6610.8 6616.7 6443.7 6445.8 6454.0 6455.1 6459.3 09:08:52 INFO - 2016-02-11 09:08:52,112 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,112 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15029.83 Median:4737.04 stddev:17586.02 (371.2%) stddev-sans-first:17602.82 09:08:52 INFO - 2016-02-11 09:08:52,113 DEBUG : BROWSER_OUTPUT: Values: 783.8 835.3 921.5 938.4 948.6 13915.6 14396.6 14405.5 14416.0 14419.3 13680.7 13701.6 13746.8 13750.8 13773.7 723.7 732.0 879.9 899.4 899.6 881.0 885.2 886.2 899.4 900.0 901.5 909.0 911.6 915.5 915.9 769.0 778.5 911.0 915.1 931.0 684.5 685.3 690.3 690.9 691.1 12728.8 12731.6 12761.4 12862.5 12939.9 12406.6 12733.2 12807.2 13215.9 13362.5 10777.8 11033.7 11042.4 11098.4 12366.3 3915.2 3945.5 3945.6 3973.3 4037.6 51850.7 52524.0 52738.2 53010.2 53090.5 11159.3 11220.7 11354.1 11357.7 11728.4 20285.6 20783.5 20914.4 20988.1 21007.0 8340.6 8461.6 8622.4 8683.6 8756.5 4657.8 4750.6 4778.7 4792.9 4833.8 3279.1 3307.5 3308.7 3339.5 3346.1 579.5 606.4 609.5 622.8 634.0 691.8 700.7 731.7 747.9 773.3 8402.6 8583.4 8622.3 8683.7 8697.9 487.3 570.3 647.0 657.8 712.0 4176.1 4660.6 4705.3 4723.5 4764.2 61075.4 61768.7 62213.7 62521.4 62882.4 55707.2 58109.7 58470.1 58841.0 58865.7 52779.2 52827.0 53128.0 53257.7 53720.0 9312.4 9558.8 9628.8 9648.2 9740.2 36084.3 36453.3 36748.0 37116.8 37200.8 44472.6 45806.0 49648.3 50207.5 50693.7 11963.0 12160.9 12184.1 12254.6 12414.4 12927.0 12959.5 13040.0 13073.5 13401.4 11464.3 11804.6 11847.1 12016.7 12325.4 12306.3 12451.7 12533.4 12747.0 12786.6 10312.0 11828.8 12019.3 12136.2 12311.6 10309.9 10956.0 10972.1 11072.4 11591.3 9413.8 10186.4 10404.3 10674.0 10763.1 09:08:52 INFO - 2016-02-11 09:08:52,113 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:08:52 INFO - 2016-02-11 09:08:52,113 DEBUG : BROWSER_OUTPUT: 09:08:52 INFO - 2016-02-11 09:08:52,717 INFO : Browser exited with error code: 0 09:08:52 INFO - 2016-02-11 09:08:52,736 INFO : Completed test dromaeo_css (00:18:32) 09:08:52 INFO - 2016-02-11 09:08:52,736 INFO : Starting test kraken 09:08:52 INFO - 2016-02-11 09:08:52,736 DEBUG : operating with platform_type : linux_ 09:08:52 INFO - 2016-02-11 09:08:52,736 INFO : Initialising browser for kraken test... 09:08:52 INFO - 2016-02-11 09:08:52,742 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpylNiq6/profile http://localhost:41102/getInfo.html 09:08:57 INFO - 2016-02-11 09:08:57,115 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:08:57 INFO - 2016-02-11 09:08:57,115 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:08:57 INFO - 2016-02-11 09:08:57,115 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:08:57 INFO - 2016-02-11 09:08:57,116 DEBUG : BROWSER_OUTPUT: __metrics 09:08:57 INFO - 2016-02-11 09:08:57,299 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] 09:08:58 INFO - 2016-02-11 09:08:58,661 INFO : Browser initialized. 09:08:58 INFO - 2016-02-11 09:08:58,662 INFO : Running cycle 1/1 for kraken test... 09:08:58 INFO - 2016-02-11 09:08:58,662 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpylNiq6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:09:10 INFO - 2016-02-11 09:09:10,324 DEBUG : BROWSER_OUTPUT: RSS: Main: 132218880 09:09:10 INFO - 2016-02-11 09:09:10,324 DEBUG : BROWSER_OUTPUT: 09:09:12 INFO - 2016-02-11 09:09:12,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41102/tests/kraken/driver.html?testName=audio-beat-detection) 09:09:13 INFO - 2016-02-11 09:09:13,356 DEBUG : BROWSER_OUTPUT: RSS: Main: 162615296 09:09:13 INFO - 2016-02-11 09:09:13,357 DEBUG : BROWSER_OUTPUT: 09:09:15 INFO - 2016-02-11 09:09:15,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41102/tests/kraken/driver.html?testName=audio-dft) 09:09:16 INFO - 2016-02-11 09:09:16,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 160329728 09:09:16 INFO - 2016-02-11 09:09:16,520 DEBUG : BROWSER_OUTPUT: 09:09:19 INFO - 2016-02-11 09:09:19,288 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41102/tests/kraken/driver.html?testName=audio-fft) 09:09:19 INFO - 2016-02-11 09:09:19,870 DEBUG : BROWSER_OUTPUT: RSS: Main: 157962240 09:09:19 INFO - 2016-02-11 09:09:19,870 DEBUG : BROWSER_OUTPUT: 09:09:21 INFO - 2016-02-11 09:09:21,842 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41102/tests/kraken/driver.html?testName=audio-oscillator) 09:09:22 INFO - 2016-02-11 09:09:22,408 DEBUG : BROWSER_OUTPUT: RSS: Main: 158048256 09:09:22 INFO - 2016-02-11 09:09:22,408 DEBUG : BROWSER_OUTPUT: 09:09:24 INFO - 2016-02-11 09:09:24,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41102/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:25 INFO - 2016-02-11 09:09:25,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 157474816 09:09:25 INFO - 2016-02-11 09:09:25,183 DEBUG : BROWSER_OUTPUT: 09:09:28 INFO - 2016-02-11 09:09:28,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41102/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:28 INFO - 2016-02-11 09:09:28,665 DEBUG : BROWSER_OUTPUT: RSS: Main: 158416896 09:09:28 INFO - 2016-02-11 09:09:28,665 DEBUG : BROWSER_OUTPUT: 09:09:31 INFO - 2016-02-11 09:09:31,987 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41102/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:32 INFO - 2016-02-11 09:09:32,554 DEBUG : BROWSER_OUTPUT: RSS: Main: 159465472 09:09:32 INFO - 2016-02-11 09:09:32,554 DEBUG : BROWSER_OUTPUT: 09:09:35 INFO - 2016-02-11 09:09:35,243 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41102/tests/kraken/driver.html?testName=json-parse-financial) 09:09:35 INFO - 2016-02-11 09:09:35,825 DEBUG : BROWSER_OUTPUT: RSS: Main: 158572544 09:09:35 INFO - 2016-02-11 09:09:35,826 DEBUG : BROWSER_OUTPUT: 09:09:37 INFO - 2016-02-11 09:09:37,355 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41102/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:37 INFO - 2016-02-11 09:09:37,910 DEBUG : BROWSER_OUTPUT: RSS: Main: 165560320 09:09:37 INFO - 2016-02-11 09:09:37,910 DEBUG : BROWSER_OUTPUT: 09:09:39 INFO - 2016-02-11 09:09:39,645 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:40 INFO - 2016-02-11 09:09:40,232 DEBUG : BROWSER_OUTPUT: RSS: Main: 164581376 09:09:40 INFO - 2016-02-11 09:09:40,232 DEBUG : BROWSER_OUTPUT: 09:09:42 INFO - 2016-02-11 09:09:42,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:42 INFO - 2016-02-11 09:09:42,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 168972288 09:09:42 INFO - 2016-02-11 09:09:42,802 DEBUG : BROWSER_OUTPUT: 09:09:45 INFO - 2016-02-11 09:09:45,124 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:45 INFO - 2016-02-11 09:09:45,706 DEBUG : BROWSER_OUTPUT: RSS: Main: 172036096 09:09:45 INFO - 2016-02-11 09:09:45,706 DEBUG : BROWSER_OUTPUT: 09:09:48 INFO - 2016-02-11 09:09:48,142 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:48 INFO - 2016-02-11 09:09:48,711 DEBUG : BROWSER_OUTPUT: RSS: Main: 170119168 09:09:48 INFO - 2016-02-11 09:09:48,711 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,347 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41102/tests/kraken/driver.html?testName=ai-astar) 09:09:50 INFO - 2016-02-11 09:09:50,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 228245504 09:09:50 INFO - 2016-02-11 09:09:50,598 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,599 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:09:50 INFO - 2016-02-11 09:09:50,599 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:09:50 INFO - 2016-02-11 09:09:50,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:09:50 INFO - 2016-02-11 09:09:50,600 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:09:50 INFO - 2016-02-11 09:09:50,600 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;109;103;108;114;108;97;108;108;108 09:09:50 INFO - 2016-02-11 09:09:50,600 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;144;121;144;162;127;148;150;128;165;122 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;159;160;163;162;161;161;161;161 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;77;70;148;81;70;72;71;157;71 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;136;92;91;92;141;91;91;140 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;110;109;110;110;108;109 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;146;145;145;147;145;147;146;146 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 09:09:50 INFO - 2016-02-11 09:09:50,601 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;56;63;57;55;56;58;58;55;57 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;65;63;64;64;64;65;63;64 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;86;78;80;79;80;80;78;85;79 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;110;107;115;123;120;118;110;114;113;117 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;131;141;140;139;143;141;140;138;139;132 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;63;63;62;73;63;61;61;64;62 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:09:50 INFO - 2016-02-11 09:09:50,602 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,908 09:09:50 INFO - 2016-02-11 09:09:50,603 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:09:50 INFO - 2016-02-11 09:09:50,603 DEBUG : BROWSER_OUTPUT: __startTimestamp1455210590598__endTimestamp 09:09:50 INFO - 2016-02-11 09:09:50,603 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,606 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:09:50 INFO - 2016-02-11 09:09:50,606 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:09:50 INFO - 2016-02-11 09:09:50,606 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,606 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.10 Median:108.00 stddev:4.41 (4.1%) stddev-sans-first:4.66 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: Values: 108.0 109.0 103.0 108.0 114.0 108.0 97.0 108.0 108.0 108.0 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.10 Median:146.00 stddev:15.95 (10.9%) stddev-sans-first:16.89 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: Values: 144.0 121.0 144.0 162.0 127.0 148.0 150.0 128.0 165.0 122.0 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 159.0 160.0 163.0 162.0 161.0 161.0 161.0 161.0 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.80 Median:71.00 stddev:33.82 (47.6%) stddev-sans-first:35.26 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: Values: 71.0 77.0 70.0 148.0 81.0 70.0 72.0 71.0 157.0 71.0 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.80 Median:91.50 stddev:22.95 (25.1%) stddev-sans-first:23.79 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 136.0 92.0 91.0 92.0 141.0 91.0 91.0 140.0 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 09:09:50 INFO - 2016-02-11 09:09:50,607 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 110.0 109.0 110.0 110.0 108.0 109.0 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 146.0 145.0 145.0 147.0 145.0 147.0 146.0 146.0 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:2.30 (4.0%) stddev-sans-first:2.44 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: Values: 57.0 56.0 63.0 57.0 55.0 56.0 58.0 58.0 55.0 57.0 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 65.0 63.0 64.0 64.0 64.0 65.0 63.0 64.0 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,608 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:80.00 stddev:2.96 (3.7%) stddev-sans-first:2.92 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: Values: 84.0 86.0 78.0 80.0 79.0 80.0 80.0 78.0 85.0 79.0 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:114.70 Median:116.00 stddev:4.95 (4.3%) stddev-sans-first:4.94 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: Values: 110.0 107.0 115.0 123.0 120.0 118.0 110.0 114.0 113.0 117.0 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.40 Median:140.00 stddev:3.89 (2.8%) stddev-sans-first:3.07 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: Values: 131.0 141.0 140.0 139.0 143.0 141.0 140.0 138.0 139.0 132.0 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.00 stddev:3.47 (5.5%) stddev-sans-first:3.68 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 62.0 73.0 63.0 61.0 61.0 64.0 62.0 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:09:50 INFO - 2016-02-11 09:09:50,609 DEBUG : BROWSER_OUTPUT: 09:09:51 INFO - 2016-02-11 09:09:51,273 INFO : Browser exited with error code: 0 09:09:51 INFO - 2016-02-11 09:09:51,327 INFO : Completed test kraken (00:00:58) 09:09:51 INFO - 2016-02-11 09:09:51,327 INFO : Starting test v8_7 09:09:51 INFO - 2016-02-11 09:09:51,328 DEBUG : operating with platform_type : linux_ 09:09:51 INFO - 2016-02-11 09:09:51,328 INFO : Initialising browser for v8_7 test... 09:09:51 INFO - 2016-02-11 09:09:51,334 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxBEdKJ/profile http://localhost:41102/getInfo.html 09:09:56 INFO - 2016-02-11 09:09:56,061 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:09:56 INFO - 2016-02-11 09:09:56,061 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:09:56 INFO - 2016-02-11 09:09:56,061 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:09:56 INFO - 2016-02-11 09:09:56,062 DEBUG : BROWSER_OUTPUT: __metrics 09:09:56 INFO - 2016-02-11 09:09:56,238 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] 09:09:57 INFO - 2016-02-11 09:09:57,161 INFO : Browser initialized. 09:09:57 INFO - 2016-02-11 09:09:57,162 INFO : Running cycle 1/1 for v8_7 test... 09:09:57 INFO - 2016-02-11 09:09:57,162 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxBEdKJ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:10:08 INFO - 2016-02-11 09:10:08,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 132026368 09:10:08 INFO - 2016-02-11 09:10:08,787 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,132 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:41102/tests/v8_7/run.html (next: http://localhost:41102/tests/v8_7/run.html) 09:10:30 INFO - 2016-02-11 09:10:30,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 425484288 09:10:30 INFO - 2016-02-11 09:10:30,382 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,382 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:10:30 INFO - 2016-02-11 09:10:30,383 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:10:30 INFO - 2016-02-11 09:10:30,383 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:10:30 INFO - 2016-02-11 09:10:30,383 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:10:30 INFO - 2016-02-11 09:10:30,383 DEBUG : BROWSER_OUTPUT: |0;Richards;167.8697330871244 09:10:30 INFO - 2016-02-11 09:10:30,383 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.8649859014762 09:10:30 INFO - 2016-02-11 09:10:30,383 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.1641168289291 09:10:30 INFO - 2016-02-11 09:10:30,384 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5240.837696335078 09:10:30 INFO - 2016-02-11 09:10:30,384 DEBUG : BROWSER_OUTPUT: |4;RayTrace;972.7626459143969 09:10:30 INFO - 2016-02-11 09:10:30,384 DEBUG : BROWSER_OUTPUT: |5;Earley;547.5929978118162 09:10:30 INFO - 2016-02-11 09:10:30,384 DEBUG : BROWSER_OUTPUT: |6;Boyer;10621.052631578947 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: |7;RegExp;24536.585365853658 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: |8;Splay;523.5602094240837 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: __startTimestamp1455210630381__endTimestamp 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:10:30 INFO - 2016-02-11 09:10:30,385 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.87 Median:167.87 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: Values: 167.9 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.86 Median:165.86 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: Values: 165.9 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.16 Median:278.16 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: Values: 278.2 09:10:30 INFO - 2016-02-11 09:10:30,386 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5240.84 Median:5240.84 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: Values: 5240.8 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:972.76 Median:972.76 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: Values: 972.8 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,387 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:547.59 Median:547.59 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,389 DEBUG : BROWSER_OUTPUT: Values: 547.6 09:10:30 INFO - 2016-02-11 09:10:30,389 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,389 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10621.05 Median:10621.05 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,389 DEBUG : BROWSER_OUTPUT: Values: 10621.1 09:10:30 INFO - 2016-02-11 09:10:30,389 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24536.59 Median:24536.59 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: Values: 24536.6 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:523.56 Median:523.56 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: Values: 523.6 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 09:10:30 INFO - 2016-02-11 09:10:30,390 DEBUG : BROWSER_OUTPUT: Values: 4826.9 09:10:30 INFO - 2016-02-11 09:10:30,391 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:10:30 INFO - 2016-02-11 09:10:30,391 DEBUG : BROWSER_OUTPUT: 09:10:31 INFO - 2016-02-11 09:10:31,070 INFO : Browser exited with error code: 0 09:10:31 INFO - 2016-02-11 09:10:31,084 INFO : Completed test v8_7 (00:00:39) 09:10:31 INFO - 2016-02-11 09:10:31,415 INFO : Completed test suite (00:20:10) 09:10:31 INFO - 2016-02-11 09:10:31,415 DEBUG : Working with test: dromaeo_css 09:10:31 INFO - 2016-02-11 09:10:31,415 DEBUG : Generating results file: dromaeo_css 09:10:31 INFO - 2016-02-11 09:10:31,416 DEBUG : Working with test: kraken 09:10:31 INFO - 2016-02-11 09:10:31,417 DEBUG : Generating results file: kraken 09:10:31 INFO - 2016-02-11 09:10:31,417 INFO : javascript benchmark 09:10:31 INFO - 2016-02-11 09:10:31,417 DEBUG : Working with test: v8_7 09:10:31 INFO - 2016-02-11 09:10:31,417 DEBUG : Generating results file: v8_7 09:10:31 INFO - 2016-02-11 09:10:31,418 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:10:31 INFO - 2016-02-11 09:10:31,544 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 09:10:31 INFO - 2016-02-11 09:10:31,544 DEBUG : process_Request line: dromaeo_css 7450.99 graph.html#tests=[[72,53,35]] 09:10:31 INFO - 2016-02-11 09:10:31,545 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:10:31 INFO - 2016-02-11 09:10:31,668 DEBUG : process_Request line: kraken 1472.50 graph.html#tests=[[232,53,35]] 09:10:31 INFO - 2016-02-11 09:10:31,668 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:10:31 INFO - 2016-02-11 09:10:31,805 DEBUG : process_Request line: v8_7 23124.11 graph.html#tests=[[230,53,35]] 09:10:31 INFO - 2016-02-11 09:10:31,806 INFO : javascript benchmark 09:10:31 INFO - 2016-02-11 09:10:31,810 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3305.8677652893884, 3360.2775403288288, 3395.270016946356, 3395.7283417326616, 3402.4215883299835, 5558.110702287635, 5567.164925261213, 5570.220169176314, 5574.9721251393785, 5594.104943209082, 5556.972215138919, 5577.330720313558, 5579.163125531172, 5590.273264475622, 5592.021396255659, 5009.57418619416, 5074.594032477411, 5094.974525127388, 5111.2333150027425, 5159.38087429509, 5024.974875125619, 5106.668066575679, 5177.689338639675, 5190.532852043315, 5193.143131383336, 5015.523525265112, 5122.795088196468, 5133.281340612317, 5143.09995750744, 5153.201253805666, 5140.203268493389, 5207.609429292799, 5207.6354655174155, 5212.4266330703595, 5236.581073514109, 4928.950710492921, 4937.629677774145, 5020.949790502122, 5094.77073531692, 5105.234214867763, 5368.543673787733, 5378.354597448292, 5380.596455265871, 5384.5692344612335, 5396.028714831329, 5873.941260587425, 5904.675242861641, 5967.16459695643, 5974.044152935553, 5984.970075149597, 5847.970760146172, 5931.377205393458, 5973.790917317917, 6004.219451471325, 6037.879242415127, 5542.334919809648, 5682.801101961411, 5708.600397972144, 5716.485516303553, 5719.971400142973, 6152.969235153795, 6188.4739797117745, 6195.31851496335, 6198.969005154945, 6203.410675985774, 5721.0, 5748.45389687979, 5824.417558244142, 5843.474087332161, 5878.353381128072, 5955.52355811534, 5962.5826192167, 5964.314103878023, 5964.940350596526, 5968.522518198533, 5794.971025144931, 5870.5890587659305, 5926.140709597084, 5926.525877929756, 5928.199693041471, 5888.263967004124, 5947.04847224442, 5971.940280597225, 6020.488258497989, 6053.545984051126, 4609.193391156601, 4611.654125940568, 4613.400257966443, 4613.5847773700525, 4641.118187544424, 3997.4603428537357, 4128.298189307765, 4155.439015732898, 4161.0, 4192.098698779777, 5553.72231388437, 5607.327120745536, 5674.829755107354, 5677.801276955287, 5699.715014249271, 5969.134475501115, 6034.909476357858, 6037.0, 6041.365656605993, 6048.6975651218145, 5537.889242215133, 5644.85887852807, 5761.193432919395, 5762.078067509178, 5762.538996880239, 5092.28707980883, 5170.974145129326, 5171.172612382001, 5172.7413629319135, 5177.119889618773, 6093.390660933871, 6154.753809847645, 6157.199564056644, 6161.199044124325, 6161.322254551996, 5822.796202132905, 5973.581849270509, 5984.251968503937, 5986.880262394815, 6016.2479690038745, 6128.655903792346, 6147.754089836357, 6153.046277826944, 6161.199044124235, 6164.198654174928, 5905.852353691193, 5907.172995780508, 5914.763409463573, 5916.822495325147, 5958.672273025025, 5869.8239052828485, 5875.20684707559, 5930.466258036798, 5973.671448070398, 6015.849603759941, 5919.5856290061, 5946.672932988728, 5972.731227094878, 5975.37258587842, 5977.432143946318, 5957.225560677258, 5966.3138739043825, 5967.91048134287, 5972.910406343995, 5974.342822289458, 5505.421930697379, 5602.887942241051, 5650.886982260414, 5711.914321285267, 5738.282714660668, 5835.299764028174, 5883.882322353615, 5896.705164741831, 5922.496587789914, 5923.318818335774, 5577.470140336662, 5785.971070144622, 5790.131480277992, 5791.218185545066, 5794.913076303775], "unit": "ms", "name": "dojo.html", "value": 5520.039984326428}, {"lowerIsBetter": false, "replicates": [562.9043062679344, 590.8641012567109, 614.3089024847047, 642.0209180998979, 703.9824004399893, 31717.682823171763, 31880.521792173105, 39677.60322396779, 40231.59768402315, 40346.19307613846, 45668.771656141675, 46098.0, 46181.30728039082, 46257.30614040792, 46263.0, 7978.321842643382, 8106.351491880635, 9164.679236226733, 9354.953225233865, 9356.812863742722, 10807.67576972692, 11028.779424411507, 11106.61126860556, 11112.16658750593, 11120.499577518991, 10832.78334433311, 10932.344059356425, 10942.890571094307, 10956.835647465294, 10961.342319460859, 8372.120927302636, 8518.27594654455, 8521.573921303941, 10776.622818201364, 10963.616273430393, 10436.895631043668, 10551.683449496528, 10563.0, 10567.365958042465, 10570.365778053341, 41553.0, 41909.0, 41924.1615167698, 41983.370249446285, 42042.0, 3688.8340024698955, 3696.4105434663084, 3708.5735140458924, 3788.71584631151, 4344.804483798237, 32046.519302210487, 32093.358132837213, 32312.0, 32701.83649081763, 32967.83516082404, 4374.803133858984, 4396.736195828228, 4435.223835828717, 4435.379046933432, 4455.131249406367, 284391.0, 286049.56975215196, 286083.0, 286194.0, 286295.0, 54923.176152357744, 55851.0, 60232.0, 65110.34889651138, 68437.3156268441, 59925.70037149874, 60072.399276007556, 60259.0, 60300.39699603036, 60491.69754151201, 15561.610959726097, 15650.13924234178, 15659.138747369005, 15667.764983525256, 15668.373265069498, 11554.942225288987, 11626.418679066013, 11633.47649355781, 11697.824532632018, 11740.882591173979, 19632.018399080276, 19812.405627831187, 20014.099365528586, 20038.0, 20135.0, 7673.961630191813, 7751.3411360035025, 7809.726659566888, 7897.526148431112, 7932.365410767125, 1073.613499140309, 1084.9530203353643, 1101.553870682375, 1107.6566264458045, 1125.4597793059377, 29418.411631767245, 29756.2560935974, 29774.851125744233, 30014.84992575023, 30451.2387190322, 1137.175547727907, 1144.1658990763956, 1191.7199458127334, 1230.2556953043459, 1251.2304932466468, 13761.518346858013, 15944.202789860692, 15948.76076858848, 15988.920055399882, 16392.918035409748, 331796.0, 332194.0, 332681.0, 332959.0, 334008.0, 54367.72816135894, 54538.18192727112, 54607.72696136494, 54617.453825462035, 54835.90328193414, 1421.6374824419706, 1430.427828868461, 1430.6537278862675, 1432.3196481671123, 1449.8840092792552, 29607.407851843273, 29618.703812961594, 30007.69992300049, 30607.846960765055, 32760.0, 50526.747366262935, 50974.745126275615, 51054.0, 51064.0, 51079.0, 264363.0, 264774.0, 265694.0, 266248.0, 267175.0, 30262.394752105276, 35754.64245357617, 35914.10214744652, 36113.0, 36674.8166259167, 43324.0, 43700.56299436965, 44132.33801492916, 44254.55745442633, 44638.77680611576, 36671.63328366682, 37021.6297837029, 37197.6280237205, 37551.43672844855, 37611.81194094012, 37158.25683486369, 37323.253534929696, 37469.81265093657, 37853.0, 38118.42822357611, 36575.817120914224, 36584.81707591445, 37205.62794372022, 37206.06984825401, 37327.0, 33699.49450758191, 33881.2672107964, 34203.828980855935, 34460.13849653779, 34590.65409345975, 26173.73826261789, 26201.73798261993, 26525.204243872715, 26545.867270663523, 27708.30729231785], "unit": "ms", "name": "ext.html", "value": 19649.743948662188}, {"lowerIsBetter": false, "replicates": [390.6054884566588, 392.29582898696856, 395.5411722402014, 397.5190020075708, 400.3474336830965, 13768.58694239172, 17805.0, 20278.594428111446, 20312.69530957037, 20772.376828695164, 18208.817911820912, 18350.265989360443, 19601.0, 19619.705704414446, 19625.509362265915, 6310.905336419946, 6414.486841052704, 6423.807285781434, 6459.224893012845, 6464.547481676284, 6278.717457714414, 6408.070829729686, 6421.967890160543, 6426.0360945858265, 6469.611823290617, 4668.416447944007, 4788.592969597588, 6394.232692076957, 6404.167458230423, 6408.583442076273, 6825.453963682919, 6932.965335173342, 7042.0493233413345, 7045.89431158533, 7051.541649792747, 6561.835954101138, 6858.417034552069, 6858.51990360675, 6862.279460656659, 6863.862722745517, 23074.0, 23152.18967336135, 23313.417164570852, 23575.528489430115, 23608.0, 2226.0984301357976, 2464.8521088734733, 2569.524637941987, 2575.9871200644066, 2580.729023452548, 9236.861447078232, 9411.43531388119, 9569.425834449885, 9725.97031510439, 9747.3664211826, 1890.2816929566814, 1897.3169658922777, 1937.6318499485053, 1940.0396803582316, 1972.2998335590803, 65430.0, 67327.66336168288, 68083.97874031944, 68468.65765671189, 69761.65119174423, 11441.0, 11462.936671399062, 11474.196806223485, 11510.827337589943, 11538.76922461563, 15019.474318398803, 15061.698766024618, 15140.091594504365, 15187.620309492351, 15205.695886082216, 8444.324454043663, 8447.36644751646, 8449.95775021121, 8479.491230526188, 8487.618057187441, 8239.382046346547, 8251.83496330082, 8274.13121622221, 8275.793105172419, 8321.708740194064, 4855.077535268288, 4892.437369702458, 4897.216445368796, 4903.5586797188425, 4923.876903077452, 1757.6252021698292, 1883.604443066944, 1943.232423192831, 1957.9706304405445, 2025.8683185593184, 919.4023884475012, 976.5507866381402, 985.7387792235063, 1059.0204061243473, 1088.553692985872, 17143.91428042852, 17315.740263896052, 17348.04585747796, 17617.0, 18044.368447104287, 1021.8262895307815, 1070.9518071686794, 1104.9337039777638, 1118.5637601335486, 1130.8473356096986, 10122.190224782, 10172.389656620626, 10271.383716977005, 10312.639057632947, 10331.94834025825, 450921.0, 457533.0, 465557.0, 558673.0, 560144.0, 85407.57296213479, 87925.56037219772, 88166.55916720376, 91012.0, 91160.54419727859, 1186.5669030803695, 1194.814803705427, 1221.3648902570792, 1232.5377983256278, 1254.7615952968906, 15958.760618590499, 15993.1203783793, 16080.678386431971, 16242.918785405996, 16288.592785180464, 36166.457503138, 36275.81862090673, 36444.45333319949, 37132.628673713996, 37652.246955060196, 49964.50035499599, 50717.23924141215, 50934.0, 51081.489185107675, 52039.219411708094, 9773.364731292728, 9899.158571521213, 10186.439745814052, 10233.74415639615, 10295.794084118426, 11746.76506469883, 12254.0, 17892.910535447238, 17973.910130449265, 17984.820151798314, 14740.189289589176, 14862.405503779728, 15055.397784088951, 15289.694206115593, 15384.615384615026, 15011.17438540847, 15386.692266154838, 15473.613159671098, 15577.0, 15651.686966260839, 15452.613684657974, 15780.132092734551, 15873.285702143201, 16246.67506649884, 16252.187390630657, 10248.436336001592, 10249.795004099728, 10353.0, 10624.628138015132, 10850.240483166102, 10862.34825910448, 10946.069584085442, 13934.721305574034, 15282.694346112794, 15558.455454059507], "unit": "ms", "name": "jquery.html", "value": 9700.409683655642}, {"lowerIsBetter": false, "replicates": [1554.5025591810622, 1610.5892997285691, 1620.9189540522975, 1790.7492950986864, 1881.4261650196702, 8222.3833212509, 8459.91540084599, 8462.703805366813, 8558.101399353072, 8567.65729370826, 8366.37252206084, 8450.49297042178, 8462.196091371325, 8464.576771161433, 8466.322694184466, 2415.4323733922515, 2420.854748715074, 2453.264020793764, 2456.8403053801535, 2458.9262322130276, 2446.865422401776, 2448.114911920328, 2477.938051548717, 2478.4175718706088, 2480.8015358771345, 2092.5814837032576, 2146.0879126371306, 2149.269248455525, 2428.7085549734056, 2470.209532949457, 2367.9408014799683, 2430.890609922558, 2434.9634755478683, 2448.045262347686, 2449.8285120041605, 2385.630227314769, 2401.1595941420537, 2418.8548687078655, 2419.129113519132, 2425.357280320716, 7373.152087509951, 7546.849063018763, 8101.311388531982, 8338.24955753979, 8414.621342039623, 5002.574781143607, 5067.898642027176, 5078.466760990117, 5078.923816142761, 5080.0, 3260.8858689945973, 3265.477523596237, 3338.4992251162275, 3377.864885404581, 3392.2876195998624, 1077.8221593437074, 1093.3986307530906, 1100.4882729530727, 1119.13826353708, 1131.490829126888, 11676.591319303869, 11844.230125041931, 11859.0, 11869.584564540286, 11892.286462812259, 4512.503624601291, 4540.818367265339, 4550.681452298308, 4567.1550763108935, 4599.471060827981, 10059.29584929048, 10097.0, 10100.0, 10116.797664046826, 10119.44343061124, 5029.371328583927, 5046.091703493406, 5047.141985862412, 5061.316722242524, 5068.189089745623, 4591.701539399928, 4594.908101837945, 4597.241455159928, 4598.0, 4598.149342371672, 3869.9226015479535, 3935.5670876203594, 3937.8818635440985, 3949.881503554898, 3976.204759048202, 1201.1591885679884, 1211.9091068169923, 1225.4607972492074, 1233.1614502138452, 1238.975220495603, 1151.7696460707725, 1155.9075273978062, 1174.48322737995, 1175.3359351966112, 1178.0281267953972, 8086.7169649062, 8098.190180981973, 8167.060788009455, 8212.055613604392, 8251.711190108437, 1117.4915413486844, 1144.1247445704043, 1177.6937996120898, 1183.9822402663967, 1187.4062968515743, 6003.849903752441, 6084.361142080106, 6426.196725409324, 6494.577852439576, 6501.414872661483, 9686.564104615309, 9799.216062714966, 10018.248631352677, 10020.298579099393, 10022.74943126413, 9790.363626364264, 9849.409035457895, 10443.582256709798, 10570.735731606772, 10577.894221057692, 7929.762107136795, 8043.39674524413, 8122.228388303101, 8125.715599953974, 8136.715214967566, 8092.9190708092165, 8193.83612327762, 8235.341172706288, 8244.340452763645, 8250.669973201006, 9202.355835091746, 9209.769755755891, 9299.768055666456, 9347.158755712018, 9360.4383736976, 9441.073267287164, 9552.42685438876, 9638.518074096408, 9688.70933871985, 9712.951435242778, 7076.221615622175, 7129.5365801224825, 7139.571625702474, 7210.170830354366, 7372.0, 8374.330053595575, 8403.369747268856, 8403.537805420761, 8411.873821892554, 8416.83166336682, 7474.962625186839, 7619.390448764197, 7734.2265773422205, 7846.215378462108, 7984.920150798418, 7554.2445755426215, 7642.1593624702355, 7677.0, 7721.884171737541, 7737.342325902361, 7798.25916537928, 7818.6481608328895, 7825.960870195612, 7827.0607527094835, 7834.412419068479, 6819.761308354184, 7015.0, 7015.298470152944, 7017.789466316019, 7023.543469674455, 6923.0, 6957.721691132298, 6958.965205173941, 6961.164660240804, 6971.163460384786], "unit": "ms", "name": "mootools.html", "value": 4851.353478155299}, {"lowerIsBetter": false, "replicates": [360.9458581212816, 364.82123759357904, 373.2032111442071, 373.3186933845731, 386.72735721316474, 5986.161937328778, 6054.182685337478, 6066.605670631417, 6127.050307202385, 6144.69276536173, 5896.941030589693, 6003.0, 6032.0, 6035.486983606399, 6039.365866584011, 4737.313089602006, 4757.0, 4764.071006153801, 4774.379330687006, 4775.4985726498735, 4766.76166191689, 4773.0, 4775.426948766153, 4776.211925032366, 4776.402949631296, 4551.0, 4626.259798432252, 4643.303504474339, 4653.7440440775745, 4741.502142275063, 4627.9074418511445, 4631.0, 4632.698874573142, 4641.675082744209, 4680.672352935296, 3842.9423558646645, 3956.485656864618, 4140.985458562642, 4146.91706165875, 4151.377293405983, 5648.293963254593, 5726.513246374063, 5776.768929242825, 5800.0, 5800.926828536734, 5404.32445944257, 5417.891642167135, 5434.701091439969, 5435.891282174334, 5440.727963601844, 6317.873642527124, 6427.485801135898, 6429.678516074177, 6437.903431448532, 6440.323766004549, 4553.112143132091, 4577.244754615518, 4577.748223847687, 4578.336141259532, 4581.908361832778, 6561.639109848957, 6604.900926486107, 6633.5024873134225, 6634.336566343375, 6647.501437392263, 4629.745364004944, 4633.72197668141, 4642.51253618372, 4645.187092258841, 4651.069786042805, 4623.9075218495445, 4694.976525117352, 4701.3888194535175, 4703.129920964564, 4707.176244157327, 5435.347758268954, 5448.101063324586, 5449.754761035763, 5452.59105567084, 5454.10007348791, 6467.773627923, 6471.126397936312, 6477.0, 6480.125183100221, 6484.481241500669, 1662.4929396534174, 1672.230773844021, 1686.3170415981301, 1687.1985806741945, 1687.6962146813446, 1139.2651739627893, 1141.3494308244221, 1144.2905398652888, 1156.2368836567994, 1183.816508441193, 6278.488604558122, 6304.211973503312, 6310.652914089769, 6313.43179113882, 6315.084312774622, 6617.239017512951, 6652.401283884474, 6663.400293973566, 6667.0, 6685.431738302299, 6193.1329613854095, 6299.716512756937, 6302.968485157545, 6307.716152773136, 6311.684415779193, 3590.1922067534597, 3613.638636136418, 3613.891583252454, 3622.3117607654463, 3625.7099432045375, 6635.7438470920115, 6722.092517510171, 6851.2121106072445, 6864.622445765531, 6883.139607549057, 6852.45180385568, 6860.3139686031, 6887.586744795228, 6891.138607674041, 6895.55178913369, 6695.933040669628, 6889.758858439931, 6915.965420172867, 6930.06444130036, 6937.132858392701, 6729.528932974645, 6743.393094621508, 6755.256921738506, 6757.087793147862, 6758.662066896537, 6653.5009874260595, 6654.0, 6661.267260601233, 6671.766488172891, 6674.0, 6771.119754431892, 6909.7581584644295, 6916.965415172892, 6922.688479018557, 6929.376356127973, 4704.317873908332, 4731.479537250969, 4736.455307639666, 4739.905201896012, 4740.597049250852, 6691.5650482718465, 6710.16122984627, 6714.798556043326, 6715.05989161508, 6727.495437842279, 6407.455366293731, 6542.018697195459, 6545.901811472737, 6547.279799222177, 6559.508036897348, 6542.0, 6568.0, 6571.080048793268, 6576.309487503743, 6588.176477940257, 6382.744690212339, 6395.42441180296, 6400.871982560416, 6405.8718825624155, 6411.743530258924, 6379.712912918837, 6592.571482853599, 6610.206775187008, 6610.8016759497295, 6616.702248398929, 6443.67781610927, 6445.806625801233, 6453.967730161319, 6455.128557644845, 6459.257185423548], "unit": "ms", "name": "prototype.html", "value": 4925.3210428638895}, {"lowerIsBetter": false, "replicates": [783.7883771381727, 835.2983493865152, 921.460945346972, 938.3759799733177, 948.630034286628, 13915.582532524028, 14396.64008399788, 14405.495807646734, 14416.0, 14419.279036048209, 13680.726385472284, 13701.588952331422, 13746.793798093011, 13750.793738093937, 13773.655658608539, 723.6526467295702, 732.0081289852253, 879.9164079412445, 899.4468401932829, 899.6311512279966, 880.9867851982225, 885.2431171348501, 886.175856453498, 899.4063917814244, 899.9640014399416, 901.4861528928514, 908.9818203635923, 911.6171208092617, 915.455304094063, 915.9496227707474, 769.0233403577454, 778.4823092643413, 911.0252030327553, 915.0803442540238, 930.9953450232706, 684.5139950635055, 685.3112621815044, 690.2821066091259, 690.9412699920513, 691.0636088100651, 12728.80906786399, 12731.55439559611, 12761.361931903368, 12862.485500579965, 12939.87060129396, 12406.62780116598, 12733.172343797623, 12807.167534110253, 13215.933920330337, 13362.532311369152, 10777.78444431107, 11033.724156896142, 11042.447877606088, 11098.389588572625, 12366.258024518558, 3915.197384536163, 3945.5265368156006, 3945.6448919597087, 3973.264945985002, 4037.555868854424, 51850.740746296025, 52524.0, 52738.208926866515, 53010.20484692733, 53090.46909530894, 11159.27464714791, 11220.66338009861, 11354.091672666167, 11357.716057098474, 11728.354940478184, 20285.59428811445, 20783.480412989797, 20914.37256882296, 20988.055537500848, 21007.0, 8340.62467188978, 8461.619227134794, 8622.439541429787, 8683.609237584324, 8756.51839148853, 4657.767111644404, 4750.596199323097, 4778.737169455644, 4792.904141917142, 4833.806647734122, 3279.0654663421287, 3307.4873394623874, 3308.7353011759, 3339.532465454838, 3346.1300061983575, 579.490048757091, 606.4081785313552, 609.5489337890018, 622.8349487385847, 634.0172732264971, 691.8443350246155, 700.6916956539106, 731.6670914733784, 747.9364254038359, 773.3465221887496, 8402.579871006425, 8583.35624828128, 8622.267107295826, 8683.652653893774, 8697.869531957025, 487.28961345676225, 570.2586637371401, 647.0262255305735, 657.8322527755391, 711.9822004449827, 4176.081262122329, 4660.557247061523, 4705.294205869147, 4723.48041715411, 4764.213904705754, 61075.38924610786, 61768.69115654393, 62213.68893155505, 62521.37478625155, 62882.371176288565, 55707.16439253414, 58109.70945145332, 58470.12294815581, 58841.0, 58865.70567147137, 52779.20831187535, 52827.0, 53128.0, 53257.73371133197, 53720.0, 9312.394694345117, 9558.761030974281, 9628.807423851344, 9648.179904707898, 9740.17208537254, 36084.27831443304, 36453.27093458169, 36748.0, 37116.81441592775, 37200.81399592985, 44472.55527444814, 45806.0, 49648.25527617161, 50207.49792502028, 50693.746531267105, 11962.989408544388, 12160.939195303967, 12184.147109702235, 12254.632361029184, 12414.379281035732, 12927.0, 12959.546415875398, 13040.0, 13073.542426015043, 13401.396937138048, 11464.25482343645, 11804.586839460577, 11847.111466640208, 12016.699582510157, 12325.383730813603, 12306.323152226714, 12451.688707782016, 12533.37333133358, 12747.0, 12786.552470663853, 10312.0, 11828.822567661664, 12019.278843269432, 12136.150469467053, 12311.56909508163, 10309.948450257702, 10956.0, 10972.067374273276, 11072.391018493738, 11591.304521728724, 9413.811723765622, 10186.439745814052, 10404.323718958243, 10674.0, 10763.085137763377], "unit": "ms", "name": "yui.html", "value": 6805.508323710746}], "name": "dromaeo_css", "value": 7450.991929332174}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 109.0, 103.0, 108.0, 114.0, 108.0, 97.0, 108.0, 108.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.1}, {"lowerIsBetter": true, "replicates": [144.0, 121.0, 144.0, 162.0, 127.0, 148.0, 150.0, 128.0, 165.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.1}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 159.0, 160.0, 163.0, 162.0, 161.0, 161.0, 161.0, 161.0], "unit": "ms", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [71.0, 77.0, 70.0, 148.0, 81.0, 70.0, 72.0, 71.0, 157.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 88.8}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 136.0, 92.0, 91.0, 92.0, 141.0, 91.0, 91.0, 140.0], "unit": "ms", "name": "audio-oscillator", "value": 105.8}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 110.0, 108.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 145.0, 147.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 63.0, 57.0, 55.0, 56.0, 58.0, 58.0, 55.0, 57.0], "unit": "ms", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 65.0, 63.0, 64.0, 64.0, 64.0, 65.0, 63.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [84.0, 86.0, 78.0, 80.0, 79.0, 80.0, 80.0, 78.0, 85.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.9}, {"lowerIsBetter": true, "replicates": [110.0, 107.0, 115.0, 123.0, 120.0, 118.0, 110.0, 114.0, 113.0, 117.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 114.7}, {"lowerIsBetter": true, "replicates": [131.0, 141.0, 140.0, 139.0, 143.0, 141.0, 140.0, 138.0, 139.0, 132.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.4}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 62.0, 73.0, 63.0, 61.0, 61.0, 64.0, 62.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.5}], "name": "kraken", "value": 1472.5000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.8697330871244], "unit": "score", "name": "Richards", "value": 210.29401399999998}, {"lowerIsBetter": false, "replicates": [165.8649859014762], "unit": "score", "name": "DeltaBlue", "value": 398.625422}, {"lowerIsBetter": false, "replicates": [278.1641168289291], "unit": "score", "name": "Encrypt", "value": 956.9206949999998}, {"lowerIsBetter": false, "replicates": [5240.837696335078], "unit": "score", "name": "Decrypt", "value": 50.78978121878119}, {"lowerIsBetter": false, "replicates": [972.7626459143969], "unit": "score", "name": "RayTrace", "value": 760.7086919999998}, {"lowerIsBetter": false, "replicates": [547.5929978118162], "unit": "score", "name": "Earley", "value": 1217.0772867132869}, {"lowerIsBetter": false, "replicates": [10621.052631578947], "unit": "score", "name": "Boyer", "value": 62.749241823587695}, {"lowerIsBetter": false, "replicates": [24536.585365853658], "unit": "score", "name": "RegExp", "value": 37.12761928429422}, {"lowerIsBetter": false, "replicates": [523.5602094240837], "unit": "score", "name": "Splay", "value": 155.64781000000008}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23124.108758276187}]} 09:10:31 INFO - RETURN: dromaeo_css: 7450.99 09:10:31 INFO - RETURN: kraken: 1472.50 09:10:31 INFO - RETURN: v8_7: 23124.11 09:10:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23124.11"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1472.50"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7450.99"}}} 09:10:31 INFO - Return code: 0 09:10:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:10:31 INFO - # TBPL SUCCESS # 09:10:31 INFO - Running post-action listener: _resource_record_post_action 09:10:31 INFO - Running post-run listener: _resource_record_post_run 09:10:33 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 319488; Write bytes: 514154496; Read time: 224; Write time: 1432548 09:10:33 INFO - install - Wall time: 17s; CPU: 20.0%; Read bytes: 0; Write bytes: 252534784; Read time: 0; Write time: 1104564 09:10:33 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 319488; Write bytes: 261021696; Read time: 224; Write time: 327984 09:10:33 INFO - Running post-run listener: _upload_blobber_files 09:10:33 INFO - Blob upload gear active. 09:10:33 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:10:33 INFO - Copying logs to upload dir... 09:10:33 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1260.600071 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-02-11 09:10:32.175089) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-11 09:10:32.178972) ========= 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009967 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455200494/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-11 09:10:32.231309) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:10:32.231658) ========= 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=df17445c3590ff7897617a0e00000225-1455209296.142369-1668224095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004591 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-11 09:10:32.281375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 09:10:32.281683) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-11 09:10:32.281970) ========= ========= Total master_lag: 0.28 =========