builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1455891386.01 results: success (0) buildid: 20160219035659 builduid: 2c1b694ca5684543abb8c21ac6ba786b revision: 905a0a5af413 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.013658) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.014066) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.014377) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006545 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.045714) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.046051) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.102590) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.102860) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.025514 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.155587) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.155898) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.156268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.156556) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-19 06:16:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.2M=0s 2016-02-19 06:16:26 (35.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170835 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.340581) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.340877) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.139717 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:26.491928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-19 06:16:26.492289) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 905a0a5af413 --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 905a0a5af413 --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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-19 06:16:26,535 Setting DEBUG logging. 2016-02-19 06:16:26,535 attempt 1/10 2016-02-19 06:16:26,535 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/905a0a5af413?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-19 06:16:27,727 unpacking tar archive at: mozilla-beta-905a0a5af413/testing/mozharness/ program finished with exit code 0 elapsedTime=1.476039 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-19 06:16:27.980658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:27.980998) ========= echo 905a0a5af413 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'905a0a5af413'] 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 905a0a5af413 program finished with exit code 0 elapsedTime=0.003513 script_repo_revision: '905a0a5af413' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:28.028632) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:28.028954) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 06:16:28.038715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2016-02-19 06:16:28.039003) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:16:28 INFO - MultiFileLogger online at 20160219 06:16:28 in /builds/slave/test-pgo 06:16:28 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 06:16:28 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 06:16:28 INFO - {'append_to_log': False, 06:16:28 INFO - 'base_work_dir': '/builds/slave/test-pgo', 06:16:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:16:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 06:16:28 INFO - 'branch': 'Mozilla-Beta', 06:16:28 INFO - 'buildbot_json_path': 'buildprops.json', 06:16:28 INFO - 'config_files': ('talos/linux_config.py',), 06:16:28 INFO - 'default_actions': ('clobber', 06:16:28 INFO - 'read-buildbot-config', 06:16:28 INFO - 'download-and-extract', 06:16:28 INFO - 'populate-webroot', 06:16:28 INFO - 'create-virtualenv', 06:16:28 INFO - 'install', 06:16:28 INFO - 'run-tests'), 06:16:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:16:28 INFO - 'download_minidump_stackwalk': True, 06:16:28 INFO - 'download_symbols': 'ondemand', 06:16:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:16:28 INFO - 'tooltool.py': '/tools/tooltool.py', 06:16:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:16:28 INFO - '/tools/misc-python/virtualenv.py')}, 06:16:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:16:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:16:28 INFO - 'installer_path': 'installer.exe', 06:16:28 INFO - 'log_level': 'info', 06:16:28 INFO - 'log_name': 'talos', 06:16:28 INFO - 'log_to_console': True, 06:16:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:16:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:16:28 INFO - 'opt_config_files': (), 06:16:28 INFO - 'pip_index': False, 06:16:28 INFO - 'sps_profile': False, 06:16:28 INFO - 'sps_profile_interval': 0, 06:16:28 INFO - 'suite': 'dromaeojs', 06:16:28 INFO - 'system_bits': '32', 06:16:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:16:28 INFO - 'title': 'talos-linux64-ix-033', 06:16:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:16:28 INFO - 'use_talos_json': True, 06:16:28 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 06:16:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:16:28 INFO - 'work_dir': 'build'} 06:16:28 INFO - ##### 06:16:28 INFO - ##### Running clobber step. 06:16:28 INFO - ##### 06:16:28 INFO - Running pre-action listener: _resource_record_pre_action 06:16:28 INFO - Running main action method: clobber 06:16:28 INFO - rmtree: /builds/slave/test-pgo/build 06:16:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 06:16:29 INFO - Running post-action listener: _resource_record_post_action 06:16:29 INFO - ##### 06:16:29 INFO - ##### Running read-buildbot-config step. 06:16:29 INFO - ##### 06:16:29 INFO - Running pre-action listener: _resource_record_pre_action 06:16:29 INFO - Running main action method: read_buildbot_config 06:16:29 INFO - Using buildbot properties: 06:16:29 INFO - { 06:16:29 INFO - "properties": { 06:16:29 INFO - "buildnumber": 128, 06:16:29 INFO - "product": "firefox", 06:16:29 INFO - "script_repo_revision": "production", 06:16:29 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 06:16:29 INFO - "repository": "", 06:16:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 06:16:29 INFO - "buildid": "20160219035659", 06:16:29 INFO - "slavename": "talos-linux64-ix-033", 06:16:29 INFO - "pgo_build": "True", 06:16:29 INFO - "basedir": "/builds/slave/test-pgo", 06:16:29 INFO - "project": "", 06:16:29 INFO - "platform": "ubuntu64_hw", 06:16:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:16:29 INFO - "slavebuilddir": "test", 06:16:29 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 06:16:29 INFO - "branch": "mozilla-beta", 06:16:29 INFO - "repo_path": "releases/mozilla-beta", 06:16:29 INFO - "stage_platform": "linux64-pgo", 06:16:29 INFO - "builduid": "2c1b694ca5684543abb8c21ac6ba786b", 06:16:29 INFO - "revision": "905a0a5af413" 06:16:29 INFO - }, 06:16:29 INFO - "sourcestamp": { 06:16:29 INFO - "repository": "", 06:16:29 INFO - "hasPatch": false, 06:16:29 INFO - "project": "", 06:16:29 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 06:16:29 INFO - "changes": [ 06:16:29 INFO - { 06:16:29 INFO - "category": null, 06:16:29 INFO - "files": [ 06:16:29 INFO - { 06:16:29 INFO - "url": null, 06:16:29 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2" 06:16:29 INFO - } 06:16:29 INFO - ], 06:16:29 INFO - "repository": "", 06:16:29 INFO - "rev": "905a0a5af413", 06:16:29 INFO - "who": "sendchange", 06:16:29 INFO - "when": 1455891308, 06:16:29 INFO - "number": 7627526, 06:16:29 INFO - "comments": "Bug 1247114 - do a one-off move of the synced-tabs button to the panel if Sync is initialized (aurora/beta version). r=Gijs, a=sylvestre", 06:16:29 INFO - "project": "", 06:16:29 INFO - "at": "Fri 19 Feb 2016 06:15:08", 06:16:29 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 06:16:29 INFO - "revlink": "", 06:16:29 INFO - "properties": [ 06:16:29 INFO - [ 06:16:29 INFO - "buildid", 06:16:29 INFO - "20160219035659", 06:16:29 INFO - "Change" 06:16:29 INFO - ], 06:16:29 INFO - [ 06:16:29 INFO - "builduid", 06:16:29 INFO - "2c1b694ca5684543abb8c21ac6ba786b", 06:16:29 INFO - "Change" 06:16:29 INFO - ], 06:16:29 INFO - [ 06:16:29 INFO - "pgo_build", 06:16:29 INFO - "True", 06:16:29 INFO - "Change" 06:16:29 INFO - ] 06:16:29 INFO - ], 06:16:29 INFO - "revision": "905a0a5af413" 06:16:29 INFO - } 06:16:29 INFO - ], 06:16:29 INFO - "revision": "905a0a5af413" 06:16:29 INFO - } 06:16:29 INFO - } 06:16:29 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2. 06:16:29 INFO - Running post-action listener: _resource_record_post_action 06:16:29 INFO - ##### 06:16:29 INFO - ##### Running download-and-extract step. 06:16:29 INFO - ##### 06:16:29 INFO - Running pre-action listener: _resource_record_pre_action 06:16:29 INFO - Running main action method: download_and_extract 06:16:29 INFO - mkdir: /builds/slave/test-pgo/build/tests 06:16:29 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')]} 06:16:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/test_packages.json 06:16:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 06:16:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 06:16:29 INFO - Downloaded 1270 bytes. 06:16:29 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 06:16:29 INFO - Using the following test package requirements: 06:16:29 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 06:16:29 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 06:16:29 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'jsshell-linux-x86_64.zip'], 06:16:29 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 06:16:29 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 06:16:29 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 06:16:29 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 06:16:29 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 06:16:29 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 06:16:29 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 06:16:29 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 06:16:29 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:16:29 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')]} 06:16:29 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:16:29 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:30 INFO - Downloaded 22255604 bytes. 06:16:30 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 06:16:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:16:30 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 06:16:31 INFO - Return code: 0 06:16:31 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 06:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:16:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:32 INFO - Downloaded 22255604 bytes. 06:16:32 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 06:16:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 06:16:32 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 06:16:33 INFO - Return code: 0 06:16: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')]} 06:16:33 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 06:16:33 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:34 INFO - Downloaded 11132038 bytes. 06:16:34 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 06:16:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 06:16:34 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 06:16:34 INFO - Return code: 0 06:16: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')]} 06:16:34 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2 06:16:34 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/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 06:16:35 INFO - Downloaded 58338805 bytes. 06:16:35 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2 06:16:35 INFO - mkdir: /builds/slave/test-pgo/properties 06:16:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 06:16:35 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 06:16:35 INFO - Contents: 06:16:35 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2 06:16:35 INFO - Running post-action listener: _resource_record_post_action 06:16:35 INFO - Running post-action listener: set_extra_try_arguments 06:16:35 INFO - ##### 06:16:35 INFO - ##### Running populate-webroot step. 06:16:35 INFO - ##### 06:16:35 INFO - Running pre-action listener: _resource_record_pre_action 06:16:35 INFO - Running main action method: populate_webroot 06:16:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:16:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:16:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:16:35 INFO - '--tppagecycles', 06:16:35 INFO - '7'], 06:16:35 INFO - 'tests': ['tsvgm']}}, 06:16:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 06:16:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:16:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:16:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 06:16:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:16:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:16:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'tests': ['damp', 'tps']}, 06:16:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['damp', 'tps']}, 06:16:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 06:16:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:16:35 INFO - 'other': {'tests': ['a11yr', 06:16:35 INFO - 'ts_paint', 06:16:35 INFO - 'tpaint', 06:16:35 INFO - 'sessionrestore', 06:16:35 INFO - 'sessionrestore_no_auto_restore']}, 06:16:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['a11yr', 06:16:35 INFO - 'ts_paint', 06:16:35 INFO - 'tpaint', 06:16:35 INFO - 'sessionrestore', 06:16:35 INFO - 'sessionrestore_no_auto_restore']}, 06:16:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['a11yr', 06:16:35 INFO - 'ts_paint', 06:16:35 INFO - 'tpaint', 06:16:35 INFO - 'sessionrestore', 06:16:35 INFO - 'sessionrestore_no_auto_restore']}, 06:16:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['a11yr', 06:16:35 INFO - 'ts_paint', 06:16:35 INFO - 'tpaint', 06:16:35 INFO - 'sessionrestore', 06:16:35 INFO - 'sessionrestore_no_auto_restore']}, 06:16:35 INFO - 'other_l64': {'tests': ['a11yr', 06:16:35 INFO - 'ts_paint', 06:16:35 INFO - 'tpaint', 06:16:35 INFO - 'sessionrestore', 06:16:35 INFO - 'sessionrestore_no_auto_restore']}, 06:16:35 INFO - 'other_nol64': {'tests': ['a11yr', 06:16:35 INFO - 'ts_paint', 06:16:35 INFO - 'tpaint', 06:16:35 INFO - 'sessionrestore', 06:16:35 INFO - 'sessionrestore_no_auto_restore']}, 06:16:35 INFO - 'svgr': {'tests': ['tsvgx', 06:16:35 INFO - 'tsvgr_opacity', 06:16:35 INFO - 'tart', 06:16:35 INFO - 'tscrollx', 06:16:35 INFO - 'cart']}, 06:16:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['tsvgx', 06:16:35 INFO - 'tsvgr_opacity', 06:16:35 INFO - 'tart', 06:16:35 INFO - 'tscrollx', 06:16:35 INFO - 'cart']}, 06:16:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'tests': ['tp5o']}, 06:16:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'talos_options': ['--e10s'], 06:16:35 INFO - 'tests': ['tp5o']}, 06:16:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'talos_options': ['--xperf_path', 06:16:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:16:35 INFO - 'tests': ['tp5n']}, 06:16:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:16:35 INFO - 'talos_options': ['--e10s', 06:16:35 INFO - '--xperf_path', 06:16:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:16:35 INFO - 'tests': ['tp5n']}}, 06:16:35 INFO - 'talos.zip': {'path': '', 06:16:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:16:35 INFO - Running post-action listener: _resource_record_post_action 06:16:35 INFO - ##### 06:16:35 INFO - ##### Running create-virtualenv step. 06:16:35 INFO - ##### 06:16:35 INFO - Running pre-action listener: _resource_record_pre_action 06:16:35 INFO - Running main action method: create_virtualenv 06:16:35 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 06:16:35 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 06:16:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 06:16:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:16:35 INFO - Using real prefix '/usr' 06:16:35 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 06:16:36 INFO - Installing distribute.............................................................................................................................................................................................done. 06:16:38 INFO - Installing pip.................done. 06:16:38 INFO - Return code: 0 06:16:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 06:16:38 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')]} 06:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:38 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')]} 06:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:38 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:38 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 06:16:38 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 06:16:38 INFO - Using env: {'DISPLAY': ':0', 06:16:38 INFO - 'HOME': '/home/cltbld', 06:16:38 INFO - 'LANG': 'en_US.UTF-8', 06:16:38 INFO - 'LANGUAGE': 'en_US:en', 06:16:38 INFO - 'LOGNAME': 'cltbld', 06:16:38 INFO - 'MAIL': '/var/mail/cltbld', 06:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:38 INFO - 'MOZ_NO_REMOTE': '1', 06:16:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:38 INFO - 'NO_EM_RESTART': '1', 06:16:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:38 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:38 INFO - 'SHELL': '/bin/bash', 06:16:38 INFO - 'SHLVL': '1', 06:16:38 INFO - 'TERM': 'linux', 06:16:38 INFO - 'TMOUT': '86400', 06:16:38 INFO - 'USER': 'cltbld', 06:16:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:38 INFO - '_': '/tools/buildbot/bin/python'} 06:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:39 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 06:16:39 INFO - Cleaning up... 06:16:39 INFO - Return code: 0 06:16:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 06:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:39 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:39 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 06:16:39 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 06:16:39 INFO - Using env: {'DISPLAY': ':0', 06:16:39 INFO - 'HOME': '/home/cltbld', 06:16:39 INFO - 'LANG': 'en_US.UTF-8', 06:16:39 INFO - 'LANGUAGE': 'en_US:en', 06:16:39 INFO - 'LOGNAME': 'cltbld', 06:16:39 INFO - 'MAIL': '/var/mail/cltbld', 06:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:39 INFO - 'MOZ_NO_REMOTE': '1', 06:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:39 INFO - 'NO_EM_RESTART': '1', 06:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:39 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:39 INFO - 'SHELL': '/bin/bash', 06:16:39 INFO - 'SHLVL': '1', 06:16:39 INFO - 'TERM': 'linux', 06:16:39 INFO - 'TMOUT': '86400', 06:16:39 INFO - 'USER': 'cltbld', 06:16:39 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:39 INFO - '_': '/tools/buildbot/bin/python'} 06:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:39 INFO - Downloading/unpacking psutil>=0.7.1 06:16:39 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 06:16:39 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 06:16:39 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 06:16:39 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 06:16:39 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 06:16:39 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 06:16:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 06:16:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:41 INFO - Installing collected packages: psutil 06:16:41 INFO - Running setup.py install for psutil 06:16:41 INFO - building 'psutil._psutil_linux' extension 06:16:41 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 06:16:41 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 06:16:41 INFO - building 'psutil._psutil_posix' extension 06:16:41 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 06:16:41 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 06:16:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:16:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:16:41 INFO - Successfully installed psutil 06:16:41 INFO - Cleaning up... 06:16:41 INFO - Return code: 0 06:16:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 06:16: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')]} 06:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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', '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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 06:16: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 mozsystemmonitor==0.0.0 06:16:41 INFO - Using env: {'DISPLAY': ':0', 06:16:41 INFO - 'HOME': '/home/cltbld', 06:16:41 INFO - 'LANG': 'en_US.UTF-8', 06:16:41 INFO - 'LANGUAGE': 'en_US:en', 06:16:41 INFO - 'LOGNAME': 'cltbld', 06:16:41 INFO - 'MAIL': '/var/mail/cltbld', 06:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:41 INFO - 'MOZ_NO_REMOTE': '1', 06:16:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:41 INFO - 'NO_EM_RESTART': '1', 06:16:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:41 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:41 INFO - 'SHELL': '/bin/bash', 06:16:41 INFO - 'SHLVL': '1', 06:16:41 INFO - 'TERM': 'linux', 06:16:41 INFO - 'TMOUT': '86400', 06:16:41 INFO - 'USER': 'cltbld', 06:16:41 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:41 INFO - '_': '/tools/buildbot/bin/python'} 06:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:16:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:16:43 INFO - Installing collected packages: mozsystemmonitor 06:16:43 INFO - Running setup.py install for mozsystemmonitor 06:16:43 INFO - Successfully installed mozsystemmonitor 06:16:43 INFO - Cleaning up... 06:16:43 INFO - Return code: 0 06:16:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 06:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:43 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:43 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 06:16:43 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 06:16:43 INFO - Using env: {'DISPLAY': ':0', 06:16:43 INFO - 'HOME': '/home/cltbld', 06:16:43 INFO - 'LANG': 'en_US.UTF-8', 06:16:43 INFO - 'LANGUAGE': 'en_US:en', 06:16:43 INFO - 'LOGNAME': 'cltbld', 06:16:43 INFO - 'MAIL': '/var/mail/cltbld', 06:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:43 INFO - 'MOZ_NO_REMOTE': '1', 06:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:43 INFO - 'NO_EM_RESTART': '1', 06:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:43 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:43 INFO - 'SHELL': '/bin/bash', 06:16:43 INFO - 'SHLVL': '1', 06:16:43 INFO - 'TERM': 'linux', 06:16:43 INFO - 'TMOUT': '86400', 06:16:43 INFO - 'USER': 'cltbld', 06:16:43 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:43 INFO - '_': '/tools/buildbot/bin/python'} 06:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:44 INFO - Downloading/unpacking blobuploader==1.2.4 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:45 INFO - Downloading blobuploader-1.2.4.tar.gz 06:16:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:16:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 06:16:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:46 INFO - Downloading docopt-0.6.1.tar.gz 06:16:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 06:16:46 INFO - Installing collected packages: blobuploader, requests, docopt 06:16:46 INFO - Running setup.py install for blobuploader 06:16:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:16:46 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 06:16:46 INFO - Running setup.py install for requests 06:16:47 INFO - Running setup.py install for docopt 06:16:47 INFO - Successfully installed blobuploader requests docopt 06:16:47 INFO - Cleaning up... 06:16:47 INFO - Return code: 0 06:16:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:47 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:47 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 06:16:47 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 06:16:47 INFO - Using env: {'DISPLAY': ':0', 06:16:47 INFO - 'HOME': '/home/cltbld', 06:16:47 INFO - 'LANG': 'en_US.UTF-8', 06:16:47 INFO - 'LANGUAGE': 'en_US:en', 06:16:47 INFO - 'LOGNAME': 'cltbld', 06:16:47 INFO - 'MAIL': '/var/mail/cltbld', 06:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:47 INFO - 'MOZ_NO_REMOTE': '1', 06:16:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:47 INFO - 'NO_EM_RESTART': '1', 06:16:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:47 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:47 INFO - 'SHELL': '/bin/bash', 06:16:47 INFO - 'SHLVL': '1', 06:16:47 INFO - 'TERM': 'linux', 06:16:47 INFO - 'TMOUT': '86400', 06:16:47 INFO - 'USER': 'cltbld', 06:16:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:47 INFO - '_': '/tools/buildbot/bin/python'} 06:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:16:47 INFO - Running setup.py (path:/tmp/pip-RejFx1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:16:47 INFO - Running setup.py (path:/tmp/pip-UmyobE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:16:47 INFO - Running setup.py (path:/tmp/pip-WVbnd6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:16:47 INFO - Running setup.py (path:/tmp/pip-0J_FPX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:16:47 INFO - Running setup.py (path:/tmp/pip-Z2pRM0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:16:47 INFO - Running setup.py (path:/tmp/pip-fkzbgo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:16:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:16:47 INFO - Running setup.py (path:/tmp/pip-d_vgdH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:16:48 INFO - Running setup.py (path:/tmp/pip-_OWqTP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:16:48 INFO - Running setup.py (path:/tmp/pip-vOVZFw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:16:48 INFO - Running setup.py (path:/tmp/pip-SkJvgr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:16:48 INFO - Running setup.py (path:/tmp/pip-xG92O5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:16:48 INFO - Running setup.py (path:/tmp/pip-ciEauX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:16:48 INFO - Running setup.py (path:/tmp/pip-AwULqq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:16:48 INFO - Running setup.py (path:/tmp/pip-SoRmgn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:16:48 INFO - Running setup.py (path:/tmp/pip-5HX_gu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:16:48 INFO - Running setup.py (path:/tmp/pip-3JGKIH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:16:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:16:48 INFO - Running setup.py (path:/tmp/pip-0BQT2K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:16:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:16:48 INFO - Running setup.py install for manifestparser 06:16:48 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 06:16:48 INFO - Running setup.py install for mozcrash 06:16:48 INFO - Running setup.py install for mozdebug 06:16:49 INFO - Running setup.py install for mozdevice 06:16:49 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Running setup.py install for mozfile 06:16:49 INFO - Running setup.py install for mozhttpd 06:16:49 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Running setup.py install for mozinfo 06:16:49 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Running setup.py install for mozInstall 06:16:49 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Running setup.py install for mozleak 06:16:49 INFO - Running setup.py install for mozlog 06:16:49 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Running setup.py install for moznetwork 06:16:49 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 06:16:49 INFO - Running setup.py install for mozprocess 06:16:49 INFO - Running setup.py install for mozprofile 06:16:50 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 06:16:50 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 06:16:50 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 06:16:50 INFO - Running setup.py install for mozrunner 06:16:50 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 06:16:50 INFO - Running setup.py install for mozscreenshot 06:16:50 INFO - Running setup.py install for moztest 06:16:50 INFO - Running setup.py install for mozversion 06:16:50 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 06:16:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:16:50 INFO - Cleaning up... 06:16:50 INFO - Return code: 0 06:16:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:16:50 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')]} 06:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:50 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')]} 06:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:50 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:50 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 06:16:50 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 06:16:50 INFO - Using env: {'DISPLAY': ':0', 06:16:50 INFO - 'HOME': '/home/cltbld', 06:16:50 INFO - 'LANG': 'en_US.UTF-8', 06:16:50 INFO - 'LANGUAGE': 'en_US:en', 06:16:50 INFO - 'LOGNAME': 'cltbld', 06:16:50 INFO - 'MAIL': '/var/mail/cltbld', 06:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:50 INFO - 'MOZ_NO_REMOTE': '1', 06:16:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:50 INFO - 'NO_EM_RESTART': '1', 06:16:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:50 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:50 INFO - 'SHELL': '/bin/bash', 06:16:50 INFO - 'SHLVL': '1', 06:16:50 INFO - 'TERM': 'linux', 06:16:50 INFO - 'TMOUT': '86400', 06:16:50 INFO - 'USER': 'cltbld', 06:16:50 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:50 INFO - '_': '/tools/buildbot/bin/python'} 06:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:16:50 INFO - Running setup.py (path:/tmp/pip-Y1poAX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 06:16:50 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)) 06:16:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:16:50 INFO - Running setup.py (path:/tmp/pip-NuTxek-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 06:16:50 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)) 06:16:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:16:50 INFO - Running setup.py (path:/tmp/pip-wikCAO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 06:16:50 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)) 06:16:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:16:50 INFO - Running setup.py (path:/tmp/pip-V436Cp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 06:16:51 INFO - Running setup.py (path:/tmp/pip-6HYfxA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:16:51 INFO - Running setup.py (path:/tmp/pip-UjxSpT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:16:51 INFO - Running setup.py (path:/tmp/pip-DrGsv1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:16:51 INFO - Running setup.py (path:/tmp/pip-Cqtc1e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 06:16:51 INFO - Running setup.py (path:/tmp/pip-rxtQWd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 06:16:51 INFO - Running setup.py (path:/tmp/pip-6YGGdl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:16:51 INFO - Running setup.py (path:/tmp/pip-LlGUC3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:16:51 INFO - Running setup.py (path:/tmp/pip-CG8Lzm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:16:51 INFO - Running setup.py (path:/tmp/pip-N3yLRL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:16:51 INFO - Running setup.py (path:/tmp/pip-hs_QEY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:16:51 INFO - Running setup.py (path:/tmp/pip-Z_U188-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 06:16:51 INFO - Running setup.py (path:/tmp/pip-lhFnny-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 06:16:51 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)) 06:16:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 06:16:51 INFO - Running setup.py (path:/tmp/pip-LeHT94-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 06:16:51 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)) 06:16:51 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)) 06:16:51 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)) 06:16:51 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)) 06:16:51 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)) 06:16:51 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)) 06:16:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 06:16:51 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 06:16:51 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 06:16:51 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 06:16:51 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 06:16:51 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 06:16:51 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 06:16:53 INFO - Downloading blessings-1.6.tar.gz 06:16:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 06:16:53 INFO - Installing collected packages: blessings 06:16:53 INFO - Running setup.py install for blessings 06:16:53 INFO - Successfully installed blessings 06:16:53 INFO - Cleaning up... 06:16:54 INFO - Return code: 0 06:16:54 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 06:16:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:16:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:16:54 INFO - Reading from file tmpfile_stdout 06:16:54 INFO - Current package versions: 06:16:54 INFO - argparse == 1.2.1 06:16:54 INFO - blessings == 1.6 06:16:54 INFO - blobuploader == 1.2.4 06:16:54 INFO - docopt == 0.6.1 06:16:54 INFO - manifestparser == 1.1 06:16:54 INFO - mozInstall == 1.12 06:16:54 INFO - mozcrash == 0.16 06:16:54 INFO - mozdebug == 0.1 06:16:54 INFO - mozdevice == 0.47 06:16:54 INFO - mozfile == 1.2 06:16:54 INFO - mozhttpd == 0.7 06:16:54 INFO - mozinfo == 0.9 06:16:54 INFO - mozleak == 0.1 06:16:54 INFO - mozlog == 3.1 06:16:54 INFO - moznetwork == 0.27 06:16:54 INFO - mozprocess == 0.22 06:16:54 INFO - mozprofile == 0.27 06:16:54 INFO - mozrunner == 6.11 06:16:54 INFO - mozscreenshot == 0.1 06:16:54 INFO - mozsystemmonitor == 0.0 06:16:54 INFO - moztest == 0.7 06:16:54 INFO - mozversion == 1.4 06:16:54 INFO - psutil == 3.1.1 06:16:54 INFO - requests == 1.2.3 06:16:54 INFO - wsgiref == 0.1.2 06:16:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 06:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:54 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:54 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 06:16:54 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 06:16:54 INFO - Using env: {'DISPLAY': ':0', 06:16:54 INFO - 'HOME': '/home/cltbld', 06:16:54 INFO - 'LANG': 'en_US.UTF-8', 06:16:54 INFO - 'LANGUAGE': 'en_US:en', 06:16:54 INFO - 'LOGNAME': 'cltbld', 06:16:54 INFO - 'MAIL': '/var/mail/cltbld', 06:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:54 INFO - 'MOZ_NO_REMOTE': '1', 06:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:54 INFO - 'NO_EM_RESTART': '1', 06:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:54 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:54 INFO - 'SHELL': '/bin/bash', 06:16:54 INFO - 'SHLVL': '1', 06:16:54 INFO - 'TERM': 'linux', 06:16:54 INFO - 'TMOUT': '86400', 06:16:54 INFO - 'USER': 'cltbld', 06:16:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:54 INFO - '_': '/tools/buildbot/bin/python'} 06:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:54 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)) 06:16:54 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)) 06:16:54 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)) 06:16:54 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)) 06:16:54 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)) 06:16:54 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)) 06:16:54 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)) 06:16:54 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)) 06:16: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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 06:16: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 mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 06:16:54 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)) 06:16:54 INFO - Cleaning up... 06:16:54 INFO - Return code: 0 06:16:54 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 06:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16:54 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 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16:54 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 06:16:54 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 06:16:54 INFO - Using env: {'DISPLAY': ':0', 06:16:54 INFO - 'HOME': '/home/cltbld', 06:16:54 INFO - 'LANG': 'en_US.UTF-8', 06:16:54 INFO - 'LANGUAGE': 'en_US:en', 06:16:54 INFO - 'LOGNAME': 'cltbld', 06:16:54 INFO - 'MAIL': '/var/mail/cltbld', 06:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:54 INFO - 'MOZ_NO_REMOTE': '1', 06:16:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:54 INFO - 'NO_EM_RESTART': '1', 06:16:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:54 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:54 INFO - 'SHELL': '/bin/bash', 06:16:54 INFO - 'SHLVL': '1', 06:16:54 INFO - 'TERM': 'linux', 06:16:54 INFO - 'TMOUT': '86400', 06:16:54 INFO - 'USER': 'cltbld', 06:16:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:54 INFO - '_': '/tools/buildbot/bin/python'} 06:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:54 INFO - Downloading/unpacking jsonschema 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:16:56 INFO - Downloading/unpacking functools32 (from jsonschema) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:56 INFO - Downloading functools32-3.2.3-2.tar.gz 06:16:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 06:16:56 INFO - warning: no files found matching '*.txt' 06:16:56 INFO - no previously-included directories found matching 'build' 06:16:56 INFO - no previously-included directories found matching 'dist' 06:16:56 INFO - no previously-included directories found matching '.git*' 06:16:56 INFO - Installing collected packages: jsonschema, functools32 06:16:56 INFO - Running setup.py install for functools32 06:16:56 INFO - warning: no files found matching '*.txt' 06:16:56 INFO - no previously-included directories found matching 'build' 06:16:56 INFO - no previously-included directories found matching 'dist' 06:16:56 INFO - no previously-included directories found matching '.git*' 06:16:56 INFO - Successfully installed jsonschema functools32 06:16:56 INFO - Cleaning up... 06:16:56 INFO - Return code: 0 06:16:56 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 06:16: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')]} 06:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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')]} 06:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:16: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f79d69c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26298a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f6600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', '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 06:16: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', 'flake8'] in /builds/slave/test-pgo/build 06:16: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 flake8 06:16:56 INFO - Using env: {'DISPLAY': ':0', 06:16:56 INFO - 'HOME': '/home/cltbld', 06:16:56 INFO - 'LANG': 'en_US.UTF-8', 06:16:56 INFO - 'LANGUAGE': 'en_US:en', 06:16:56 INFO - 'LOGNAME': 'cltbld', 06:16:56 INFO - 'MAIL': '/var/mail/cltbld', 06:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:16:56 INFO - 'MOZ_NO_REMOTE': '1', 06:16:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:16:56 INFO - 'NO_EM_RESTART': '1', 06:16:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:16:56 INFO - 'PWD': '/builds/slave/test-pgo', 06:16:56 INFO - 'SHELL': '/bin/bash', 06:16:56 INFO - 'SHLVL': '1', 06:16:56 INFO - 'TERM': 'linux', 06:16:56 INFO - 'TMOUT': '86400', 06:16:56 INFO - 'USER': 'cltbld', 06:16:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:16:56 INFO - '_': '/tools/buildbot/bin/python'} 06:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:16:57 INFO - Downloading/unpacking flake8 06:16:57 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 06:16:57 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 06:16:57 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 06:16:57 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 06:16:57 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 06:16:57 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 06:16:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:16:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:16:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16: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 06:16:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 06:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:16:59 INFO - no previously-included directories found matching 'docs/_build' 06:16:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:16:59 INFO - Running setup.py install for pep8 06:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:16:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:16:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:16:59 INFO - no previously-included directories found matching 'docs/_build' 06:16:59 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 06:16:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:16:59 INFO - Cleaning up... 06:16:59 INFO - Return code: 0 06:16:59 INFO - Running post-action listener: _resource_record_post_action 06:16:59 INFO - Running post-action listener: _start_resource_monitoring 06:16:59 INFO - Starting resource monitoring. 06:16:59 INFO - ##### 06:16:59 INFO - ##### Running install step. 06:16:59 INFO - ##### 06:16:59 INFO - Running pre-action listener: _resource_record_pre_action 06:16:59 INFO - Running main action method: install 06:16:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 06:16:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 06:17:00 INFO - Reading from file tmpfile_stdout 06:17:00 INFO - Detecting whether we're running mozinstall >=1.0... 06:17:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 06:17:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 06:17:00 INFO - Reading from file tmpfile_stdout 06:17:00 INFO - Output received: 06:17:00 INFO - Usage: mozinstall [options] installer 06:17:00 INFO - Options: 06:17:00 INFO - -h, --help show this help message and exit 06:17:00 INFO - -d DEST, --destination=DEST 06:17:00 INFO - Directory to install application into. [default: 06:17:00 INFO - "/builds/slave/test-pgo"] 06:17:00 INFO - --app=APP Application being installed. [default: firefox] 06:17:00 INFO - mkdir: /builds/slave/test-pgo/build/application 06:17:00 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'] 06:17:00 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 06:17:16 INFO - Reading from file tmpfile_stdout 06:17:16 INFO - Output received: 06:17:16 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 06:17:16 INFO - Running post-action listener: _resource_record_post_action 06:17:16 INFO - ##### 06:17:16 INFO - ##### Running run-tests step. 06:17:16 INFO - ##### 06:17:16 INFO - Running pre-action listener: _resource_record_pre_action 06:17:16 INFO - Running main action method: run_tests 06:17:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 06:17:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 06:17:16 INFO - Python 2.7.3 06:17:16 INFO - Return code: 0 06:17:16 INFO - grabbing minidump binary from tooltool 06:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:17:16 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 0x2785350>, '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 0x2786250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27866e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 06:17:16 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 06:17:16 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 06:17:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:17:16 INFO - Return code: 0 06:17:16 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 06:17:16 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 06:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 06:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 06:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 06:17:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 06:17:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 06:17:18 INFO - Return code: 0 06:17:18 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/1455883019/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 06:17:18 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/1455883019/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost 06:17:18 INFO - Using env: {'DISPLAY': ':0', 06:17:18 INFO - 'HOME': '/home/cltbld', 06:17:18 INFO - 'LANG': 'en_US.UTF-8', 06:17:18 INFO - 'LANGUAGE': 'en_US:en', 06:17:18 INFO - 'LOGNAME': 'cltbld', 06:17:18 INFO - 'MAIL': '/var/mail/cltbld', 06:17:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:17:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 06:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:17:18 INFO - 'MOZ_NO_REMOTE': '1', 06:17:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 06:17:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:17:18 INFO - 'NO_EM_RESTART': '1', 06:17:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 06:17:18 INFO - 'PWD': '/builds/slave/test-pgo', 06:17:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 06:17:18 INFO - 'SHELL': '/bin/bash', 06:17:18 INFO - 'SHLVL': '1', 06:17:18 INFO - 'TERM': 'linux', 06:17:18 INFO - 'TMOUT': '86400', 06:17:18 INFO - 'USER': 'cltbld', 06:17:18 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589', 06:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:17:18 INFO - '_': '/tools/buildbot/bin/python'} 06:17:18 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/1455883019/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost'] with output_timeout 3600 06:17:31 INFO - mozversion INFO | application_buildid: 20160219035659 06:17:31 INFO - mozversion INFO | application_changeset: 905a0a5af413b332ee7e7c60ef2c76a1b3726264 06:17:31 INFO - mozversion INFO | application_display_name: Nightly 06:17:31 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:17:31 INFO - mozversion INFO | application_name: Firefox 06:17:31 INFO - mozversion INFO | application_remotingname: firefox 06:17:31 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:17:31 INFO - mozversion INFO | application_vendor: Mozilla 06:17:31 INFO - mozversion INFO | application_version: 45.0 06:17:31 INFO - mozversion INFO | platform_buildid: 20160219035659 06:17:31 INFO - mozversion INFO | platform_changeset: 905a0a5af413b332ee7e7c60ef2c76a1b3726264 06:17:31 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:17:31 INFO - mozversion INFO | platform_version: 45.0 06:17:31 INFO - 2016-02-19 06:17:31,045 DEBUG : using testdate: 1455891451 06:17:31 INFO - 2016-02-19 06:17:31,045 DEBUG : actual date: 1455891451 06:17:31 INFO - 2016-02-19 06:17:31,125 INFO : starting webserver on 'localhost:43377' 06:17:31 INFO - 2016-02-19 06:17:31,126 INFO : Starting test suite talos-linux64-ix-033 06:17:31 INFO - 2016-02-19 06:17:31,126 INFO : Starting test dromaeo_css 06:17:31 INFO - 2016-02-19 06:17:31,126 DEBUG : operating with platform_type : linux_ 06:17:31 INFO - 2016-02-19 06:17:31,126 INFO : Initialising browser for dromaeo_css test... 06:17:31 INFO - 2016-02-19 06:17:31,139 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaq0v0T/profile http://localhost:43377/getInfo.html 06:17:40 INFO - 2016-02-19 06:17:40,913 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:17:40 INFO - 2016-02-19 06:17:40,914 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:17:40 INFO - 2016-02-19 06:17:40,914 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:17:40 INFO - 2016-02-19 06:17:40,914 DEBUG : BROWSER_OUTPUT: __metrics 06:17:50 INFO - 2016-02-19 06:17:50,227 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] 06:17:53 INFO - 2016-02-19 06:17:53,025 INFO : Browser initialized. 06:17:53 INFO - 2016-02-19 06:17:53,026 INFO : Running cycle 1/1 for dromaeo_css test... 06:17:53 INFO - 2016-02-19 06:17:53,026 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaq0v0T/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:18:04 INFO - 2016-02-19 06:18:04,717 DEBUG : BROWSER_OUTPUT: RSS: Main: 132739072 06:18:04 INFO - 2016-02-19 06:18:04,717 DEBUG : BROWSER_OUTPUT: 06:18:55 INFO - 2016-02-19 06:18:55,673 DEBUG : BROWSER_OUTPUT: 1455891535668 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] 06:21:05 INFO - 2016-02-19 06:21:05,397 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43377/tests/dromaeo/cssquery-ext.html) 06:21:06 INFO - 2016-02-19 06:21:06,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 235249664 06:21:06 INFO - 2016-02-19 06:21:06,448 DEBUG : BROWSER_OUTPUT: 06:24:08 INFO - 2016-02-19 06:24:08,687 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/dromaeo/cssquery-ext.html (next: http://localhost:43377/tests/dromaeo/cssquery-jquery.html) 06:24:09 INFO - 2016-02-19 06:24:09,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 218591232 06:24:09 INFO - 2016-02-19 06:24:09,253 DEBUG : BROWSER_OUTPUT: 06:27:11 INFO - 2016-02-19 06:27:11,151 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43377/tests/dromaeo/cssquery-mootools.html) 06:27:11 INFO - 2016-02-19 06:27:11,718 DEBUG : BROWSER_OUTPUT: RSS: Main: 217993216 06:27:11 INFO - 2016-02-19 06:27:11,718 DEBUG : BROWSER_OUTPUT: 06:30:13 INFO - 2016-02-19 06:30:13,338 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43377/tests/dromaeo/cssquery-prototype.html) 06:30:13 INFO - 2016-02-19 06:30:13,908 DEBUG : BROWSER_OUTPUT: RSS: Main: 197160960 06:30:13 INFO - 2016-02-19 06:30:13,908 DEBUG : BROWSER_OUTPUT: 06:33:16 INFO - 2016-02-19 06:33:16,315 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43377/tests/dromaeo/cssquery-yui.html) 06:33:16 INFO - 2016-02-19 06:33:16,877 DEBUG : BROWSER_OUTPUT: RSS: Main: 197586944 06:33:16 INFO - 2016-02-19 06:33:16,877 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,054 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/dromaeo/cssquery-yui.html (next: http://localhost:43377/tests/dromaeo/cssquery-dojo.html) 06:36:19 INFO - 2016-02-19 06:36:19,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 243208192 06:36:19 INFO - 2016-02-19 06:36:19,305 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,306 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:36:19 INFO - 2016-02-19 06:36:19,306 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:36:19 INFO - 2016-02-19 06:36:19,307 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:36:19 INFO - 2016-02-19 06:36:19,307 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:36:19 INFO - 2016-02-19 06:36:19,310 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3293.4730443129097;3300.6864347886944;3323.219043524771;3329.850156742946;3348.7655864089506;5459.0992486239775;5465.59008074394;5488.121900495921;5488.451154884519;5504.64219825711;5415.0523658359725;5469.835904922858;5489.752961116749;5490.505854473106;5498.477644623765;4900.6569540132205;4986.725730084844;4994.775235114428;5013.824516141935;5053.873653158664;4928.802847886081;4991.251312303148;5038.899222015576;5045.293658887758;5067.7719502622285;4986.351774269358;5093.949060509384;5115.283860259567;5131.563817075553;5131.769070391822;5040.092783299005;5109.9744501277255;5117.385913690362;5129.487051294859;5134.948650513485;4804.807807687688;4884.609231261491;4911.435184953741;4982.626303027251;5027.874303142414;5274.868128296785;5287.86780330491;5289.497497737709;5291.841244762663;5301.469853014706;5920.763169473216;5922.940770592325;5925.851853703692;5932.703364831741;5939.406059394069;5639.3232812062815;5823.79616713413;5824.359320474744;5848.853778655568;5888.617239879394;5514.779408823682;5535.169724541311;5543.501084902297;5546.084895992157;5546.168074788774;5685.459881311269;5981.222441082718;5982.760689572456;5987.1318658794235;5988.6706231157705;5726.513246374022;5865.648061116347;5948.791792287249;5951.821445356646;5994.100884867217;5958.791442299499;5959.22530071088;5965.403459654;5969.253843269591;5970.223870896755;5684.289463817023;5811.970940145272;5887.793927212527;5896.204012458349;5899.4985426239255;5792.4497172768515;5897.675627840425;5901.468867801833;5905.4685078343155;5930.11048342752;4505.842295519641;4513.142502924434;4563.201439748031;4564.178447879347;4594.65540084495;3961.088949541622;4016.2369149861433;4046.736962097454;4099.8360065597635;4118.567550407224;5493.862653433696;5517.089680202722;5518.33779946409;5524.8342549723575;5581.832545023656;5963.747613001318;5972.701364931736;5977;5982.012967860254;5991.460768530766;5368.1679339702405;5418.106012507892;5507.228987941611;5554.333479982347;5562.443755624486;5164.870878227999;5182.2485739567555;5184;5189.844304670866;5191.844244672666;6169.598976066542;6169.81490555284;6169.92026395379;6171.105189747462;6177.722002509808;5718.599698021098;5784.971075144597;5843.29880414353;5889.676067816311;5945.464908158287;6131.724072416663;6165.907511387422;6168.814935551941;6174.753009879554;6179.5056395489155;5694.259746233129;5897.439743224387;5900.881982360414;5905.911411328748;5909.054551271776;5825.592208545361;5841.32824725162;5936.792212272722;5940.108983652487;5967.671778052249;5771.134329850556;5790.044642633919;5823.18475413451;5824.15549745293;5829.650220986754;5805.912911306418;5806.158107074366;5812.82561523155;5813;5813.215215945793;5492.780288788404;5583.41374155724;5596.9440305598055;5693.032184528723;5697.515711164598;5810.5351571875;5842;5848.707564621837;5855.3266374367495;5874.353821079763;5692.1461780733225;5738.655680659007;5748.856278593069;5751.396103409082;5751.712414379181 06:36:19 INFO - 2016-02-19 06:36:19,311 DEBUG : BROWSER_OUTPUT: |1;ext.html;542.7382573341077;586.563010557135;587.4712758517334;592.1236569876583;612.6967151260125;41715.79142104289;42130;42180.57819421805;42245.36631950523;42378.94052648682;47146.29280560794;47788.52211477884;48109.27836082462;48160.27759583609;48165.51834481654;7780.727674531393;7866.881996770053;7871;9183.219426348769;9360.578773955154;10892.564297428093;11186.049185819214;11186.216964812467;11187.49656265466;11191.944040279786;10851.294665846734;10928.781424371507;10958.452077396138;10962.671119866416;10966.616168434106;8007.719729809458;8162.306203972669;10555.102816260627;10743.301685390425;10783.730406739816;10316.381017138921;10445.634402795942;10460.163186945027;10460.267781255316;10460.738481537946;35295;39395.21209575821;39847.20305593901;39912.401313980314;39941.20117597632;2846.5303224967856;3689.889303320878;3714.8142592870518;3843.884683459473;3896.5713771485116;31613.84193079043;31715.84142079275;31910.042698719077;32216.744827861825;32932.83533582341;4174.041371470715;4203.243416185085;4251.91496170078;4293.699441039129;4392.253316936129;331751;332165;332387;332961;333421.99999999756;62269.37730622727;63442.68278658577;63477.365226347145;66391;66821.33178668248;72046.63976680083;72199.2780072203;73041.26958730345;73095.63452182704;73170.26829731635;17558.209880555405;17623.559411014827;17652.205650745746;17684.734728979074;17729.7340539892;13381.330933453288;13545.661358466117;13547.119437236548;13549.661258468617;13556.932215339057;21555.353339399844;22022.779772202393;22051.66922496164;22768;22838.200662976717;8460.78848028792;8542.231199191978;8583.613737381833;8643.56782160902;8672.262857657044;1011.1051719228535;1042.2391654092555;1118.457548089176;1121.607437396921;1163.5345861655405;28749.56875646867;28863;29257.122286331443;29476;30099.849500752356;1108.6452335252802;1170.3153655111685;1185.8221266809876;1215.665691934725;1239.7396546725108;15254.618634534225;17312.913435432994;17489.912550437166;17941.910290448464;18189.636207276046;456323;464701;465433;467169;468097;61355.693221533606;61383;61806;61893;61967.38032619616;1454.5709015840353;1483.8812894968376;1495.9551213463612;1496.393960446043;1505.789189513469;30785;31961.200969975936;34090.65909340875;34320.48519272163;34602;54477;55270;55289;55293.723531382086;55343.72328138495;344165;344769;346388;346667;348438;29629.703702962695;29891.850540747157;34670.30659386849;34781;35493.29013419769;39866.40200397054;39921;40047.799761001006;40766.59233407628;40901.1819763609;33557.328853423285;34232.657673422946;34248.65751342455;34363.14092147716;34618.48072278968;29743.553846691884;33968.7955148546;36370.4544431839;36906;38494.037649059;34424.65575344315;40586;40810.79594602008;40895.38656920089;41144.79427602843;40656.39015414831;42361.95142841404;43036.35445468258;43096;44092.33861492144;31562.684373155975;31881.36237275195;32248.83875580607;32271.35457290794;32368 06:36:19 INFO - 2016-02-19 06:36:19,314 DEBUG : BROWSER_OUTPUT: |2;jquery.html;391.0692551726891;393.2940372032203;394.56794809683385;396.18979187561433;396.76590811421255;13784.379702913368;15870.52388428346;16637.500874973754;18880.150393232303;18899;18622.348217812378;18643.1610577524;19010.524736881584;19017.144228509715;19088.045597720095;6047.1533985241995;6167.537434692394;6178.938210617881;6185.474234690057;6197.597156184834;4452.554744525538;4558.794854231551;6101.877962440749;6674.833129171761;6923.826904327382;5184.274201611777;6823.249442561314;6855.2801955794675;6898.37914587687;6899.413549848269;5292.470752924715;5303.549198318147;5307.655002424869;5315.627906046579;5330.067238233293;5146.614003949719;5242.475752424765;5556.111022236423;5573.526250268732;5573.6098473106895;11988.400579970967;12168.2699038058;12176.939115304456;19570.412887613395;20195.596088078302;2188.2318866633723;2195.83531235158;2211.612967730641;2214.490667146565;2272.8409011369213;9637.036296370377;9936.304458687895;11210.887891121067;13725;13829.170249785046;1909.4653497020856;1910.3313840155834;1934.429343343717;1968.6161198566285;2010.678291473372;63763;65695.3430465694;65796.34203657998;66725.66637166831;67529;10510;10588.947055264829;10595.364278143335;10597.41714205726;10657.946710266398;13691.452341906412;13807.723845523033;13812.516561920285;13813.171209727449;13813.585592432242;7991.200879911962;8006;8031.959840200761;8053.07389650186;8062.032556093189;7873.409494287951;7912.564808935449;7925.960370198112;7954.204579542;7997.6800927963395;4294.733053749152;4327.891802704958;4338.783060846945;4433.357163211316;4714.504976977368;1715.2052386311668;1764.0109246229695;1815.5914919143352;1834.477174005402;1913.7894831568517;941.3739862991034;969.3747532841278;1100.0302806169354;1127.475020681927;1146.879577644352;16858.24137913797;17352.392666256874;17681.646367072586;17811.109444527723;17949.923004619766;850.7150104714866;968.7820240445843;1044.9320794148357;1051.6582110814015;1083.4257843343041;9292.72121836346;9733.977932317146;10084.949575252223;10385.532651030722;10446.738831529121;632983;643858;655528;663671;665048;81940.59029704932;84706.15293847106;85807;86763;87500.5624971871;1143.5140065472142;1176.300101439642;1183.2900259844025;1194.4087676600138;1195.9026122343173;15781.526554203392;15819.683606328039;15864.76202856935;16024.27890744896;16106.355745770037;34778.478322824674;35233.29533409266;35854.64145358513;36530.452043219906;37475.43786843254;46588;48411.27383089327;50731;51291.2306315413;53080;9963.850542241727;10312.226583006455;13874.583762487142;14299.785503217252;14432.061915975428;19191.712124317866;19211.711824322367;19418.61162776765;19647.214111435384;19884.70172947436;15129.243537823286;15189.392424302903;16017.519474415787;16314;16445.177741113137;15657;15952.282147303631;16037.679246414773;16066.116363600115;16217.189140543162;13189.93405033007;13363.198208107544;16749.832501675315;16778.916105419394;17018.149092545074;15127;15293.388264469297;15337.309821057856;15386.769198461809;15488.612784680023;14493.92753036228;14509.911756618085;14723.116613003254;15030.699386012;15561.144137072117 06:36:19 INFO - 2016-02-19 06:36:19,315 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1500.9624759381013;1516.5905205594497;1708.7266037433997;1807.6203997160594;1857.9071046447675;8092.959535202323;8376.664933402664;8378.790530236733;8378.790530236747;8390.160983901615;8125.796855078611;8200.138985406536;8201.548914809682;8202.302804261644;8206.220409061145;2280.600894843404;2303.516261585069;2341.180586794617;2341.508283260517;2342.0163531316803;2345.5074434368803;2370.644403339504;2372.406898275431;2372.833901626887;2373.9525209495887;1996.9101390437465;2043.0295609585403;2258.627326491127;2269.069681430614;2345.6716059751648;2335.8948847301913;2338.275134708246;2362.976370236293;2369.2536850892;2369.6208606622945;2315.8378913476063;2387.9761202387926;2389.1757343716563;2389.7132344118645;2393.904243830245;6912.654367281666;6974.616396098212;7535.171131175566;7760.223977602251;7774.455788094836;4742.288656701522;4831.975840120812;4836.298736683161;4836.371271734687;4837.709737415731;3101.4727496325677;3109.347037122207;3149.5118256670253;3151.2909595341093;3184.85668144934;931.8322701913653;947.4504787223394;963.0995019656643;972.1299437003912;1010.343276870033;11282.097432205404;11316.717082072932;11316.886831131666;11319.094472442268;11337.206395552315;4222.134462435193;4229.534751177368;4271.508776490681;4280.293751530962;4303.1393721255245;9858.852117218248;9873.555689993967;9875.160611347972;9883.950580247052;9901.702948911545;4806.711597304173;4826.444958829709;4827.782749776268;4830.975845120752;4839.854804355875;4360.847370342023;4401.779911004437;4406.25093734066;4410.184115938561;4413.337999300066;3498.370293347222;3581.2479379330634;3587.192881601619;3605.0626837022037;3614.150674591469;1079.445598338406;1080.206048554312;1085.5766251162054;1088.7060493666665;1099.455769394139;1227;1228.5638598297655;1316.5589527508391;1431.5275958933528;1462.436961769705;7943.563104029218;7989.880151797728;8141.79645508867;8149.225823546753;8150.510969341858;1223.8164275358768;1225.8774122587847;1226.0804396702474;1234.2717796500108;1262.1669697999275;5494.670319780826;6284.842878928063;6329.651869147241;6341.619502829845;6343.9682801585695;7798.298153166243;9831.213502919749;9986.35088719231;9991.950040249752;9992.900070999198;9757.65848195324;9828.852567211497;9903.801923961624;9912.504374781232;9914.80170396588;7852.293293603603;7917.129115797257;7919.247671471201;7924.801879953047;7928.682852685828;7826.060872695313;7899.289063984269;7958.681652733826;7972.322352599862;7996.640151193326;8811.074837142009;8940.5082720451;8977.147171018743;9110.863337050083;9129.406588571721;9115.954420227856;9154.40496367734;9231.492267925329;9311.534423278945;9423.57593908262;7163.570185788869;7251.927480725125;7253;7260.927390726237;7268.454865885185;7531.284527969834;7614.923850761643;7625.6949722010495;7646.655900484603;7647.732329368446;7333.1200255969625;7420.220876808074;7430.925690743024;7530.359919406912;7577.16651168382;7417.962910185631;7427.777166684792;7471.028766260351;7495.325420712162;7522.849543009219;7398.223186565333;7584.014078169803;7588.165301816906;7604.581748003913;7612.276833700789;7008.719651214098;7014.68433920485;7016;7017.017617533652;7026.332498412643;6889.655517224219;6909;6996.195437524546;6996.265392133752;7007.649617519206 06:36:19 INFO - 2016-02-19 06:36:19,318 DEBUG : BROWSER_OUTPUT: |4;prototype.html;360.6934106009891;362.4888906641636;365.69647192829956;365.85548708260256;365.8756022952197;6178.505719542437;6286.937130628692;6290.308066112724;6295.685215739212;6298.4646305064125;5908.586398952064;6025.487833534155;6055.152278680988;6178.960942888847;6188.071789231618;4778.2115950868065;4789.353437285974;4791;4802.06359759847;4815.277708343741;4523.050159466499;4761.381020467335;4803.711777293373;4804.639652026094;4809.2305231162845;4610.723356598598;4611.239145540982;4657.5575320344515;4756.3816703828625;4760.071786001731;4711.90576188476;4740.715557066553;4748.905021899543;4750.809967601292;4757.26262429324;4188.434561334211;4191.97904010481;4195.056112374722;4195.244855925932;4204.1801848639525;5802.506786923117;5895.204147440084;5906.881862362771;5908.822735317947;5928.466438020599;5605.775768969236;5610.074337734269;5617.325920889479;5619.7471113799975;5620.241267428885;6582.440492558138;6591;6593.340665933416;6594.1427614410295;6594.53838231324;4684.203685373494;4685.6485763567525;4685.8594242172785;4688.507706690816;4702.153612349775;6724.991251312294;6797.898031529531;6829.726810927557;6858.279880612514;6861.348171923659;4780.306855505932;4821.325014497973;4827.903441931142;4832.130216560982;4834.685745426536;4855.684380515326;4858.1012512685265;4861.708297502161;4865.391826021747;4869.9513004870205;5467.17992301158;5569.470900264469;5571.387147413781;5574.470425309589;5574.888502229933;6586.473082153416;6589.308122647149;6599.274079851212;6625.436837868835;6649.235337936103;1719.475559954201;1731.047923641992;1740.4256595323766;1743.1807050686157;1744.206386094324;1191.4876603060593;1195.0797885628017;1201.6995751062234;1201.7236035711835;1226.1355744200318;6382.138411314506;6390.488760899117;6399.616023038632;6410.711517981702;6413.3266007069515;6565.376289252514;6885.036094946711;6898.965505172442;6904.033435319059;6904.171499420102;6263.749450021948;6302.1177035215105;6444.323346048691;6451.870962580722;6455.644939528277;3706.8517259309865;3723.739338246297;3727.5340582427198;3729.198222382201;3729.627037296303;6912.170539535288;7114.679839407239;7163.641817909188;7166.462515311373;7167.749128780468;7107.111611048619;7107.857842843114;7115.217326094125;7121.5727056375745;7127.322904324081;7040.155181378165;7141.928580714126;7173.96413017942;7191.244919283505;7192.100987376578;6847.349501797321;6951.6871740770775;6956.40870526011;6968.79093627192;6982.092327997328;6819.113515242987;6834.487413444111;6835.179778426621;6839.350261725327;6842.418394436529;7171.892421613576;7173.457706593107;7178.533395329287;7179.138503379419;7194.676239569337;4679.298105284234;4739.383880095704;4762.690425122356;4776.904461910812;4777.235642297216;6855.6572171390235;6866.176058872968;6923.376896079176;6927.861442771217;6931.064306318583;6686.3313668632745;6716.6977486012265;6723.462123030244;6726.159230096238;6740.090087838079;6592.077109204811;6682.899756503753;6710.530262881747;6712.026756120433;6721.798346049626;6576.309487503934;6581;6591.143151390289;6592.538522303393;6595.307492713388;6732.697028633821;6741.561798483083;6744.325567443217;6746;6746.561473504206;6270.7805226816845;6437.227532696106;6579.243387010555;6580.045893345534;6583.407493325623 06:36:19 INFO - 2016-02-19 06:36:19,319 DEBUG : BROWSER_OUTPUT: |5;yui.html;861.9310455163584;874;890.3589415620755;908.7410088124884;912.8311262416453;13305.534306299282;13626.931865340684;13725.450981960723;13725.862741372583;13735.450581976722;12816.166949148299;12855.614331570045;12902.290374029448;12941.805872911891;12946.029047821428;704.2499737779274;712.0351923144143;890.4568213389844;891.6255172827427;901.8061116859874;906.714384968736;912.2747415804452;913.5888850017485;913.9908600913973;914.2640174659397;934.392644780891;939.774454131007;942.2132519346354;942.7784470649426;942.9198518125934;724.4566575068699;735.5990984913209;942.6465075596685;952.6713283917038;953.0279115302353;702.4345401951427;706.8020954132851;707.2149913595905;708.8334241453255;713.0338391479522;13136.146150500186;13234.602961911158;13284.468621255139;13287;13324;11352.091832653368;11771.646850594496;11965.700857478545;12017;12220.633380998584;10163.898361016369;10212.591496340137;10267.79464410715;10315.484225788756;10376.014278643592;3646.47126166707;3651.4887915691825;3657.7073834093208;3699.5560532736245;3706.36991711407;48565.271520927214;49195.50804491981;49284.26073608899;49796.26528010414;50090.49909500895;9469.715908522756;9563.808723825485;9572.329936904489;9636.614535418645;9690.418574885389;17017.744733829004;17025.48923532296;17052.829471705372;17054.14729263532;17066.743998840026;7539.886901696479;7677.155512893577;7688.46180767341;7715.305622494003;7741.651625676858;4678.345031695565;4688.742119183409;4707.411573553306;4712.52874712526;4809.807607695723;3010.6236720409947;3049.6645369009393;3049.9847500762353;3053.5267033609825;3054.160105970876;565.225640872008;571.9342275638271;576.6943519934441;576.9596128271064;583.378701682715;593.5785592229479;599.8093783839076;624.8812725582125;677.4986509982676;721.1526456413798;8428.747137585882;8698.652053917773;9285.396449230779;9375.249980001583;9458.243340532741;527.8812267239917;601.9307779605314;662.8110988368292;714.4320265388924;715.8568286342648;4056.9797151014054;4102.261592913304;4111.784171020032;4176.665153382189;4271.423357846651;58332.41667583355;58930;59589.40410595838;59595.404045959855;60149;53394;53489.46510534923;53806.461935380925;54352.45647543553;54708.72645636826;49322.75338623284;49365.25952110721;49723.99999999928;49814.50185498171;50167.74916125396;8531.445456045338;8598.527081010605;8609.053004169411;8858.601362938554;9152.405093668891;35399.469007964384;35675.81059830725;35699.82150089233;37372;37825.62174378331;47081.76459117683;47257.7637111826;47566.524334757596;47575;47892.28161577649;11290.548378065114;11459;11532.711682207677;11641.5343386267;11719.589814356457;12450.750984980068;12461.626151215478;12504.437300321326;12665.556705515262;12834.29411382383;11716.589919353122;12018;12068.094892882893;12357.135000549875;12385.752284954071;11602.593909213138;11858.644240672793;11881.5841445549;11896.378394172041;12244.755104897675;11584.536618535501;11594.536218551164;11795.587154449553;11880.168388212742;12216.38918054076;9883.851742224017;10039.899601003897;10302.690919272434;10398;10449.477526123816;10012.949935250277;10162.491875406053;10173.491325433552;10175.796484070424;10224.948875255575 06:36:19 INFO - 2016-02-19 06:36:19,320 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:36:19 INFO - 2016-02-19 06:36:19,320 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:36:19 INFO - 2016-02-19 06:36:19,320 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,804 06:36:19 INFO - 2016-02-19 06:36:19,320 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:36:19 INFO - 2016-02-19 06:36:19,320 DEBUG : BROWSER_OUTPUT: __startTimestamp1455892579305__endTimestamp 06:36:19 INFO - 2016-02-19 06:36:19,321 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,321 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:36:19 INFO - 2016-02-19 06:36:19,321 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:36:19 INFO - 2016-02-19 06:36:19,321 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,322 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5484.28 Median:5693.03 stddev:608.64 (10.7%) stddev-sans-first:585.74 06:36:19 INFO - 2016-02-19 06:36:19,322 DEBUG : BROWSER_OUTPUT: Values: 3293.5 3300.7 3323.2 3329.9 3348.8 5459.1 5465.6 5488.1 5488.5 5504.6 5415.1 5469.8 5489.8 5490.5 5498.5 4900.7 4986.7 4994.8 5013.8 5053.9 4928.8 4991.3 5038.9 5045.3 5067.8 4986.4 5093.9 5115.3 5131.6 5131.8 5040.1 5110.0 5117.4 5129.5 5134.9 4804.8 4884.6 4911.4 4982.6 5027.9 5274.9 5287.9 5289.5 5291.8 5301.5 5920.8 5922.9 5925.9 5932.7 5939.4 5639.3 5823.8 5824.4 5848.9 5888.6 5514.8 5535.2 5543.5 5546.1 5546.2 5685.5 5981.2 5982.8 5987.1 5988.7 5726.5 5865.6 5948.8 5951.8 5994.1 5958.8 5959.2 5965.4 5969.3 5970.2 5684.3 5812.0 5887.8 5896.2 5899.5 5792.4 5897.7 5901.5 5905.5 5930.1 4505.8 4513.1 4563.2 4564.2 4594.7 3961.1 4016.2 4046.7 4099.8 4118.6 5493.9 5517.1 5518.3 5524.8 5581.8 5963.7 5972.7 5977.0 5982.0 5991.5 5368.2 5418.1 5507.2 5554.3 5562.4 5164.9 5182.2 5184.0 5189.8 5191.8 6169.6 6169.8 6169.9 6171.1 6177.7 5718.6 5785.0 5843.3 5889.7 5945.5 6131.7 6165.9 6168.8 6174.8 6179.5 5694.3 5897.4 5900.9 5905.9 5909.1 5825.6 5841.3 5936.8 5940.1 5967.7 5771.1 5790.0 5823.2 5824.2 5829.7 5805.9 5806.2 5812.8 5813.0 5813.2 5492.8 5583.4 5596.9 5693.0 5697.5 5810.5 5842.0 5848.7 5855.3 5874.4 5692.1 5738.7 5748.9 5751.4 5751.7 06:36:19 INFO - 2016-02-19 06:36:19,322 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,322 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:56087.52 Median:190503.24 stddev:101533.92 (53.3%) stddev-sans-first:101733.10 06:36:19 INFO - 2016-02-19 06:36:19,324 DEBUG : BROWSER_OUTPUT: Values: 542.7 586.6 587.5 592.1 612.7 41715.8 42130.0 42180.6 42245.4 42378.9 47146.3 47788.5 48109.3 48160.3 48165.5 7780.7 7866.9 7871.0 9183.2 9360.6 10892.6 11186.0 11186.2 11187.5 11191.9 10851.3 10928.8 10958.5 10962.7 10966.6 8007.7 8162.3 10555.1 10743.3 10783.7 10316.4 10445.6 10460.2 10460.3 10460.7 35295.0 39395.2 39847.2 39912.4 39941.2 2846.5 3689.9 3714.8 3843.9 3896.6 31613.8 31715.8 31910.0 32216.7 32932.8 4174.0 4203.2 4251.9 4293.7 4392.3 331751.0 332165.0 332387.0 332961.0 333422.0 62269.4 63442.7 63477.4 66391.0 66821.3 72046.6 72199.3 73041.3 73095.6 73170.3 17558.2 17623.6 17652.2 17684.7 17729.7 13381.3 13545.7 13547.1 13549.7 13556.9 21555.4 22022.8 22051.7 22768.0 22838.2 8460.8 8542.2 8583.6 8643.6 8672.3 1011.1 1042.2 1118.5 1121.6 1163.5 28749.6 28863.0 29257.1 29476.0 30099.8 1108.6 1170.3 1185.8 1215.7 1239.7 15254.6 17312.9 17489.9 17941.9 18189.6 456323.0 464701.0 465433.0 467169.0 468097.0 61355.7 61383.0 61806.0 61893.0 61967.4 1454.6 1483.9 1496.0 1496.4 1505.8 30785.0 31961.2 34090.7 34320.5 34602.0 54477.0 55270.0 55289.0 55293.7 55343.7 344165.0 344769.0 346388.0 346667.0 348438.0 29629.7 29891.9 34670.3 34781.0 35493.3 39866.4 39921.0 40047.8 40766.6 40901.2 33557.3 34232.7 34248.7 34363.1 34618.5 29743.6 33968.8 36370.5 36906.0 38494.0 34424.7 40586.0 40810.8 40895.4 41144.8 40656.4 42362.0 43036.4 43096.0 44092.3 31562.7 31881.4 32248.8 32271.4 32368.0 06:36:19 INFO - 2016-02-19 06:36:19,324 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,324 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:33012.74 Median:19608.81 stddev:106467.95 (543.0%) stddev-sans-first:106738.44 06:36:19 INFO - 2016-02-19 06:36:19,325 DEBUG : BROWSER_OUTPUT: Values: 391.1 393.3 394.6 396.2 396.8 13784.4 15870.5 16637.5 18880.2 18899.0 18622.3 18643.2 19010.5 19017.1 19088.0 6047.2 6167.5 6178.9 6185.5 6197.6 4452.6 4558.8 6101.9 6674.8 6923.8 5184.3 6823.2 6855.3 6898.4 6899.4 5292.5 5303.5 5307.7 5315.6 5330.1 5146.6 5242.5 5556.1 5573.5 5573.6 11988.4 12168.3 12176.9 19570.4 20195.6 2188.2 2195.8 2211.6 2214.5 2272.8 9637.0 9936.3 11210.9 13725.0 13829.2 1909.5 1910.3 1934.4 1968.6 2010.7 63763.0 65695.3 65796.3 66725.7 67529.0 10510.0 10588.9 10595.4 10597.4 10657.9 13691.5 13807.7 13812.5 13813.2 13813.6 7991.2 8006.0 8032.0 8053.1 8062.0 7873.4 7912.6 7926.0 7954.2 7997.7 4294.7 4327.9 4338.8 4433.4 4714.5 1715.2 1764.0 1815.6 1834.5 1913.8 941.4 969.4 1100.0 1127.5 1146.9 16858.2 17352.4 17681.6 17811.1 17949.9 850.7 968.8 1044.9 1051.7 1083.4 9292.7 9734.0 10084.9 10385.5 10446.7 632983.0 643858.0 655528.0 663671.0 665048.0 81940.6 84706.2 85807.0 86763.0 87500.6 1143.5 1176.3 1183.3 1194.4 1195.9 15781.5 15819.7 15864.8 16024.3 16106.4 34778.5 35233.3 35854.6 36530.5 37475.4 46588.0 48411.3 50731.0 51291.2 53080.0 9963.9 10312.2 13874.6 14299.8 14432.1 19191.7 19211.7 19418.6 19647.2 19884.7 15129.2 15189.4 16017.5 16314.0 16445.2 15657.0 15952.3 16037.7 16066.1 16217.2 13189.9 13363.2 16749.8 16778.9 17018.1 15127.0 15293.4 15337.3 15386.8 15488.6 14493.9 14509.9 14723.1 15030.7 15561.1 06:36:19 INFO - 2016-02-19 06:36:19,325 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,326 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5750.67 Median:6996.23 stddev:3028.84 (43.3%) stddev-sans-first:3020.50 06:36:19 INFO - 2016-02-19 06:36:19,327 DEBUG : BROWSER_OUTPUT: Values: 1501.0 1516.6 1708.7 1807.6 1857.9 8093.0 8376.7 8378.8 8378.8 8390.2 8125.8 8200.1 8201.5 8202.3 8206.2 2280.6 2303.5 2341.2 2341.5 2342.0 2345.5 2370.6 2372.4 2372.8 2374.0 1996.9 2043.0 2258.6 2269.1 2345.7 2335.9 2338.3 2363.0 2369.3 2369.6 2315.8 2388.0 2389.2 2389.7 2393.9 6912.7 6974.6 7535.2 7760.2 7774.5 4742.3 4832.0 4836.3 4836.4 4837.7 3101.5 3109.3 3149.5 3151.3 3184.9 931.8 947.5 963.1 972.1 1010.3 11282.1 11316.7 11316.9 11319.1 11337.2 4222.1 4229.5 4271.5 4280.3 4303.1 9858.9 9873.6 9875.2 9884.0 9901.7 4806.7 4826.4 4827.8 4831.0 4839.9 4360.8 4401.8 4406.3 4410.2 4413.3 3498.4 3581.2 3587.2 3605.1 3614.2 1079.4 1080.2 1085.6 1088.7 1099.5 1227.0 1228.6 1316.6 1431.5 1462.4 7943.6 7989.9 8141.8 8149.2 8150.5 1223.8 1225.9 1226.1 1234.3 1262.2 5494.7 6284.8 6329.7 6341.6 6344.0 7798.3 9831.2 9986.4 9992.0 9992.9 9757.7 9828.9 9903.8 9912.5 9914.8 7852.3 7917.1 7919.2 7924.8 7928.7 7826.1 7899.3 7958.7 7972.3 7996.6 8811.1 8940.5 8977.1 9110.9 9129.4 9116.0 9154.4 9231.5 9311.5 9423.6 7163.6 7251.9 7253.0 7260.9 7268.5 7531.3 7614.9 7625.7 7646.7 7647.7 7333.1 7420.2 7430.9 7530.4 7577.2 7418.0 7427.8 7471.0 7495.3 7522.8 7398.2 7584.0 7588.2 7604.6 7612.3 7008.7 7014.7 7016.0 7017.0 7026.3 6889.7 6909.0 6996.2 6996.3 7007.6 06:36:19 INFO - 2016-02-19 06:36:19,327 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,327 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5576.88 Median:6297.07 stddev:1670.51 (26.5%) stddev-sans-first:1628.67 06:36:19 INFO - 2016-02-19 06:36:19,329 DEBUG : BROWSER_OUTPUT: Values: 360.7 362.5 365.7 365.9 365.9 6178.5 6286.9 6290.3 6295.7 6298.5 5908.6 6025.5 6055.2 6179.0 6188.1 4778.2 4789.4 4791.0 4802.1 4815.3 4523.1 4761.4 4803.7 4804.6 4809.2 4610.7 4611.2 4657.6 4756.4 4760.1 4711.9 4740.7 4748.9 4750.8 4757.3 4188.4 4192.0 4195.1 4195.2 4204.2 5802.5 5895.2 5906.9 5908.8 5928.5 5605.8 5610.1 5617.3 5619.7 5620.2 6582.4 6591.0 6593.3 6594.1 6594.5 4684.2 4685.6 4685.9 4688.5 4702.2 6725.0 6797.9 6829.7 6858.3 6861.3 4780.3 4821.3 4827.9 4832.1 4834.7 4855.7 4858.1 4861.7 4865.4 4870.0 5467.2 5569.5 5571.4 5574.5 5574.9 6586.5 6589.3 6599.3 6625.4 6649.2 1719.5 1731.0 1740.4 1743.2 1744.2 1191.5 1195.1 1201.7 1201.7 1226.1 6382.1 6390.5 6399.6 6410.7 6413.3 6565.4 6885.0 6899.0 6904.0 6904.2 6263.7 6302.1 6444.3 6451.9 6455.6 3706.9 3723.7 3727.5 3729.2 3729.6 6912.2 7114.7 7163.6 7166.5 7167.7 7107.1 7107.9 7115.2 7121.6 7127.3 7040.2 7141.9 7174.0 7191.2 7192.1 6847.3 6951.7 6956.4 6968.8 6982.1 6819.1 6834.5 6835.2 6839.4 6842.4 7171.9 7173.5 7178.5 7179.1 7194.7 4679.3 4739.4 4762.7 4776.9 4777.2 6855.7 6866.2 6923.4 6927.9 6931.1 6686.3 6716.7 6723.5 6726.2 6740.1 6592.1 6682.9 6710.5 6712.0 6721.8 6576.3 6581.0 6591.1 6592.5 6595.3 6732.7 6741.6 6744.3 6746.0 6746.6 6270.8 6437.2 6579.2 6580.0 6583.4 06:36:19 INFO - 2016-02-19 06:36:19,329 DEBUG : BROWSER_OUTPUT: 06:36:19 INFO - 2016-02-19 06:36:19,329 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14252.17 Median:47570.76 stddev:16690.23 (35.1%) stddev-sans-first:16706.76 06:36:19 INFO - 2016-02-19 06:36:19,329 DEBUG : BROWSER_OUTPUT: Values: 861.9 874.0 890.4 908.7 912.8 13305.5 13626.9 13725.5 13725.9 13735.5 12816.2 12855.6 12902.3 12941.8 12946.0 704.2 712.0 890.5 891.6 901.8 906.7 912.3 913.6 914.0 914.3 934.4 939.8 942.2 942.8 942.9 724.5 735.6 942.6 952.7 953.0 702.4 706.8 707.2 708.8 713.0 13136.1 13234.6 13284.5 13287.0 13324.0 11352.1 11771.6 11965.7 12017.0 12220.6 10163.9 10212.6 10267.8 10315.5 10376.0 3646.5 3651.5 3657.7 3699.6 3706.4 48565.3 49195.5 49284.3 49796.3 50090.5 9469.7 9563.8 9572.3 9636.6 9690.4 17017.7 17025.5 17052.8 17054.1 17066.7 7539.9 7677.2 7688.5 7715.3 7741.7 4678.3 4688.7 4707.4 4712.5 4809.8 3010.6 3049.7 3050.0 3053.5 3054.2 565.2 571.9 576.7 577.0 583.4 593.6 599.8 624.9 677.5 721.2 8428.7 8698.7 9285.4 9375.2 9458.2 527.9 601.9 662.8 714.4 715.9 4057.0 4102.3 4111.8 4176.7 4271.4 58332.4 58930.0 59589.4 59595.4 60149.0 53394.0 53489.5 53806.5 54352.5 54708.7 49322.8 49365.3 49724.0 49814.5 50167.7 8531.4 8598.5 8609.1 8858.6 9152.4 35399.5 35675.8 35699.8 37372.0 37825.6 47081.8 47257.8 47566.5 47575.0 47892.3 11290.5 11459.0 11532.7 11641.5 11719.6 12450.8 12461.6 12504.4 12665.6 12834.3 11716.6 12018.0 12068.1 12357.1 12385.8 11602.6 11858.6 11881.6 11896.4 12244.8 11584.5 11594.5 11795.6 11880.2 12216.4 9883.9 10039.9 10302.7 10398.0 10449.5 10012.9 10162.5 10173.5 10175.8 10224.9 06:36:19 INFO - 2016-02-19 06:36:19,329 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:36:19 INFO - 2016-02-19 06:36:19,329 DEBUG : BROWSER_OUTPUT: 06:36:20 INFO - 2016-02-19 06:36:20,401 INFO : Browser exited with error code: 0 06:36:20 INFO - 2016-02-19 06:36:20,422 INFO : Completed test dromaeo_css (00:18:49) 06:36:20 INFO - 2016-02-19 06:36:20,422 INFO : Starting test kraken 06:36:20 INFO - 2016-02-19 06:36:20,422 DEBUG : operating with platform_type : linux_ 06:36:20 INFO - 2016-02-19 06:36:20,422 INFO : Initialising browser for kraken test... 06:36:20 INFO - 2016-02-19 06:36:20,428 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_nA0W6/profile http://localhost:43377/getInfo.html 06:36:28 INFO - 2016-02-19 06:36:28,998 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:36:28 INFO - 2016-02-19 06:36:28,998 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:36:28 INFO - 2016-02-19 06:36:28,998 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:36:28 INFO - 2016-02-19 06:36:28,998 DEBUG : BROWSER_OUTPUT: __metrics 06:36:29 INFO - 2016-02-19 06:36:29,543 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] 06:36:32 INFO - 2016-02-19 06:36:32,701 INFO : Browser initialized. 06:36:32 INFO - 2016-02-19 06:36:32,702 INFO : Running cycle 1/1 for kraken test... 06:36:32 INFO - 2016-02-19 06:36:32,702 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_nA0W6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:36:44 INFO - 2016-02-19 06:36:44,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 133324800 06:36:44 INFO - 2016-02-19 06:36:44,360 DEBUG : BROWSER_OUTPUT: 06:36:46 INFO - 2016-02-19 06:36:46,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43377/tests/kraken/driver.html?testName=audio-beat-detection) 06:36:47 INFO - 2016-02-19 06:36:47,365 DEBUG : BROWSER_OUTPUT: RSS: Main: 164577280 06:36:47 INFO - 2016-02-19 06:36:47,365 DEBUG : BROWSER_OUTPUT: 06:36:49 INFO - 2016-02-19 06:36:49,923 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43377/tests/kraken/driver.html?testName=audio-dft) 06:36:50 INFO - 2016-02-19 06:36:50,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 161886208 06:36:50 INFO - 2016-02-19 06:36:50,492 DEBUG : BROWSER_OUTPUT: 06:36:53 INFO - 2016-02-19 06:36:53,297 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43377/tests/kraken/driver.html?testName=audio-fft) 06:36:53 INFO - 2016-02-19 06:36:53,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 159260672 06:36:53 INFO - 2016-02-19 06:36:53,866 DEBUG : BROWSER_OUTPUT: 06:36:55 INFO - 2016-02-19 06:36:55,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43377/tests/kraken/driver.html?testName=audio-oscillator) 06:36:56 INFO - 2016-02-19 06:36:56,405 DEBUG : BROWSER_OUTPUT: RSS: Main: 160542720 06:36:56 INFO - 2016-02-19 06:36:56,405 DEBUG : BROWSER_OUTPUT: 06:36:58 INFO - 2016-02-19 06:36:58,605 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43377/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:36:59 INFO - 2016-02-19 06:36:59,167 DEBUG : BROWSER_OUTPUT: RSS: Main: 158556160 06:36:59 INFO - 2016-02-19 06:36:59,167 DEBUG : BROWSER_OUTPUT: 06:37:02 INFO - 2016-02-19 06:37:02,010 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43377/tests/kraken/driver.html?testName=imaging-darkroom) 06:37:02 INFO - 2016-02-19 06:37:02,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 157962240 06:37:02 INFO - 2016-02-19 06:37:02,584 DEBUG : BROWSER_OUTPUT: 06:37:06 INFO - 2016-02-19 06:37:06,559 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43377/tests/kraken/driver.html?testName=imaging-desaturate) 06:37:07 INFO - 2016-02-19 06:37:07,126 DEBUG : BROWSER_OUTPUT: RSS: Main: 154697728 06:37:07 INFO - 2016-02-19 06:37:07,126 DEBUG : BROWSER_OUTPUT: 06:37:09 INFO - 2016-02-19 06:37:09,823 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43377/tests/kraken/driver.html?testName=json-parse-financial) 06:37:10 INFO - 2016-02-19 06:37:10,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 152485888 06:37:10 INFO - 2016-02-19 06:37:10,390 DEBUG : BROWSER_OUTPUT: 06:37:11 INFO - 2016-02-19 06:37:11,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43377/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:37:12 INFO - 2016-02-19 06:37:12,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 163721216 06:37:12 INFO - 2016-02-19 06:37:12,508 DEBUG : BROWSER_OUTPUT: 06:37:14 INFO - 2016-02-19 06:37:14,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:37:14 INFO - 2016-02-19 06:37:14,882 DEBUG : BROWSER_OUTPUT: RSS: Main: 164831232 06:37:14 INFO - 2016-02-19 06:37:14,882 DEBUG : BROWSER_OUTPUT: 06:37:16 INFO - 2016-02-19 06:37:16,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:37:17 INFO - 2016-02-19 06:37:17,442 DEBUG : BROWSER_OUTPUT: RSS: Main: 168091648 06:37:17 INFO - 2016-02-19 06:37:17,442 DEBUG : BROWSER_OUTPUT: 06:37:19 INFO - 2016-02-19 06:37:19,810 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:37:20 INFO - 2016-02-19 06:37:20,390 DEBUG : BROWSER_OUTPUT: RSS: Main: 175484928 06:37:20 INFO - 2016-02-19 06:37:20,391 DEBUG : BROWSER_OUTPUT: 06:37:22 INFO - 2016-02-19 06:37:22,850 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:37:23 INFO - 2016-02-19 06:37:23,416 DEBUG : BROWSER_OUTPUT: RSS: Main: 170795008 06:37:23 INFO - 2016-02-19 06:37:23,416 DEBUG : BROWSER_OUTPUT: 06:37:25 INFO - 2016-02-19 06:37:25,066 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43377/tests/kraken/driver.html?testName=ai-astar) 06:37:32 INFO - 2016-02-19 06:37:25,315 DEBUG : BROWSER_OUTPUT: RSS: Main: 230961152 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;105;110;112;111;99;110;108;111 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;123;147;160;128;149;149;125;158;131 06:37:32 INFO - 2016-02-19 06:37:25,316 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;160;160;160;159;163;158;167;161;159 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;70;149;72;70;72;73;153;72 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;140;92;94;94;136;92;93;134 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;110;110;109;109;109;109;110 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;145;145;146;146;145;145;145;144 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;96;96;96;95;95;96 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;57;57;58;57;58;57;58;58;56;56 06:37:32 INFO - 2016-02-19 06:37:25,317 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;65;65;65;65;65;66;66;65;65 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;78;79;77;78;77;79;79;78;78 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;114;120;122;112;128;118;118;109;116;115 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;144;134;143;154;143;132;141;139;143;142 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;62;65;66;66;64;63;62;64;63;65 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:37:32 INFO - 2016-02-19 06:37:25,318 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,893 06:37:32 INFO - 2016-02-19 06:37:25,319 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:37:32 INFO - 2016-02-19 06:37:25,319 DEBUG : BROWSER_OUTPUT: __startTimestamp1455892645313__endTimestamp 06:37:32 INFO - 2016-02-19 06:37:25,319 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,322 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:37:32 INFO - 2016-02-19 06:37:25,322 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:37:32 INFO - 2016-02-19 06:37:25,323 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,323 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.60 Median:110.50 stddev:3.89 (3.5%) stddev-sans-first:4.10 06:37:32 INFO - 2016-02-19 06:37:25,323 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 105.0 110.0 112.0 111.0 99.0 110.0 108.0 111.0 06:37:32 INFO - 2016-02-19 06:37:25,323 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,323 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:142.70 Median:149.00 stddev:14.49 (9.7%) stddev-sans-first:14.42 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: Values: 157.0 123.0 147.0 160.0 128.0 149.0 149.0 125.0 158.0 131.0 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.60 Median:160.00 stddev:2.63 (1.6%) stddev-sans-first:2.73 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: Values: 159.0 160.0 160.0 160.0 159.0 163.0 158.0 167.0 161.0 159.0 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.60 Median:72.00 stddev:33.44 (46.4%) stddev-sans-first:35.05 06:37:32 INFO - 2016-02-19 06:37:25,324 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 70.0 149.0 72.0 70.0 72.0 73.0 153.0 72.0 06:37:32 INFO - 2016-02-19 06:37:25,325 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,325 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:93.00 stddev:21.09 (22.7%) stddev-sans-first:21.90 06:37:32 INFO - 2016-02-19 06:37:25,325 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 140.0 92.0 94.0 94.0 136.0 92.0 93.0 134.0 06:37:32 INFO - 2016-02-19 06:37:25,325 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,325 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 06:37:32 INFO - 2016-02-19 06:37:25,325 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 110.0 110.0 109.0 109.0 109.0 109.0 110.0 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 144.0 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.50 stddev:0.79 (1.4%) stddev-sans-first:0.83 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: Values: 57.0 57.0 58.0 57.0 58.0 57.0 58.0 58.0 56.0 56.0 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 65.0 65.0 65.0 65.0 66.0 66.0 65.0 65.0 06:37:32 INFO - 2016-02-19 06:37:25,326 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: Values: 77.0 78.0 79.0 77.0 78.0 77.0 79.0 79.0 78.0 78.0 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:118.00 stddev:5.37 (4.6%) stddev-sans-first:5.57 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: Values: 114.0 120.0 122.0 112.0 128.0 118.0 118.0 109.0 116.0 115.0 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.50 Median:143.00 stddev:5.99 (4.2%) stddev-sans-first:6.28 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: Values: 144.0 134.0 143.0 154.0 143.0 132.0 141.0 139.0 143.0 142.0 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.50 stddev:1.49 (2.3%) stddev-sans-first:1.39 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: Values: 62.0 65.0 66.0 66.0 64.0 63.0 62.0 64.0 63.0 65.0 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:37:32 INFO - 2016-02-19 06:37:25,327 DEBUG : BROWSER_OUTPUT: 06:37:33 INFO - 2016-02-19 06:37:33,326 INFO : Browser shutdown timed out after 5 seconds, terminating process. 06:37:33 INFO - 2016-02-19 06:37:33,327 DEBUG : Terminating psutil.Process(pid=21093, name='firefox') 06:37:34 INFO - 2016-02-19 06:37:34,184 INFO : Browser exited with error code: None 06:37:37 INFO - 2016-02-19 06:37:37,773 INFO : Completed test kraken (00:01:17) 06:37:37 INFO - 2016-02-19 06:37:37,773 INFO : Starting test v8_7 06:37:37 INFO - 2016-02-19 06:37:37,774 DEBUG : operating with platform_type : linux_ 06:37:37 INFO - 2016-02-19 06:37:37,774 INFO : Initialising browser for v8_7 test... 06:37:37 INFO - 2016-02-19 06:37:37,780 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjvcpjh/profile http://localhost:43377/getInfo.html 06:37:45 INFO - 2016-02-19 06:37:45,399 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:37:45 INFO - 2016-02-19 06:37:45,400 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:37:45 INFO - 2016-02-19 06:37:45,400 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:37:45 INFO - 2016-02-19 06:37:45,400 DEBUG : BROWSER_OUTPUT: __metrics 06:37:45 INFO - 2016-02-19 06:37:45,905 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] 06:37:48 INFO - 2016-02-19 06:37:48,785 INFO : Browser initialized. 06:37:48 INFO - 2016-02-19 06:37:48,786 INFO : Running cycle 1/1 for v8_7 test... 06:37:48 INFO - 2016-02-19 06:37:48,786 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpjvcpjh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:38:00 INFO - 2016-02-19 06:38:00,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 132755456 06:38:00 INFO - 2016-02-19 06:38:00,466 DEBUG : BROWSER_OUTPUT: 06:38:21 INFO - 2016-02-19 06:38:21,945 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:43377/tests/v8_7/run.html (next: http://localhost:43377/tests/v8_7/run.html) 06:38:22 INFO - 2016-02-19 06:38:22,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 421089280 06:38:22 INFO - 2016-02-19 06:38:22,194 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,194 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:38:22 INFO - 2016-02-19 06:38:22,196 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:38:22 INFO - 2016-02-19 06:38:22,196 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:38:22 INFO - 2016-02-19 06:38:22,196 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:38:22 INFO - 2016-02-19 06:38:22,196 DEBUG : BROWSER_OUTPUT: |0;Richards;165.59032952475576 06:38:22 INFO - 2016-02-19 06:38:22,196 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.63615409944023 06:38:22 INFO - 2016-02-19 06:38:22,197 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.623542476402 06:38:22 INFO - 2016-02-19 06:38:22,197 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5164.948453608247 06:38:22 INFO - 2016-02-19 06:38:22,197 DEBUG : BROWSER_OUTPUT: |4;RayTrace;947.8672985781991 06:38:22 INFO - 2016-02-19 06:38:22,197 DEBUG : BROWSER_OUTPUT: |5;Earley;537.9236148466917 06:38:22 INFO - 2016-02-19 06:38:22,197 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 06:38:22 INFO - 2016-02-19 06:38:22,197 DEBUG : BROWSER_OUTPUT: |7;RegExp;24119.04761904762 06:38:22 INFO - 2016-02-19 06:38:22,198 DEBUG : BROWSER_OUTPUT: |8;Splay;526.8703898840885 06:38:22 INFO - 2016-02-19 06:38:22,198 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 06:38:22 INFO - 2016-02-19 06:38:22,198 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:38:22 INFO - 2016-02-19 06:38:22,198 DEBUG : BROWSER_OUTPUT: __startTimestamp1455892702194__endTimestamp 06:38:22 INFO - 2016-02-19 06:38:22,198 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.59 Median:165.59 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: Values: 165.6 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,199 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.64 Median:164.64 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,200 DEBUG : BROWSER_OUTPUT: Values: 164.6 06:38:22 INFO - 2016-02-19 06:38:22,200 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,200 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.62 Median:277.62 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,200 DEBUG : BROWSER_OUTPUT: Values: 277.6 06:38:22 INFO - 2016-02-19 06:38:22,200 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,200 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,202 DEBUG : BROWSER_OUTPUT: Values: 5164.9 06:38:22 INFO - 2016-02-19 06:38:22,202 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,202 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:947.87 Median:947.87 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,202 DEBUG : BROWSER_OUTPUT: Values: 947.9 06:38:22 INFO - 2016-02-19 06:38:22,202 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,202 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:537.92 Median:537.92 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: Values: 537.9 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: Values: 10547.4 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24119.05 Median:24119.05 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: Values: 24119.0 06:38:22 INFO - 2016-02-19 06:38:22,203 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:526.87 Median:526.87 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: Values: 526.9 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: Values: 4830.9 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:38:22 INFO - 2016-02-19 06:38:22,204 DEBUG : BROWSER_OUTPUT: 06:38:23 INFO - 2016-02-19 06:38:23,205 INFO : Browser exited with error code: 0 06:38:23 INFO - 2016-02-19 06:38:23,217 INFO : Completed test v8_7 (00:00:45) 06:38:23 INFO - 2016-02-19 06:38:23,663 INFO : Completed test suite (00:20:52) 06:38:23 INFO - 2016-02-19 06:38:23,663 DEBUG : Working with test: dromaeo_css 06:38:23 INFO - 2016-02-19 06:38:23,663 DEBUG : Generating results file: dromaeo_css 06:38:23 INFO - 2016-02-19 06:38:23,664 DEBUG : Working with test: kraken 06:38:23 INFO - 2016-02-19 06:38:23,664 DEBUG : Generating results file: kraken 06:38:23 INFO - 2016-02-19 06:38:23,664 INFO : javascript benchmark 06:38:23 INFO - 2016-02-19 06:38:23,665 DEBUG : Working with test: v8_7 06:38:23 INFO - 2016-02-19 06:38:23,665 DEBUG : Generating results file: v8_7 06:38:23 INFO - 2016-02-19 06:38:23,665 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:38:23 INFO - 2016-02-19 06:38:23,724 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 06:38:23 INFO - 2016-02-19 06:38:23,724 DEBUG : process_Request line: dromaeo_css 7422.22 graph.html#tests=[[72,53,35]] 06:38:23 INFO - 2016-02-19 06:38:23,724 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:38:23 INFO - 2016-02-19 06:38:23,757 DEBUG : process_Request line: kraken 1478.70 graph.html#tests=[[232,53,35]] 06:38:23 INFO - 2016-02-19 06:38:23,757 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:38:23 INFO - 2016-02-19 06:38:23,902 DEBUG : process_Request line: v8_7 23351.54 graph.html#tests=[[230,53,35]] 06:38:23 INFO - 2016-02-19 06:38:23,904 INFO : javascript benchmark 06:38:23 INFO - 2016-02-19 06:38:23,908 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3293.4730443129097, 3300.6864347886944, 3323.219043524771, 3329.850156742946, 3348.7655864089506, 5459.0992486239775, 5465.59008074394, 5488.121900495921, 5488.451154884519, 5504.64219825711, 5415.0523658359725, 5469.835904922858, 5489.752961116749, 5490.505854473106, 5498.477644623765, 4900.6569540132205, 4986.725730084844, 4994.775235114428, 5013.824516141935, 5053.873653158664, 4928.802847886081, 4991.251312303148, 5038.899222015576, 5045.293658887758, 5067.7719502622285, 4986.351774269358, 5093.949060509384, 5115.283860259567, 5131.563817075553, 5131.769070391822, 5040.092783299005, 5109.9744501277255, 5117.385913690362, 5129.487051294859, 5134.948650513485, 4804.807807687688, 4884.609231261491, 4911.435184953741, 4982.626303027251, 5027.874303142414, 5274.868128296785, 5287.86780330491, 5289.497497737709, 5291.841244762663, 5301.469853014706, 5920.763169473216, 5922.940770592325, 5925.851853703692, 5932.703364831741, 5939.406059394069, 5639.3232812062815, 5823.79616713413, 5824.359320474744, 5848.853778655568, 5888.617239879394, 5514.779408823682, 5535.169724541311, 5543.501084902297, 5546.084895992157, 5546.168074788774, 5685.459881311269, 5981.222441082718, 5982.760689572456, 5987.1318658794235, 5988.6706231157705, 5726.513246374022, 5865.648061116347, 5948.791792287249, 5951.821445356646, 5994.100884867217, 5958.791442299499, 5959.22530071088, 5965.403459654, 5969.253843269591, 5970.223870896755, 5684.289463817023, 5811.970940145272, 5887.793927212527, 5896.204012458349, 5899.4985426239255, 5792.4497172768515, 5897.675627840425, 5901.468867801833, 5905.4685078343155, 5930.11048342752, 4505.842295519641, 4513.142502924434, 4563.201439748031, 4564.178447879347, 4594.65540084495, 3961.088949541622, 4016.2369149861433, 4046.736962097454, 4099.8360065597635, 4118.567550407224, 5493.862653433696, 5517.089680202722, 5518.33779946409, 5524.8342549723575, 5581.832545023656, 5963.747613001318, 5972.701364931736, 5977.0, 5982.012967860254, 5991.460768530766, 5368.1679339702405, 5418.106012507892, 5507.228987941611, 5554.333479982347, 5562.443755624486, 5164.870878227999, 5182.2485739567555, 5184.0, 5189.844304670866, 5191.844244672666, 6169.598976066542, 6169.81490555284, 6169.92026395379, 6171.105189747462, 6177.722002509808, 5718.599698021098, 5784.971075144597, 5843.29880414353, 5889.676067816311, 5945.464908158287, 6131.724072416663, 6165.907511387422, 6168.814935551941, 6174.753009879554, 6179.5056395489155, 5694.259746233129, 5897.439743224387, 5900.881982360414, 5905.911411328748, 5909.054551271776, 5825.592208545361, 5841.32824725162, 5936.792212272722, 5940.108983652487, 5967.671778052249, 5771.134329850556, 5790.044642633919, 5823.18475413451, 5824.15549745293, 5829.650220986754, 5805.912911306418, 5806.158107074366, 5812.82561523155, 5813.0, 5813.215215945793, 5492.780288788404, 5583.41374155724, 5596.9440305598055, 5693.032184528723, 5697.515711164598, 5810.5351571875, 5842.0, 5848.707564621837, 5855.3266374367495, 5874.353821079763, 5692.1461780733225, 5738.655680659007, 5748.856278593069, 5751.396103409082, 5751.712414379181], "unit": "ms", "name": "dojo.html", "value": 5444.949879477215}, {"lowerIsBetter": false, "replicates": [542.7382573341077, 586.563010557135, 587.4712758517334, 592.1236569876583, 612.6967151260125, 41715.79142104289, 42130.0, 42180.57819421805, 42245.36631950523, 42378.94052648682, 47146.29280560794, 47788.52211477884, 48109.27836082462, 48160.27759583609, 48165.51834481654, 7780.727674531393, 7866.881996770053, 7871.0, 9183.219426348769, 9360.578773955154, 10892.564297428093, 11186.049185819214, 11186.216964812467, 11187.49656265466, 11191.944040279786, 10851.294665846734, 10928.781424371507, 10958.452077396138, 10962.671119866416, 10966.616168434106, 8007.719729809458, 8162.306203972669, 10555.102816260627, 10743.301685390425, 10783.730406739816, 10316.381017138921, 10445.634402795942, 10460.163186945027, 10460.267781255316, 10460.738481537946, 35295.0, 39395.21209575821, 39847.20305593901, 39912.401313980314, 39941.20117597632, 2846.5303224967856, 3689.889303320878, 3714.8142592870518, 3843.884683459473, 3896.5713771485116, 31613.84193079043, 31715.84142079275, 31910.042698719077, 32216.744827861825, 32932.83533582341, 4174.041371470715, 4203.243416185085, 4251.91496170078, 4293.699441039129, 4392.253316936129, 331751.0, 332165.0, 332387.0, 332961.0, 333421.99999999756, 62269.37730622727, 63442.68278658577, 63477.365226347145, 66391.0, 66821.33178668248, 72046.63976680083, 72199.2780072203, 73041.26958730345, 73095.63452182704, 73170.26829731635, 17558.209880555405, 17623.559411014827, 17652.205650745746, 17684.734728979074, 17729.7340539892, 13381.330933453288, 13545.661358466117, 13547.119437236548, 13549.661258468617, 13556.932215339057, 21555.353339399844, 22022.779772202393, 22051.66922496164, 22768.0, 22838.200662976717, 8460.78848028792, 8542.231199191978, 8583.613737381833, 8643.56782160902, 8672.262857657044, 1011.1051719228535, 1042.2391654092555, 1118.457548089176, 1121.607437396921, 1163.5345861655405, 28749.56875646867, 28863.0, 29257.122286331443, 29476.0, 30099.849500752356, 1108.6452335252802, 1170.3153655111685, 1185.8221266809876, 1215.665691934725, 1239.7396546725108, 15254.618634534225, 17312.913435432994, 17489.912550437166, 17941.910290448464, 18189.636207276046, 456323.0, 464701.0, 465433.0, 467169.0, 468097.0, 61355.693221533606, 61383.0, 61806.0, 61893.0, 61967.38032619616, 1454.5709015840353, 1483.8812894968376, 1495.9551213463612, 1496.393960446043, 1505.789189513469, 30785.0, 31961.200969975936, 34090.65909340875, 34320.48519272163, 34602.0, 54477.0, 55270.0, 55289.0, 55293.723531382086, 55343.72328138495, 344165.0, 344769.0, 346388.0, 346667.0, 348438.0, 29629.703702962695, 29891.850540747157, 34670.30659386849, 34781.0, 35493.29013419769, 39866.40200397054, 39921.0, 40047.799761001006, 40766.59233407628, 40901.1819763609, 33557.328853423285, 34232.657673422946, 34248.65751342455, 34363.14092147716, 34618.48072278968, 29743.553846691884, 33968.7955148546, 36370.4544431839, 36906.0, 38494.037649059, 34424.65575344315, 40586.0, 40810.79594602008, 40895.38656920089, 41144.79427602843, 40656.39015414831, 42361.95142841404, 43036.35445468258, 43096.0, 44092.33861492144, 31562.684373155975, 31881.36237275195, 32248.83875580607, 32271.35457290794, 32368.0], "unit": "ms", "name": "ext.html", "value": 20732.159129509324}, {"lowerIsBetter": false, "replicates": [391.0692551726891, 393.2940372032203, 394.56794809683385, 396.18979187561433, 396.76590811421255, 13784.379702913368, 15870.52388428346, 16637.500874973754, 18880.150393232303, 18899.0, 18622.348217812378, 18643.1610577524, 19010.524736881584, 19017.144228509715, 19088.045597720095, 6047.1533985241995, 6167.537434692394, 6178.938210617881, 6185.474234690057, 6197.597156184834, 4452.554744525538, 4558.794854231551, 6101.877962440749, 6674.833129171761, 6923.826904327382, 5184.274201611777, 6823.249442561314, 6855.2801955794675, 6898.37914587687, 6899.413549848269, 5292.470752924715, 5303.549198318147, 5307.655002424869, 5315.627906046579, 5330.067238233293, 5146.614003949719, 5242.475752424765, 5556.111022236423, 5573.526250268732, 5573.6098473106895, 11988.400579970967, 12168.2699038058, 12176.939115304456, 19570.412887613395, 20195.596088078302, 2188.2318866633723, 2195.83531235158, 2211.612967730641, 2214.490667146565, 2272.8409011369213, 9637.036296370377, 9936.304458687895, 11210.887891121067, 13725.0, 13829.170249785046, 1909.4653497020856, 1910.3313840155834, 1934.429343343717, 1968.6161198566285, 2010.678291473372, 63763.0, 65695.3430465694, 65796.34203657998, 66725.66637166831, 67529.0, 10510.0, 10588.947055264829, 10595.364278143335, 10597.41714205726, 10657.946710266398, 13691.452341906412, 13807.723845523033, 13812.516561920285, 13813.171209727449, 13813.585592432242, 7991.200879911962, 8006.0, 8031.959840200761, 8053.07389650186, 8062.032556093189, 7873.409494287951, 7912.564808935449, 7925.960370198112, 7954.204579542, 7997.6800927963395, 4294.733053749152, 4327.891802704958, 4338.783060846945, 4433.357163211316, 4714.504976977368, 1715.2052386311668, 1764.0109246229695, 1815.5914919143352, 1834.477174005402, 1913.7894831568517, 941.3739862991034, 969.3747532841278, 1100.0302806169354, 1127.475020681927, 1146.879577644352, 16858.24137913797, 17352.392666256874, 17681.646367072586, 17811.109444527723, 17949.923004619766, 850.7150104714866, 968.7820240445843, 1044.9320794148357, 1051.6582110814015, 1083.4257843343041, 9292.72121836346, 9733.977932317146, 10084.949575252223, 10385.532651030722, 10446.738831529121, 632983.0, 643858.0, 655528.0, 663671.0, 665048.0, 81940.59029704932, 84706.15293847106, 85807.0, 86763.0, 87500.5624971871, 1143.5140065472142, 1176.300101439642, 1183.2900259844025, 1194.4087676600138, 1195.9026122343173, 15781.526554203392, 15819.683606328039, 15864.76202856935, 16024.27890744896, 16106.355745770037, 34778.478322824674, 35233.29533409266, 35854.64145358513, 36530.452043219906, 37475.43786843254, 46588.0, 48411.27383089327, 50731.0, 51291.2306315413, 53080.0, 9963.850542241727, 10312.226583006455, 13874.583762487142, 14299.785503217252, 14432.061915975428, 19191.712124317866, 19211.711824322367, 19418.61162776765, 19647.214111435384, 19884.70172947436, 15129.243537823286, 15189.392424302903, 16017.519474415787, 16314.0, 16445.177741113137, 15657.0, 15952.282147303631, 16037.679246414773, 16066.116363600115, 16217.189140543162, 13189.93405033007, 13363.198208107544, 16749.832501675315, 16778.916105419394, 17018.149092545074, 15127.0, 15293.388264469297, 15337.309821057856, 15386.769198461809, 15488.612784680023, 14493.92753036228, 14509.911756618085, 14723.116613003254, 15030.699386012, 15561.144137072117], "unit": "ms", "name": "jquery.html", "value": 9608.686623361675}, {"lowerIsBetter": false, "replicates": [1500.9624759381013, 1516.5905205594497, 1708.7266037433997, 1807.6203997160594, 1857.9071046447675, 8092.959535202323, 8376.664933402664, 8378.790530236733, 8378.790530236747, 8390.160983901615, 8125.796855078611, 8200.138985406536, 8201.548914809682, 8202.302804261644, 8206.220409061145, 2280.600894843404, 2303.516261585069, 2341.180586794617, 2341.508283260517, 2342.0163531316803, 2345.5074434368803, 2370.644403339504, 2372.406898275431, 2372.833901626887, 2373.9525209495887, 1996.9101390437465, 2043.0295609585403, 2258.627326491127, 2269.069681430614, 2345.6716059751648, 2335.8948847301913, 2338.275134708246, 2362.976370236293, 2369.2536850892, 2369.6208606622945, 2315.8378913476063, 2387.9761202387926, 2389.1757343716563, 2389.7132344118645, 2393.904243830245, 6912.654367281666, 6974.616396098212, 7535.171131175566, 7760.223977602251, 7774.455788094836, 4742.288656701522, 4831.975840120812, 4836.298736683161, 4836.371271734687, 4837.709737415731, 3101.4727496325677, 3109.347037122207, 3149.5118256670253, 3151.2909595341093, 3184.85668144934, 931.8322701913653, 947.4504787223394, 963.0995019656643, 972.1299437003912, 1010.343276870033, 11282.097432205404, 11316.717082072932, 11316.886831131666, 11319.094472442268, 11337.206395552315, 4222.134462435193, 4229.534751177368, 4271.508776490681, 4280.293751530962, 4303.1393721255245, 9858.852117218248, 9873.555689993967, 9875.160611347972, 9883.950580247052, 9901.702948911545, 4806.711597304173, 4826.444958829709, 4827.782749776268, 4830.975845120752, 4839.854804355875, 4360.847370342023, 4401.779911004437, 4406.25093734066, 4410.184115938561, 4413.337999300066, 3498.370293347222, 3581.2479379330634, 3587.192881601619, 3605.0626837022037, 3614.150674591469, 1079.445598338406, 1080.206048554312, 1085.5766251162054, 1088.7060493666665, 1099.455769394139, 1227.0, 1228.5638598297655, 1316.5589527508391, 1431.5275958933528, 1462.436961769705, 7943.563104029218, 7989.880151797728, 8141.79645508867, 8149.225823546753, 8150.510969341858, 1223.8164275358768, 1225.8774122587847, 1226.0804396702474, 1234.2717796500108, 1262.1669697999275, 5494.670319780826, 6284.842878928063, 6329.651869147241, 6341.619502829845, 6343.9682801585695, 7798.298153166243, 9831.213502919749, 9986.35088719231, 9991.950040249752, 9992.900070999198, 9757.65848195324, 9828.852567211497, 9903.801923961624, 9912.504374781232, 9914.80170396588, 7852.293293603603, 7917.129115797257, 7919.247671471201, 7924.801879953047, 7928.682852685828, 7826.060872695313, 7899.289063984269, 7958.681652733826, 7972.322352599862, 7996.640151193326, 8811.074837142009, 8940.5082720451, 8977.147171018743, 9110.863337050083, 9129.406588571721, 9115.954420227856, 9154.40496367734, 9231.492267925329, 9311.534423278945, 9423.57593908262, 7163.570185788869, 7251.927480725125, 7253.0, 7260.927390726237, 7268.454865885185, 7531.284527969834, 7614.923850761643, 7625.6949722010495, 7646.655900484603, 7647.732329368446, 7333.1200255969625, 7420.220876808074, 7430.925690743024, 7530.359919406912, 7577.16651168382, 7417.962910185631, 7427.777166684792, 7471.028766260351, 7495.325420712162, 7522.849543009219, 7398.223186565333, 7584.014078169803, 7588.165301816906, 7604.581748003913, 7612.276833700789, 7008.719651214098, 7014.68433920485, 7016.0, 7017.017617533652, 7026.332498412643, 6889.655517224219, 6909.0, 6996.195437524546, 6996.265392133752, 7007.649617519206], "unit": "ms", "name": "mootools.html", "value": 4692.546337767106}, {"lowerIsBetter": false, "replicates": [360.6934106009891, 362.4888906641636, 365.69647192829956, 365.85548708260256, 365.8756022952197, 6178.505719542437, 6286.937130628692, 6290.308066112724, 6295.685215739212, 6298.4646305064125, 5908.586398952064, 6025.487833534155, 6055.152278680988, 6178.960942888847, 6188.071789231618, 4778.2115950868065, 4789.353437285974, 4791.0, 4802.06359759847, 4815.277708343741, 4523.050159466499, 4761.381020467335, 4803.711777293373, 4804.639652026094, 4809.2305231162845, 4610.723356598598, 4611.239145540982, 4657.5575320344515, 4756.3816703828625, 4760.071786001731, 4711.90576188476, 4740.715557066553, 4748.905021899543, 4750.809967601292, 4757.26262429324, 4188.434561334211, 4191.97904010481, 4195.056112374722, 4195.244855925932, 4204.1801848639525, 5802.506786923117, 5895.204147440084, 5906.881862362771, 5908.822735317947, 5928.466438020599, 5605.775768969236, 5610.074337734269, 5617.325920889479, 5619.7471113799975, 5620.241267428885, 6582.440492558138, 6591.0, 6593.340665933416, 6594.1427614410295, 6594.53838231324, 4684.203685373494, 4685.6485763567525, 4685.8594242172785, 4688.507706690816, 4702.153612349775, 6724.991251312294, 6797.898031529531, 6829.726810927557, 6858.279880612514, 6861.348171923659, 4780.306855505932, 4821.325014497973, 4827.903441931142, 4832.130216560982, 4834.685745426536, 4855.684380515326, 4858.1012512685265, 4861.708297502161, 4865.391826021747, 4869.9513004870205, 5467.17992301158, 5569.470900264469, 5571.387147413781, 5574.470425309589, 5574.888502229933, 6586.473082153416, 6589.308122647149, 6599.274079851212, 6625.436837868835, 6649.235337936103, 1719.475559954201, 1731.047923641992, 1740.4256595323766, 1743.1807050686157, 1744.206386094324, 1191.4876603060593, 1195.0797885628017, 1201.6995751062234, 1201.7236035711835, 1226.1355744200318, 6382.138411314506, 6390.488760899117, 6399.616023038632, 6410.711517981702, 6413.3266007069515, 6565.376289252514, 6885.036094946711, 6898.965505172442, 6904.033435319059, 6904.171499420102, 6263.749450021948, 6302.1177035215105, 6444.323346048691, 6451.870962580722, 6455.644939528277, 3706.8517259309865, 3723.739338246297, 3727.5340582427198, 3729.198222382201, 3729.627037296303, 6912.170539535288, 7114.679839407239, 7163.641817909188, 7166.462515311373, 7167.749128780468, 7107.111611048619, 7107.857842843114, 7115.217326094125, 7121.5727056375745, 7127.322904324081, 7040.155181378165, 7141.928580714126, 7173.96413017942, 7191.244919283505, 7192.100987376578, 6847.349501797321, 6951.6871740770775, 6956.40870526011, 6968.79093627192, 6982.092327997328, 6819.113515242987, 6834.487413444111, 6835.179778426621, 6839.350261725327, 6842.418394436529, 7171.892421613576, 7173.457706593107, 7178.533395329287, 7179.138503379419, 7194.676239569337, 4679.298105284234, 4739.383880095704, 4762.690425122356, 4776.904461910812, 4777.235642297216, 6855.6572171390235, 6866.176058872968, 6923.376896079176, 6927.861442771217, 6931.064306318583, 6686.3313668632745, 6716.6977486012265, 6723.462123030244, 6726.159230096238, 6740.090087838079, 6592.077109204811, 6682.899756503753, 6710.530262881747, 6712.026756120433, 6721.798346049626, 6576.309487503934, 6581.0, 6591.143151390289, 6592.538522303393, 6595.307492713388, 6732.697028633821, 6741.561798483083, 6744.325567443217, 6746.0, 6746.561473504206, 6270.7805226816845, 6437.227532696106, 6579.243387010555, 6580.045893345534, 6583.407493325623], "unit": "ms", "name": "prototype.html", "value": 5043.936959389328}, {"lowerIsBetter": false, "replicates": [861.9310455163584, 874.0, 890.3589415620755, 908.7410088124884, 912.8311262416453, 13305.534306299282, 13626.931865340684, 13725.450981960723, 13725.862741372583, 13735.450581976722, 12816.166949148299, 12855.614331570045, 12902.290374029448, 12941.805872911891, 12946.029047821428, 704.2499737779274, 712.0351923144143, 890.4568213389844, 891.6255172827427, 901.8061116859874, 906.714384968736, 912.2747415804452, 913.5888850017485, 913.9908600913973, 914.2640174659397, 934.392644780891, 939.774454131007, 942.2132519346354, 942.7784470649426, 942.9198518125934, 724.4566575068699, 735.5990984913209, 942.6465075596685, 952.6713283917038, 953.0279115302353, 702.4345401951427, 706.8020954132851, 707.2149913595905, 708.8334241453255, 713.0338391479522, 13136.146150500186, 13234.602961911158, 13284.468621255139, 13287.0, 13324.0, 11352.091832653368, 11771.646850594496, 11965.700857478545, 12017.0, 12220.633380998584, 10163.898361016369, 10212.591496340137, 10267.79464410715, 10315.484225788756, 10376.014278643592, 3646.47126166707, 3651.4887915691825, 3657.7073834093208, 3699.5560532736245, 3706.36991711407, 48565.271520927214, 49195.50804491981, 49284.26073608899, 49796.26528010414, 50090.49909500895, 9469.715908522756, 9563.808723825485, 9572.329936904489, 9636.614535418645, 9690.418574885389, 17017.744733829004, 17025.48923532296, 17052.829471705372, 17054.14729263532, 17066.743998840026, 7539.886901696479, 7677.155512893577, 7688.46180767341, 7715.305622494003, 7741.651625676858, 4678.345031695565, 4688.742119183409, 4707.411573553306, 4712.52874712526, 4809.807607695723, 3010.6236720409947, 3049.6645369009393, 3049.9847500762353, 3053.5267033609825, 3054.160105970876, 565.225640872008, 571.9342275638271, 576.6943519934441, 576.9596128271064, 583.378701682715, 593.5785592229479, 599.8093783839076, 624.8812725582125, 677.4986509982676, 721.1526456413798, 8428.747137585882, 8698.652053917773, 9285.396449230779, 9375.249980001583, 9458.243340532741, 527.8812267239917, 601.9307779605314, 662.8110988368292, 714.4320265388924, 715.8568286342648, 4056.9797151014054, 4102.261592913304, 4111.784171020032, 4176.665153382189, 4271.423357846651, 58332.41667583355, 58930.0, 59589.40410595838, 59595.404045959855, 60149.0, 53394.0, 53489.46510534923, 53806.461935380925, 54352.45647543553, 54708.72645636826, 49322.75338623284, 49365.25952110721, 49723.99999999928, 49814.50185498171, 50167.74916125396, 8531.445456045338, 8598.527081010605, 8609.053004169411, 8858.601362938554, 9152.405093668891, 35399.469007964384, 35675.81059830725, 35699.82150089233, 37372.0, 37825.62174378331, 47081.76459117683, 47257.7637111826, 47566.524334757596, 47575.0, 47892.28161577649, 11290.548378065114, 11459.0, 11532.711682207677, 11641.5343386267, 11719.589814356457, 12450.750984980068, 12461.626151215478, 12504.437300321326, 12665.556705515262, 12834.29411382383, 11716.589919353122, 12018.0, 12068.094892882893, 12357.135000549875, 12385.752284954071, 11602.593909213138, 11858.644240672793, 11881.5841445549, 11896.378394172041, 12244.755104897675, 11584.536618535501, 11594.536218551164, 11795.587154449553, 11880.168388212742, 12216.38918054076, 9883.851742224017, 10039.899601003897, 10302.690919272434, 10398.0, 10449.477526123816, 10012.949935250277, 10162.491875406053, 10173.491325433552, 10175.796484070424, 10224.948875255575], "unit": "ms", "name": "yui.html", "value": 6511.529264879836}], "name": "dromaeo_css", "value": 7422.222555324668}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 105.0, 110.0, 112.0, 111.0, 99.0, 110.0, 108.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [157.0, 123.0, 147.0, 160.0, 128.0, 149.0, 149.0, 125.0, 158.0, 131.0], "unit": "ms", "name": "audio-beat-detection", "value": 142.7}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 160.0, 160.0, 159.0, 163.0, 158.0, 167.0, 161.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.6}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 70.0, 149.0, 72.0, 70.0, 72.0, 73.0, 153.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 87.6}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 140.0, 92.0, 94.0, 94.0, 136.0, 92.0, 93.0, 134.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 144.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [57.0, 57.0, 58.0, 57.0, 58.0, 57.0, 58.0, 58.0, 56.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.2}, {"lowerIsBetter": true, "replicates": [77.0, 78.0, 79.0, 77.0, 78.0, 77.0, 79.0, 79.0, 78.0, 78.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [114.0, 120.0, 122.0, 112.0, 128.0, 118.0, 118.0, 109.0, 116.0, 115.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.2}, {"lowerIsBetter": true, "replicates": [144.0, 134.0, 143.0, 154.0, 143.0, 132.0, 141.0, 139.0, 143.0, 142.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.5}, {"lowerIsBetter": true, "replicates": [62.0, 65.0, 66.0, 66.0, 64.0, 63.0, 62.0, 64.0, 63.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.0}], "name": "kraken", "value": 1478.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.59032952475576], "unit": "score", "name": "Richards", "value": 213.18877800000007}, {"lowerIsBetter": false, "replicates": [164.63615409944023], "unit": "score", "name": "DeltaBlue", "value": 401.60073199999994}, {"lowerIsBetter": false, "replicates": [277.623542476402], "unit": "score", "name": "Encrypt", "value": 958.7839620000002}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [947.8672985781991], "unit": "score", "name": "RayTrace", "value": 780.6883950000001}, {"lowerIsBetter": false, "replicates": [537.9236148466917], "unit": "score", "name": "Earley", "value": 1238.9547169999996}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [24119.04761904762], "unit": "score", "name": "RegExp", "value": 37.77035538005923}, {"lowerIsBetter": false, "replicates": [526.8703898840885], "unit": "score", "name": "Splay", "value": 154.66991800000002}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 23351.53642747859}]} 06:38:23 INFO - RETURN: dromaeo_css: 7422.22 06:38:23 INFO - RETURN: kraken: 1478.70 06:38:23 INFO - RETURN: v8_7: 23351.54 06:38:23 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23351.54"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1478.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7422.22"}}} 06:38:24 INFO - Return code: 0 06:38:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:38:24 INFO - # TBPL SUCCESS # 06:38:24 INFO - Running post-action listener: _resource_record_post_action 06:38:24 INFO - Running post-run listener: _resource_record_post_run 06:38:25 INFO - Total resource usage - Wall time: 1284s; CPU: 13.0%; Read bytes: 405504; Write bytes: 577335296; Read time: 1932; Write time: 4517268 06:38:25 INFO - install - Wall time: 17s; CPU: 20.0%; Read bytes: 0; Write bytes: 173797376; Read time: 0; Write time: 1166268 06:38:25 INFO - run-tests - Wall time: 1267s; CPU: 13.0%; Read bytes: 405504; Write bytes: 394407936; Read time: 1932; Write time: 3245376 06:38:25 INFO - Running post-run listener: _upload_blobber_files 06:38:25 INFO - Blob upload gear active. 06:38:25 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:38:25 INFO - Copying logs to upload dir... 06:38:25 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1317.143144 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2016-02-19 06:38:25.209316) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-19 06:38:25.212791) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009970 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455883019/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.10 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-19 06:38:25.322366) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:38:25.322736) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455890686.854325-1859083589 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 06:38:25.373343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-19 06:38:25.373686) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-19 06:38:25.374060) ========= ========= Total master_lag: 0.31 =========