builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1456855621.05 results: success (0) buildid: 20160301073943 builduid: 13a8a44a82eb48b49f846c683f7d0bc5 revision: cc7623105377 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.047852) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.048290) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.048584) ========= 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006332 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.070965) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.071262) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.084282) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.084565) ========= 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004635 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.132964) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.133270) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.133629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.133896) ========= 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-01 10:07:01-- 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% 60.9M=0s 2016-03-01 10:07:01 (60.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139845 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.286030) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.286379) ========= 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019853 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.336040) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.336427) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cc7623105377 --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 cc7623105377 --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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-01 10:07:01,448 Setting DEBUG logging. 2016-03-01 10:07:01,448 attempt 1/10 2016-03-01 10:07:01,448 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cc7623105377?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-01 10:07:01,739 unpacking tar archive at: mozilla-beta-cc7623105377/testing/mozharness/ program finished with exit code 0 elapsedTime=0.596065 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.958768) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:01.959070) ========= echo cc7623105377 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'cc7623105377'] 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False cc7623105377 program finished with exit code 0 elapsedTime=0.003191 script_repo_revision: 'cc7623105377' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:02.006763) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:02.007080) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-01 10:07:02.016738) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-03-01 10:07:02.017135) ========= /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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:07:02 INFO - MultiFileLogger online at 20160301 10:07:02 in /builds/slave/test-pgo 10:07:02 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 10:07:02 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:07:02 INFO - {'append_to_log': False, 10:07:02 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:07:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:07:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:07:02 INFO - 'branch': 'Mozilla-Beta', 10:07:02 INFO - 'buildbot_json_path': 'buildprops.json', 10:07:02 INFO - 'config_files': ('talos/linux_config.py',), 10:07:02 INFO - 'default_actions': ('clobber', 10:07:02 INFO - 'read-buildbot-config', 10:07:02 INFO - 'download-and-extract', 10:07:02 INFO - 'populate-webroot', 10:07:02 INFO - 'create-virtualenv', 10:07:02 INFO - 'install', 10:07:02 INFO - 'run-tests'), 10:07:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:07:02 INFO - 'download_minidump_stackwalk': True, 10:07:02 INFO - 'download_symbols': 'ondemand', 10:07:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:07:02 INFO - 'tooltool.py': '/tools/tooltool.py', 10:07:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:07:02 INFO - '/tools/misc-python/virtualenv.py')}, 10:07:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:07:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:07:02 INFO - 'installer_path': 'installer.exe', 10:07:02 INFO - 'log_level': 'info', 10:07:02 INFO - 'log_name': 'talos', 10:07:02 INFO - 'log_to_console': True, 10:07:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:07:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:07:02 INFO - 'opt_config_files': (), 10:07:02 INFO - 'pip_index': False, 10:07:02 INFO - 'sps_profile': False, 10:07:02 INFO - 'sps_profile_interval': 0, 10:07:02 INFO - 'suite': 'dromaeojs', 10:07:02 INFO - 'system_bits': '32', 10:07:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:07:02 INFO - 'title': 'talos-linux64-ix-036', 10:07:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:07:02 INFO - 'use_talos_json': True, 10:07:02 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:07:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:07:02 INFO - 'work_dir': 'build'} 10:07:02 INFO - ##### 10:07:02 INFO - ##### Running clobber step. 10:07:02 INFO - ##### 10:07:02 INFO - Running pre-action listener: _resource_record_pre_action 10:07:02 INFO - Running main action method: clobber 10:07:02 INFO - rmtree: /builds/slave/test-pgo/build 10:07:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:07:02 INFO - Running post-action listener: _resource_record_post_action 10:07:02 INFO - ##### 10:07:02 INFO - ##### Running read-buildbot-config step. 10:07:02 INFO - ##### 10:07:02 INFO - Running pre-action listener: _resource_record_pre_action 10:07:02 INFO - Running main action method: read_buildbot_config 10:07:02 INFO - Using buildbot properties: 10:07:02 INFO - { 10:07:02 INFO - "properties": { 10:07:02 INFO - "buildnumber": 106, 10:07:02 INFO - "product": "firefox", 10:07:02 INFO - "script_repo_revision": "production", 10:07:02 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:07:02 INFO - "repository": "", 10:07:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:07:02 INFO - "buildid": "20160301073943", 10:07:02 INFO - "slavename": "talos-linux64-ix-036", 10:07:02 INFO - "pgo_build": "True", 10:07:02 INFO - "basedir": "/builds/slave/test-pgo", 10:07:02 INFO - "project": "", 10:07:02 INFO - "platform": "ubuntu64_hw", 10:07:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:07:02 INFO - "slavebuilddir": "test", 10:07:02 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:07:02 INFO - "branch": "mozilla-beta", 10:07:02 INFO - "repo_path": "releases/mozilla-beta", 10:07:02 INFO - "stage_platform": "linux64-pgo", 10:07:02 INFO - "builduid": "13a8a44a82eb48b49f846c683f7d0bc5", 10:07:02 INFO - "revision": "cc7623105377" 10:07:02 INFO - }, 10:07:02 INFO - "sourcestamp": { 10:07:02 INFO - "repository": "", 10:07:02 INFO - "hasPatch": false, 10:07:02 INFO - "project": "", 10:07:02 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:07:02 INFO - "changes": [ 10:07:02 INFO - { 10:07:02 INFO - "category": null, 10:07:02 INFO - "files": [ 10:07:02 INFO - { 10:07:02 INFO - "url": null, 10:07:02 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:07:02 INFO - } 10:07:02 INFO - ], 10:07:02 INFO - "repository": "", 10:07:02 INFO - "rev": "cc7623105377", 10:07:02 INFO - "who": "sendchange", 10:07:02 INFO - "when": 1456855583, 10:07:02 INFO - "number": 7676381, 10:07:02 INFO - "comments": "Bug 1250311 - Update web-platform-tests expected data for mesa changes, a=testonly", 10:07:02 INFO - "project": "", 10:07:02 INFO - "at": "Tue 01 Mar 2016 10:06:23", 10:07:02 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:07:02 INFO - "revlink": "", 10:07:02 INFO - "properties": [ 10:07:02 INFO - [ 10:07:02 INFO - "buildid", 10:07:02 INFO - "20160301073943", 10:07:02 INFO - "Change" 10:07:02 INFO - ], 10:07:02 INFO - [ 10:07:02 INFO - "builduid", 10:07:02 INFO - "13a8a44a82eb48b49f846c683f7d0bc5", 10:07:02 INFO - "Change" 10:07:02 INFO - ], 10:07:02 INFO - [ 10:07:02 INFO - "pgo_build", 10:07:02 INFO - "True", 10:07:02 INFO - "Change" 10:07:02 INFO - ] 10:07:02 INFO - ], 10:07:02 INFO - "revision": "cc7623105377" 10:07:02 INFO - } 10:07:02 INFO - ], 10:07:02 INFO - "revision": "cc7623105377" 10:07:02 INFO - } 10:07:02 INFO - } 10:07:02 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:07:02 INFO - Running post-action listener: _resource_record_post_action 10:07:02 INFO - ##### 10:07:02 INFO - ##### Running download-and-extract step. 10:07:02 INFO - ##### 10:07:02 INFO - Running pre-action listener: _resource_record_pre_action 10:07:02 INFO - Running main action method: download_and_extract 10:07:02 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/test_packages.json 10:07:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:07:02 INFO - Downloaded 1270 bytes. 10:07:02 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:07:02 INFO - Using the following test package requirements: 10:07:02 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:07:02 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:07:02 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'jsshell-linux-x86_64.zip'], 10:07:02 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:07:02 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:07:02 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:07:02 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:07:02 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:07:02 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:07:02 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:07:02 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:07:02 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:02 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:07:02 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:13 INFO - Downloaded 21584415 bytes. 10:07:13 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 10:07:13 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:07:13 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 10:07:14 INFO - Return code: 0 10:07:14 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 10:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:07:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:14 INFO - Downloaded 21584415 bytes. 10:07:14 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 10:07:14 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:07:14 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 10:07:16 INFO - Return code: 0 10:07: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')]} 10:07:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:07:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:23 INFO - Downloaded 11132038 bytes. 10:07:23 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 10:07:23 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:07:23 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 10:07:23 INFO - Return code: 0 10:07:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:23 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:07:23 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/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 10:07:37 INFO - Downloaded 57949386 bytes. 10:07:37 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:07:37 INFO - mkdir: /builds/slave/test-pgo/properties 10:07:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:07:37 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:07:37 INFO - Contents: 10:07:37 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:07:37 INFO - Running post-action listener: _resource_record_post_action 10:07:37 INFO - Running post-action listener: set_extra_try_arguments 10:07:37 INFO - ##### 10:07:37 INFO - ##### Running populate-webroot step. 10:07:37 INFO - ##### 10:07:37 INFO - Running pre-action listener: _resource_record_pre_action 10:07:37 INFO - Running main action method: populate_webroot 10:07:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:07:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:07:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:07:37 INFO - '--tppagecycles', 10:07:37 INFO - '7'], 10:07:37 INFO - 'tests': ['tsvgm']}}, 10:07:37 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:07:37 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:07:37 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:07:37 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:07:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:07:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:07:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'tests': ['damp', 'tps']}, 10:07:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['damp', 'tps']}, 10:07:37 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:07:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:07:37 INFO - 'other': {'tests': ['a11yr', 10:07:37 INFO - 'ts_paint', 10:07:37 INFO - 'tpaint', 10:07:37 INFO - 'sessionrestore', 10:07:37 INFO - 'sessionrestore_no_auto_restore']}, 10:07:37 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['a11yr', 10:07:37 INFO - 'ts_paint', 10:07:37 INFO - 'tpaint', 10:07:37 INFO - 'sessionrestore', 10:07:37 INFO - 'sessionrestore_no_auto_restore']}, 10:07:37 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['a11yr', 10:07:37 INFO - 'ts_paint', 10:07:37 INFO - 'tpaint', 10:07:37 INFO - 'sessionrestore', 10:07:37 INFO - 'sessionrestore_no_auto_restore']}, 10:07:37 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['a11yr', 10:07:37 INFO - 'ts_paint', 10:07:37 INFO - 'tpaint', 10:07:37 INFO - 'sessionrestore', 10:07:37 INFO - 'sessionrestore_no_auto_restore']}, 10:07:37 INFO - 'other_l64': {'tests': ['a11yr', 10:07:37 INFO - 'ts_paint', 10:07:37 INFO - 'tpaint', 10:07:37 INFO - 'sessionrestore', 10:07:37 INFO - 'sessionrestore_no_auto_restore']}, 10:07:37 INFO - 'other_nol64': {'tests': ['a11yr', 10:07:37 INFO - 'ts_paint', 10:07:37 INFO - 'tpaint', 10:07:37 INFO - 'sessionrestore', 10:07:37 INFO - 'sessionrestore_no_auto_restore']}, 10:07:37 INFO - 'svgr': {'tests': ['tsvgx', 10:07:37 INFO - 'tsvgr_opacity', 10:07:37 INFO - 'tart', 10:07:37 INFO - 'tscrollx', 10:07:37 INFO - 'cart']}, 10:07:37 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['tsvgx', 10:07:37 INFO - 'tsvgr_opacity', 10:07:37 INFO - 'tart', 10:07:37 INFO - 'tscrollx', 10:07:37 INFO - 'cart']}, 10:07:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'tests': ['tp5o']}, 10:07:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'talos_options': ['--e10s'], 10:07:37 INFO - 'tests': ['tp5o']}, 10:07:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'talos_options': ['--xperf_path', 10:07:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:07:37 INFO - 'tests': ['tp5n']}, 10:07:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:07:37 INFO - 'talos_options': ['--e10s', 10:07:37 INFO - '--xperf_path', 10:07:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:07:37 INFO - 'tests': ['tp5n']}}, 10:07:37 INFO - 'talos.zip': {'path': '', 10:07:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:07:37 INFO - Running post-action listener: _resource_record_post_action 10:07:37 INFO - ##### 10:07:37 INFO - ##### Running create-virtualenv step. 10:07:37 INFO - ##### 10:07:37 INFO - Running pre-action listener: _resource_record_pre_action 10:07:37 INFO - Running main action method: create_virtualenv 10:07:37 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:07:37 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 10:07:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:07:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:07:37 INFO - Using real prefix '/usr' 10:07:37 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:07:38 INFO - Installing distribute.............................................................................................................................................................................................done. 10:07:40 INFO - Installing pip.................done. 10:07:40 INFO - Return code: 0 10:07:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:07:40 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')]} 10:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:40 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')]} 10:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:40 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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:40 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 10:07:40 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 10:07:40 INFO - Using env: {'DISPLAY': ':0', 10:07:40 INFO - 'HOME': '/home/cltbld', 10:07:40 INFO - 'LANG': 'en_US.UTF-8', 10:07:40 INFO - 'LANGUAGE': 'en_US:en', 10:07:40 INFO - 'LOGNAME': 'cltbld', 10:07:40 INFO - 'MAIL': '/var/mail/cltbld', 10:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:40 INFO - 'MOZ_NO_REMOTE': '1', 10:07:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:40 INFO - 'NO_EM_RESTART': '1', 10:07:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:40 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:40 INFO - 'SHELL': '/bin/bash', 10:07:40 INFO - 'SHLVL': '1', 10:07:40 INFO - 'TERM': 'linux', 10:07:40 INFO - 'TMOUT': '86400', 10:07:40 INFO - 'USER': 'cltbld', 10:07:40 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:40 INFO - '_': '/tools/buildbot/bin/python'} 10:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:40 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 10:07:40 INFO - Cleaning up... 10:07:40 INFO - Return code: 0 10:07:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:07:40 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')]} 10:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:40 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')]} 10:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:40 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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:40 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 10:07:40 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 10:07:40 INFO - Using env: {'DISPLAY': ':0', 10:07:40 INFO - 'HOME': '/home/cltbld', 10:07:40 INFO - 'LANG': 'en_US.UTF-8', 10:07:40 INFO - 'LANGUAGE': 'en_US:en', 10:07:40 INFO - 'LOGNAME': 'cltbld', 10:07:40 INFO - 'MAIL': '/var/mail/cltbld', 10:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:40 INFO - 'MOZ_NO_REMOTE': '1', 10:07:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:40 INFO - 'NO_EM_RESTART': '1', 10:07:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:40 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:40 INFO - 'SHELL': '/bin/bash', 10:07:40 INFO - 'SHLVL': '1', 10:07:40 INFO - 'TERM': 'linux', 10:07:40 INFO - 'TMOUT': '86400', 10:07:40 INFO - 'USER': 'cltbld', 10:07:40 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:40 INFO - '_': '/tools/buildbot/bin/python'} 10:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:40 INFO - Downloading/unpacking psutil>=0.7.1 10:07:40 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 10:07:40 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 10:07:40 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 10:07:40 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 10:07:40 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 10:07:40 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 10:07:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:07:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:07:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:07:42 INFO - Installing collected packages: psutil 10:07:42 INFO - Running setup.py install for psutil 10:07:42 INFO - building 'psutil._psutil_linux' extension 10:07:42 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 10:07:42 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 10:07:42 INFO - building 'psutil._psutil_posix' extension 10:07:42 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 10:07:42 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 10:07:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:07:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:07:42 INFO - Successfully installed psutil 10:07:42 INFO - Cleaning up... 10:07:43 INFO - Return code: 0 10:07:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:07: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')]} 10:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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')]} 10:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07: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', '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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 10:07: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 mozsystemmonitor==0.0.0 10:07:43 INFO - Using env: {'DISPLAY': ':0', 10:07:43 INFO - 'HOME': '/home/cltbld', 10:07:43 INFO - 'LANG': 'en_US.UTF-8', 10:07:43 INFO - 'LANGUAGE': 'en_US:en', 10:07:43 INFO - 'LOGNAME': 'cltbld', 10:07:43 INFO - 'MAIL': '/var/mail/cltbld', 10:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:43 INFO - 'MOZ_NO_REMOTE': '1', 10:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:43 INFO - 'NO_EM_RESTART': '1', 10:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:43 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:43 INFO - 'SHELL': '/bin/bash', 10:07:43 INFO - 'SHLVL': '1', 10:07:43 INFO - 'TERM': 'linux', 10:07:43 INFO - 'TMOUT': '86400', 10:07:43 INFO - 'USER': 'cltbld', 10:07:43 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:43 INFO - '_': '/tools/buildbot/bin/python'} 10:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:07:43 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 10:07:43 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 10:07:43 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 10:07:43 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 10:07:43 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 10:07:43 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 10:07:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:07:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:07:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:07:45 INFO - Installing collected packages: mozsystemmonitor 10:07:45 INFO - Running setup.py install for mozsystemmonitor 10:07:45 INFO - Successfully installed mozsystemmonitor 10:07:45 INFO - Cleaning up... 10:07:45 INFO - Return code: 0 10:07:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:07:45 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')]} 10:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:45 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')]} 10:07:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:45 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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:45 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 10:07:45 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 10:07:45 INFO - Using env: {'DISPLAY': ':0', 10:07:45 INFO - 'HOME': '/home/cltbld', 10:07:45 INFO - 'LANG': 'en_US.UTF-8', 10:07:45 INFO - 'LANGUAGE': 'en_US:en', 10:07:45 INFO - 'LOGNAME': 'cltbld', 10:07:45 INFO - 'MAIL': '/var/mail/cltbld', 10:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:45 INFO - 'MOZ_NO_REMOTE': '1', 10:07:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:45 INFO - 'NO_EM_RESTART': '1', 10:07:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:45 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:45 INFO - 'SHELL': '/bin/bash', 10:07:45 INFO - 'SHLVL': '1', 10:07:45 INFO - 'TERM': 'linux', 10:07:45 INFO - 'TMOUT': '86400', 10:07:45 INFO - 'USER': 'cltbld', 10:07:45 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:45 INFO - '_': '/tools/buildbot/bin/python'} 10:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:45 INFO - Downloading/unpacking blobuploader==1.2.4 10:07:45 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 10:07:45 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 10:07:45 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 10:07:45 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 10:07:45 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 10:07:45 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 10:07:47 INFO - Downloading blobuploader-1.2.4.tar.gz 10:07:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:07:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:07:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 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 10:07:47 INFO - Downloading docopt-0.6.1.tar.gz 10:07:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:07:48 INFO - Installing collected packages: blobuploader, requests, docopt 10:07:48 INFO - Running setup.py install for blobuploader 10:07:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:07:48 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:07:48 INFO - Running setup.py install for requests 10:07:48 INFO - Running setup.py install for docopt 10:07:48 INFO - Successfully installed blobuploader requests docopt 10:07:48 INFO - Cleaning up... 10:07:49 INFO - Return code: 0 10:07:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 10:07:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:07:49 INFO - Using env: {'DISPLAY': ':0', 10:07:49 INFO - 'HOME': '/home/cltbld', 10:07:49 INFO - 'LANG': 'en_US.UTF-8', 10:07:49 INFO - 'LANGUAGE': 'en_US:en', 10:07:49 INFO - 'LOGNAME': 'cltbld', 10:07:49 INFO - 'MAIL': '/var/mail/cltbld', 10:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:49 INFO - 'MOZ_NO_REMOTE': '1', 10:07:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:49 INFO - 'NO_EM_RESTART': '1', 10:07:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:49 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:49 INFO - 'SHELL': '/bin/bash', 10:07:49 INFO - 'SHLVL': '1', 10:07:49 INFO - 'TERM': 'linux', 10:07:49 INFO - 'TMOUT': '86400', 10:07:49 INFO - 'USER': 'cltbld', 10:07:49 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:49 INFO - '_': '/tools/buildbot/bin/python'} 10:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:07:49 INFO - Running setup.py (path:/tmp/pip-yTU4Co-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:07:49 INFO - Running setup.py (path:/tmp/pip-pgKWMC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:07:49 INFO - Running setup.py (path:/tmp/pip-UhgBc3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:07:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:07:49 INFO - Running setup.py (path:/tmp/pip-1tsQ4D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:07:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:07:50 INFO - Running setup.py (path:/tmp/pip-VO0b0D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:07:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:07:50 INFO - Running setup.py (path:/tmp/pip-chT83z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:07:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:07:50 INFO - Running setup.py (path:/tmp/pip-aU54Jm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:07:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:07:50 INFO - Running setup.py (path:/tmp/pip-w0deSk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:07:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:07:50 INFO - Running setup.py (path:/tmp/pip-Sipec3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:07:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:07:50 INFO - Running setup.py (path:/tmp/pip-E3LElX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:07:51 INFO - Running setup.py (path:/tmp/pip-0rnqpI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:07:51 INFO - Running setup.py (path:/tmp/pip-Oz8Eb0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:07:51 INFO - Running setup.py (path:/tmp/pip-ghC6B0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:07:51 INFO - Running setup.py (path:/tmp/pip-sF5NyE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:07:51 INFO - Running setup.py (path:/tmp/pip-rHe2mW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:07:51 INFO - Running setup.py (path:/tmp/pip-VaGxfK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:07:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:07:51 INFO - Running setup.py (path:/tmp/pip-3a5JB_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:07:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:07:51 INFO - Running setup.py install for manifestparser 10:07:51 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:07:51 INFO - Running setup.py install for mozcrash 10:07:51 INFO - Running setup.py install for mozdebug 10:07:51 INFO - Running setup.py install for mozdevice 10:07:51 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:07:51 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:07:51 INFO - Running setup.py install for mozfile 10:07:52 INFO - Running setup.py install for mozhttpd 10:07:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for mozinfo 10:07:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for mozInstall 10:07:52 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for mozleak 10:07:52 INFO - Running setup.py install for mozlog 10:07:52 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for moznetwork 10:07:52 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for mozprocess 10:07:52 INFO - Running setup.py install for mozprofile 10:07:52 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for mozrunner 10:07:52 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:07:52 INFO - Running setup.py install for mozscreenshot 10:07:53 INFO - Running setup.py install for moztest 10:07:53 INFO - Running setup.py install for mozversion 10:07:53 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:07:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:07:53 INFO - Cleaning up... 10:07:53 INFO - Return code: 0 10:07:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:07:53 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')]} 10:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:53 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')]} 10:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:53 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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:53 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 10:07:53 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 10:07:53 INFO - Using env: {'DISPLAY': ':0', 10:07:53 INFO - 'HOME': '/home/cltbld', 10:07:53 INFO - 'LANG': 'en_US.UTF-8', 10:07:53 INFO - 'LANGUAGE': 'en_US:en', 10:07:53 INFO - 'LOGNAME': 'cltbld', 10:07:53 INFO - 'MAIL': '/var/mail/cltbld', 10:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:53 INFO - 'MOZ_NO_REMOTE': '1', 10:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:53 INFO - 'NO_EM_RESTART': '1', 10:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:53 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:53 INFO - 'SHELL': '/bin/bash', 10:07:53 INFO - 'SHLVL': '1', 10:07:53 INFO - 'TERM': 'linux', 10:07:53 INFO - 'TMOUT': '86400', 10:07:53 INFO - 'USER': 'cltbld', 10:07:53 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:53 INFO - '_': '/tools/buildbot/bin/python'} 10:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:07:53 INFO - Running setup.py (path:/tmp/pip-Ea_rhz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:07:53 INFO - Running setup.py (path:/tmp/pip-Mkc5MB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:07:53 INFO - Running setup.py (path:/tmp/pip-BT8fF0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:07:53 INFO - Running setup.py (path:/tmp/pip-kQY4I5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:07:53 INFO - Running setup.py (path:/tmp/pip-uGbCDU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:07:53 INFO - Running setup.py (path:/tmp/pip-lpVZiQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 10:07:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:07:53 INFO - Running setup.py (path:/tmp/pip-UtiwK3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:07:54 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)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:07:54 INFO - Running setup.py (path:/tmp/pip-69boat-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:07:54 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)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:07:54 INFO - Running setup.py (path:/tmp/pip-oUr3sq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:07:54 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)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:07:54 INFO - Running setup.py (path:/tmp/pip-_11bfx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:07:54 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)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:07:54 INFO - Running setup.py (path:/tmp/pip-3O45CG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:07:54 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)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:07:54 INFO - Running setup.py (path:/tmp/pip-RtuFxs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:07:54 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)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:07:54 INFO - Running setup.py (path:/tmp/pip-27GcDa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:07:54 INFO - Running setup.py (path:/tmp/pip-A1J_fc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:07:54 INFO - Running setup.py (path:/tmp/pip-6fJwnH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:07:54 INFO - Running setup.py (path:/tmp/pip-ks6myH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 10:07:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:07:54 INFO - Running setup.py (path:/tmp/pip-WvGyld-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:07:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:07:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:56 INFO - Downloading blessings-1.6.tar.gz 10:07:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:07:56 INFO - Installing collected packages: blessings 10:07:56 INFO - Running setup.py install for blessings 10:07:56 INFO - Successfully installed blessings 10:07:56 INFO - Cleaning up... 10:07:56 INFO - Return code: 0 10:07:56 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:07:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:07:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:07:57 INFO - Reading from file tmpfile_stdout 10:07:57 INFO - Current package versions: 10:07:57 INFO - argparse == 1.2.1 10:07:57 INFO - blessings == 1.6 10:07:57 INFO - blobuploader == 1.2.4 10:07:57 INFO - docopt == 0.6.1 10:07:57 INFO - manifestparser == 1.1 10:07:57 INFO - mozInstall == 1.12 10:07:57 INFO - mozcrash == 0.16 10:07:57 INFO - mozdebug == 0.1 10:07:57 INFO - mozdevice == 0.47 10:07:57 INFO - mozfile == 1.2 10:07:57 INFO - mozhttpd == 0.7 10:07:57 INFO - mozinfo == 0.9 10:07:57 INFO - mozleak == 0.1 10:07:57 INFO - mozlog == 3.1 10:07:57 INFO - moznetwork == 0.27 10:07:57 INFO - mozprocess == 0.22 10:07:57 INFO - mozprofile == 0.27 10:07:57 INFO - mozrunner == 6.11 10:07:57 INFO - mozscreenshot == 0.1 10:07:57 INFO - mozsystemmonitor == 0.0 10:07:57 INFO - moztest == 0.7 10:07:57 INFO - mozversion == 1.4 10:07:57 INFO - psutil == 3.1.1 10:07:57 INFO - requests == 1.2.3 10:07:57 INFO - wsgiref == 0.1.2 10:07:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:07:57 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')]} 10:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:57 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')]} 10:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:57 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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:57 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 10:07:57 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 10:07:57 INFO - Using env: {'DISPLAY': ':0', 10:07:57 INFO - 'HOME': '/home/cltbld', 10:07:57 INFO - 'LANG': 'en_US.UTF-8', 10:07:57 INFO - 'LANGUAGE': 'en_US:en', 10:07:57 INFO - 'LOGNAME': 'cltbld', 10:07:57 INFO - 'MAIL': '/var/mail/cltbld', 10:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:57 INFO - 'MOZ_NO_REMOTE': '1', 10:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:57 INFO - 'NO_EM_RESTART': '1', 10:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:57 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:57 INFO - 'SHELL': '/bin/bash', 10:07:57 INFO - 'SHLVL': '1', 10:07:57 INFO - 'TERM': 'linux', 10:07:57 INFO - 'TMOUT': '86400', 10:07:57 INFO - 'USER': 'cltbld', 10:07:57 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:57 INFO - '_': '/tools/buildbot/bin/python'} 10:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 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)) 10:07:57 INFO - Cleaning up... 10:07:57 INFO - Return code: 0 10:07:57 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:07:57 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')]} 10:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:57 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')]} 10:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:57 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 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:57 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 10:07:57 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 10:07:57 INFO - Using env: {'DISPLAY': ':0', 10:07:57 INFO - 'HOME': '/home/cltbld', 10:07:57 INFO - 'LANG': 'en_US.UTF-8', 10:07:57 INFO - 'LANGUAGE': 'en_US:en', 10:07:57 INFO - 'LOGNAME': 'cltbld', 10:07:57 INFO - 'MAIL': '/var/mail/cltbld', 10:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:57 INFO - 'MOZ_NO_REMOTE': '1', 10:07:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:57 INFO - 'NO_EM_RESTART': '1', 10:07:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:57 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:57 INFO - 'SHELL': '/bin/bash', 10:07:57 INFO - 'SHLVL': '1', 10:07:57 INFO - 'TERM': 'linux', 10:07:57 INFO - 'TMOUT': '86400', 10:07:57 INFO - 'USER': 'cltbld', 10:07:57 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:57 INFO - '_': '/tools/buildbot/bin/python'} 10:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:07:57 INFO - Downloading/unpacking jsonschema 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:07:59 INFO - Downloading/unpacking functools32 (from jsonschema) 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07: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 10:07:59 INFO - Downloading functools32-3.2.3-2.tar.gz 10:07:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:07:59 INFO - warning: no files found matching '*.txt' 10:07:59 INFO - no previously-included directories found matching 'build' 10:07:59 INFO - no previously-included directories found matching 'dist' 10:07:59 INFO - no previously-included directories found matching '.git*' 10:07:59 INFO - Installing collected packages: jsonschema, functools32 10:07:59 INFO - Running setup.py install for functools32 10:07:59 INFO - warning: no files found matching '*.txt' 10:07:59 INFO - no previously-included directories found matching 'build' 10:07:59 INFO - no previously-included directories found matching 'dist' 10:07:59 INFO - no previously-included directories found matching '.git*' 10:07:59 INFO - Successfully installed jsonschema functools32 10:07:59 INFO - Cleaning up... 10:07:59 INFO - Return code: 0 10:07:59 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:07:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff905847f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11cc0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, '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': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', '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 10:07:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 10:07:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:07:59 INFO - Using env: {'DISPLAY': ':0', 10:07:59 INFO - 'HOME': '/home/cltbld', 10:07:59 INFO - 'LANG': 'en_US.UTF-8', 10:07:59 INFO - 'LANGUAGE': 'en_US:en', 10:07:59 INFO - 'LOGNAME': 'cltbld', 10:07:59 INFO - 'MAIL': '/var/mail/cltbld', 10:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:07:59 INFO - 'MOZ_NO_REMOTE': '1', 10:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:07:59 INFO - 'NO_EM_RESTART': '1', 10:07:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:07:59 INFO - 'PWD': '/builds/slave/test-pgo', 10:07:59 INFO - 'SHELL': '/bin/bash', 10:07:59 INFO - 'SHLVL': '1', 10:07:59 INFO - 'TERM': 'linux', 10:07:59 INFO - 'TMOUT': '86400', 10:07:59 INFO - 'USER': 'cltbld', 10:07:59 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:07:59 INFO - '_': '/tools/buildbot/bin/python'} 10:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:08:00 INFO - Downloading/unpacking flake8 10:08:00 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 10:08:00 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 10:08:00 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 10:08:00 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 10:08:00 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 10:08:00 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 10:08:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:08:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:08:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:08:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 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 10:08:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:08:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:08:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:08:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:08:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:08:02 INFO - no previously-included directories found matching 'docs/_build' 10:08:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:08:02 INFO - Running setup.py install for pep8 10:08:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:08:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:08:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:08:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:08:02 INFO - no previously-included directories found matching 'docs/_build' 10:08:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:08:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:08:02 INFO - Cleaning up... 10:08:02 INFO - Return code: 0 10:08:02 INFO - Running post-action listener: _resource_record_post_action 10:08:02 INFO - Running post-action listener: _start_resource_monitoring 10:08:02 INFO - Starting resource monitoring. 10:08:02 INFO - ##### 10:08:02 INFO - ##### Running install step. 10:08:02 INFO - ##### 10:08:02 INFO - Running pre-action listener: _resource_record_pre_action 10:08:02 INFO - Running main action method: install 10:08:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:08:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:08:03 INFO - Reading from file tmpfile_stdout 10:08:03 INFO - Detecting whether we're running mozinstall >=1.0... 10:08:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:08:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:08:03 INFO - Reading from file tmpfile_stdout 10:08:03 INFO - Output received: 10:08:03 INFO - Usage: mozinstall [options] installer 10:08:03 INFO - Options: 10:08:03 INFO - -h, --help show this help message and exit 10:08:03 INFO - -d DEST, --destination=DEST 10:08:03 INFO - Directory to install application into. [default: 10:08:03 INFO - "/builds/slave/test-pgo"] 10:08:03 INFO - --app=APP Application being installed. [default: firefox] 10:08:03 INFO - mkdir: /builds/slave/test-pgo/build/application 10:08:03 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'] 10:08:03 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 10:08:17 INFO - Reading from file tmpfile_stdout 10:08:17 INFO - Output received: 10:08:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:08:17 INFO - Running post-action listener: _resource_record_post_action 10:08:17 INFO - ##### 10:08:17 INFO - ##### Running run-tests step. 10:08:17 INFO - ##### 10:08:17 INFO - Running pre-action listener: _resource_record_pre_action 10:08:17 INFO - Running main action method: run_tests 10:08:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:08:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:08:17 INFO - Python 2.7.3 10:08:17 INFO - Return code: 0 10:08:17 INFO - grabbing minidump binary from tooltool 10:08:17 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')]} 10:08:17 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 0x125ae30>, '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 0x125bd30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x125c1c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:08:17 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 10:08:17 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 10:08:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:08:17 INFO - Return code: 0 10:08:17 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:08:17 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:08:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:08:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:08:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:08:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:08:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:08:18 INFO - Return code: 0 10:08: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/1456846783/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:08: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/1456846783/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost 10:08:18 INFO - Using env: {'DISPLAY': ':0', 10:08:18 INFO - 'HOME': '/home/cltbld', 10:08:18 INFO - 'LANG': 'en_US.UTF-8', 10:08:18 INFO - 'LANGUAGE': 'en_US:en', 10:08:18 INFO - 'LOGNAME': 'cltbld', 10:08:18 INFO - 'MAIL': '/var/mail/cltbld', 10:08:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:08:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:08:18 INFO - 'MOZ_NO_REMOTE': '1', 10:08:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:08:18 INFO - 'NO_EM_RESTART': '1', 10:08:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:08:18 INFO - 'PWD': '/builds/slave/test-pgo', 10:08:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:08:18 INFO - 'SHELL': '/bin/bash', 10:08:18 INFO - 'SHLVL': '1', 10:08:18 INFO - 'TERM': 'linux', 10:08:18 INFO - 'TMOUT': '86400', 10:08:18 INFO - 'USER': 'cltbld', 10:08:18 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193', 10:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:08:18 INFO - '_': '/tools/buildbot/bin/python'} 10:08: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/1456846783/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost'] with output_timeout 3600 10:08:18 INFO - mozversion INFO | application_buildid: 20160301073943 10:08:18 INFO - mozversion INFO | application_changeset: cc76231053776149d6dd9874b5a9f38c0914740f 10:08:18 INFO - mozversion INFO | application_display_name: Nightly 10:08:18 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:08:18 INFO - mozversion INFO | application_name: Firefox 10:08:18 INFO - mozversion INFO | application_remotingname: firefox 10:08:18 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:08:18 INFO - mozversion INFO | application_vendor: Mozilla 10:08:18 INFO - mozversion INFO | application_version: 45.0 10:08:18 INFO - mozversion INFO | platform_buildid: 20160301073943 10:08:18 INFO - mozversion INFO | platform_changeset: cc76231053776149d6dd9874b5a9f38c0914740f 10:08:18 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:08:18 INFO - mozversion INFO | platform_version: 45.0 10:08:18 INFO - 2016-03-01 10:08:18,991 DEBUG : using testdate: 1456855698 10:08:18 INFO - 2016-03-01 10:08:18,991 DEBUG : actual date: 1456855698 10:08:19 INFO - 2016-03-01 10:08:19,040 INFO : starting webserver on 'localhost:54123' 10:08:19 INFO - 2016-03-01 10:08:19,041 INFO : Starting test suite talos-linux64-ix-036 10:08:19 INFO - 2016-03-01 10:08:19,041 INFO : Starting test dromaeo_css 10:08:19 INFO - 2016-03-01 10:08:19,042 DEBUG : operating with platform_type : linux_ 10:08:19 INFO - 2016-03-01 10:08:19,042 INFO : Initialising browser for dromaeo_css test... 10:08:19 INFO - 2016-03-01 10:08:19,051 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsaMi4i/profile http://localhost:54123/getInfo.html 10:08:22 INFO - 2016-03-01 10:08:22,927 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:08:22 INFO - 2016-03-01 10:08:22,928 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:08:22 INFO - 2016-03-01 10:08:22,928 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:08:22 INFO - 2016-03-01 10:08:22,928 DEBUG : BROWSER_OUTPUT: __metrics 10:08:23 INFO - 2016-03-01 10:08:23,089 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] 10:08:24 INFO - 2016-03-01 10:08:24,413 INFO : Browser initialized. 10:08:24 INFO - 2016-03-01 10:08:24,414 INFO : Running cycle 1/1 for dromaeo_css test... 10:08:24 INFO - 2016-03-01 10:08:24,414 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsaMi4i/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:08:36 INFO - 2016-03-01 10:08:36,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 133722112 10:08:36 INFO - 2016-03-01 10:08:36,049 DEBUG : BROWSER_OUTPUT: 10:09:26 INFO - 2016-03-01 10:09:26,904 DEBUG : BROWSER_OUTPUT: 1456855766902 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] 10:11:35 INFO - 2016-03-01 10:11:35,159 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54123/tests/dromaeo/cssquery-ext.html) 10:11:36 INFO - 2016-03-01 10:11:36,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 257896448 10:11:36 INFO - 2016-03-01 10:11:36,414 DEBUG : BROWSER_OUTPUT: 10:14:38 INFO - 2016-03-01 10:14:38,552 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/dromaeo/cssquery-ext.html (next: http://localhost:54123/tests/dromaeo/cssquery-jquery.html) 10:14:39 INFO - 2016-03-01 10:14:39,114 DEBUG : BROWSER_OUTPUT: RSS: Main: 214179840 10:14:39 INFO - 2016-03-01 10:14:39,114 DEBUG : BROWSER_OUTPUT: 10:17:40 INFO - 2016-03-01 10:17:40,833 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54123/tests/dromaeo/cssquery-mootools.html) 10:17:41 INFO - 2016-03-01 10:17:41,395 DEBUG : BROWSER_OUTPUT: RSS: Main: 219701248 10:17:41 INFO - 2016-03-01 10:17:41,395 DEBUG : BROWSER_OUTPUT: 10:20:43 INFO - 2016-03-01 10:20:43,084 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54123/tests/dromaeo/cssquery-prototype.html) 10:20:43 INFO - 2016-03-01 10:20:43,651 DEBUG : BROWSER_OUTPUT: RSS: Main: 196685824 10:20:43 INFO - 2016-03-01 10:20:43,651 DEBUG : BROWSER_OUTPUT: 10:23:46 INFO - 2016-03-01 10:23:46,071 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54123/tests/dromaeo/cssquery-yui.html) 10:23:46 INFO - 2016-03-01 10:23:46,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 181768192 10:23:46 INFO - 2016-03-01 10:23:46,653 DEBUG : BROWSER_OUTPUT: 10:26:48 INFO - 2016-03-01 10:26:48,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/dromaeo/cssquery-yui.html (next: http://localhost:54123/tests/dromaeo/cssquery-dojo.html) 10:26:49 INFO - 2016-03-01 10:26:49,071 DEBUG : BROWSER_OUTPUT: RSS: Main: 233697280 10:26:49 INFO - 2016-03-01 10:26:49,071 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,071 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:26:49 INFO - 2016-03-01 10:26:49,072 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:26:49 INFO - 2016-03-01 10:26:49,072 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:26:49 INFO - 2016-03-01 10:26:49,072 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:26:49 INFO - 2016-03-01 10:26:49,072 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3271.280318329967;3370.938154481337;3399.711024562912;3399.9150021249466;3440.7935523868564;5503.614746967709;5551.750171242294;5566.332040155182;5573.5819813514045;5600.971995140018;5573.191887176367;5610.214569960209;5610.495055445009;5612.494875461208;5618.297712785902;4911.115999120157;5062.240663900417;5088.643794934356;5143.074246635604;5199.350081239845;5004.224345226496;5065.898682026357;5066.290719299301;5067;5077.238414237857;4994.150994330972;5027.321311622939;5073.162928116856;5114.718690472023;5117.462666420028;5060.468650791688;5085.745712714368;5123.718195499246;5128.692278463304;5147.922781158286;5008.398992120932;5063.088644044071;5063.392392912837;5086;5123.513266239701;5224.373075230958;5290;5375.354957405137;5381.919271210935;5458.099413596752;5916.497097746654;6006.969965150146;6013.098035294697;6015.157877897098;6051.727672254759;6004.0693692477735;6015;6020.337762846083;6023.126646636257;6029.487493563052;5349.732513374316;5454.863628409322;5501.944980550224;5588.944110558883;5594.356648985337;6067.423594758491;6082.361352058058;6089.878202436015;6092.451679348791;6129.816105516842;5909.2908850938165;5913.319968203626;5913.940860591339;5917.378675239124;5919.585629005928;5752.36723960364;5833.649981001154;5868.589198756132;5946.048632218824;5996.640201587918;5966.224390829165;6024.548158888101;6026.367231440723;6026.517878569792;6031.547633927473;5996.550258730613;6013.819585412357;6023.427774361428;6023.939760602426;6025;4491.146682130385;4500.099980003947;4579.7252164870215;4586.747728874877;4609.861704148881;4119.938200926988;4126.09225970286;4137.420761093449;4138.255114079434;4170.04089059518;5445.455454454523;5661.858453538694;5715.199872017921;5719.685417302005;5731.054376027909;5817.854553636193;5867.589268751147;5939.109133629903;5985.760569577168;6004.42957918997;5690.2887139107615;5729.398413166641;5731.140328950691;5735.1684005818925;5735.369109397963;5099.490050994945;5245.6852588844795;5247.658902171346;5250.212468129735;5250.422453530108;6068.302145253264;6128.877422451526;6180.845478863064;6182.629042257389;6184.783532576338;5988.161657367885;5988.820335389945;5989.041753319448;5994.460498555151;6033.245844269466;5989.970050149721;6061;6121.571489995657;6130.2030736004035;6130.877382452415;5745.195672605755;5815.185873977562;5815.738291776795;5817.069268916953;5845.561582881386;5956.851078723066;6028.819135425944;6045.909311360245;6051.062085376773;6051.394860513913;5614.5789065821045;5739.62692424991;5809.2738407699035;5846.035404158266;5919;5960.8807823844145;5963.850903727442;5963.880722385615;5966.820995370146;5967.582269241111;5853.560982926383;5861.648301101947;5876.265466816648;5877.706114694333;5879.441453061952;5596.104623260258;5738.053221218453;5740.770369185186;5770.163326317745;5959.986802243542;5761.39505351932;5789.478946894799;5816.185733997159;5817.534597232296;5835.56233282514 10:26:49 INFO - 2016-03-01 10:26:49,072 DEBUG : BROWSER_OUTPUT: |1;ext.html;563.8533981164896;609.3875654966758;613.1109890658545;621.8631900981783;624.0576729138999;32876;40961.79519102404;40990.38514422286;41054.38418423726;41189.794051029705;45557;45906.31140532895;46061.53938460614;46134.53865461345;46136.538634613644;8079.111297757271;8250.174982501732;9096.954515227431;9280;10491.37051776892;10684.252102352839;10736.463176841127;10806.783864322748;10809.83785243218;10810.405427701473;10832.350058996486;10989.945050274737;11022.118230541577;11023.61417350393;11028.5037173327;10526.157907367393;10609.204309676728;10622.57509699615;10627.14982801374;10632.149428045737;10615.416152111631;10757.946210268978;10758.354498730101;10767.19246056549;10768.946155269252;41964.37053444201;42893;42914.570854291684;42925;42941.785291073655;3390.304987477561;3436.1409647588102;3451.6893479586956;3609.2781443711365;3673.669369756735;32631.836840815882;33485;33503.16242093943;33504.832475837706;33627.15932101692;3778.7165962552917;3779.79211143387;3838.5777564467885;3847.903802404934;3965.643092121694;287734;290989;293039;293231;293348;58295;59695;66310;66786.66606666935;66955.3304466949;70263.64868175627;71291;71537.64231178915;71732;71784;15578;15636.4527241546;15639;15670.843291567166;15791.684166316609;11395.487203075882;11433.771324573629;11482.368469734078;11506.252093613888;11602.825957610643;21129;22272.88863555672;22421.551568968855;22444;22527.662085068736;8549.487030778173;8841.867371989425;8845.911540884637;8862.64549418029;8887;1051.1065594244983;1099.7745462180235;1125.6172901213463;1145.66202970124;1152.3316476443642;29571.408571828444;29705.958523417667;29810;30182.396352073276;30513.69486305153;1096.4079397125622;1122.865256169265;1142.166218660366;1174.3423682737694;1197.7424853656332;14324.856751432353;14662;15237.771433428508;17293.740593891103;17488.737668934977;412739;416934;419750.8024919773;419809;420342;57584;58109.418905811246;58205.417945820845;58225.417745822844;58244.417555824744;1413.7313910357;1494.5964589560758;1499.8800095992513;1509.8565636264536;1510.9773353399708;30630;32552.83723581367;33600.49599256062;33644.49533256954;34385.82807085948;51999.48000519946;53613;53660.46339536711;53702.731486342316;53721.73139134279;256640;257325;259525;259575;260795;42495.57504424916;42697;42731.786341068095;50402;50795.49204508055;44245;44800;45024.77487612541;45750.08499830051;45753.54246457493;39158.804205979926;39640.80179599083;39900.2019959605;40662.39006414847;40935.59064409318;37450.43824342582;43924.14493057941;44391.11217775691;44649.776751116035;44673.32990005217;38450.07749612452;39202.6079739199;39288.803555982035;39554.406683899186;39637.801810991914;34776.30447390988;35168.64831351756;36161.63838361688;36218.4567231497;36697.81651091728;26994.190174294803;27020.459590808467;27081.322966925985;27188.45623087567;27288.590671140348 10:26:49 INFO - 2016-03-01 10:26:49,072 DEBUG : BROWSER_OUTPUT: |2;jquery.html;365.87011610878153;372.4822496729545;373.9551253849539;374.59356598091074;379.58435513113164;15461.381544738197;17038.403655872047;18923.43229703111;18931;19436.90281548592;12649.304288264164;12936.611901642942;12941.15882467639;18562.53593660159;19118.61762764744;4089.5092588889374;5177.974110129454;5308.124159513675;5327.893442131155;5334.146536554152;4145.336746120622;5736.6558006519745;5820.505257053155;5853.151293062503;5870.618409803349;5799.81103873707;5822.272215973004;5827.125931110325;5911.88176236475;6375.808725738212;6258;6276.435120839124;6284.371562843725;6294.464970477469;6304.054391841238;5550.417206193332;5644.5484361251;5649.548036157139;5701.230333904911;5813.88372232553;12965.481380744759;13023.218606883618;13048.739025219442;20751.27370542039;22665.09339626413;2288.942776430586;2309.7574754650855;2319.6288593825075;2412.3969007748065;2431.58663027284;10613.150947924147;10835.512401941853;10891.782164356748;12199.93900030503;14019.64950876226;1889.4898377438153;1942.5855833957592;1996.7304413904083;1999.5301104240637;2011.7988201179912;65530;68360.6581967087;68644.31355686429;69239.30760692379;70494.94257586141;10728.946355268174;10874.45627718611;10883.18376121794;10910.181736369805;10957.616483423042;14640.194789286692;14660.413583456755;14676.633084172981;14761.77857332141;14785.26073696311;7877.960610196912;7923.20767923203;7945.284924356835;7987.600619968978;7992.520448772976;7479.626018699043;7485.5508669480005;7503.66233519482;7509.286617771303;7564.432667549956;4415.359772832921;4470.798814053311;4487.573680500347;4636.4899861015265;4719.764011799396;1772.6367249526215;1833.954151146232;1862.1992543206552;1882.9905850470934;1891.7540719706626;907.5099446299057;971.0386717150112;980.4754456365953;1056.9894301057045;1063.3779239145033;14903.627409314853;16576.419825306057;17049.573760655836;17379.217935192948;17477.213525391133;1008.9243306752023;1024.3546565663733;1029.4749677664295;1032.4837581209395;1057.4765491081964;9786.41281523111;10003.949980250052;10027.699169024942;10066.798664026679;10268.794624107475;604795;616876;619853;625932;627648;89222.55388723015;91678.54160729154;91913.5404322974;93867.53066234762;95004;1171.4845467994055;1196.4855112301793;1203.1397550751255;1206.5053328135423;1222.6148763139583;16208.918955405148;16260.349586016428;16313.59216019609;16520.09139497339;16636.91681541633;37803;38076;38131.80934095312;39454.40818387784;40030.1993960125;51805.150716710945;52760.472395277095;54038.72980635072;54119;59204;10017.14854237398;10129.34159279674;10420.166386689198;10557.472126393803;10559.947200263949;17764.644707105526;17790.37733679315;17891.821081789014;18163.909180454015;18293.725594116364;14819.25903704832;14870.107793532423;15081.924590377417;15104.924475377553;15360.38558457694;14671.55985320442;14723.705525889636;14994.850051499781;15084;15480.380784768484;14345.211013394364;14691.55925322242;15020.399184032516;15713.371465141267;15800.36798528092;10097.343672661253;10134.493275336352;10147.64483243083;10209.795804083727;10243.180545556188;14889.032212906126;14986.400543978554;15031.549053528412;15241.23793810283;15303.540893773205 10:26:49 INFO - 2016-03-01 10:26:49,076 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1559.508754742256;1612.1536193498407;1621.0516847644128;1739.139126132564;1847.8891266524001;7428.591427471487;7747.418943579226;7748.535087894723;7752.457327987043;7755.030621172354;6805.5576387534775;6952.4785641077;7241.493095483318;7451.590162541058;7515.473916825825;2307.826912981525;2310.2029799719153;2343.5898717724417;2344.0155134843408;2346.495503466754;2052.7639321477996;2302.781235782598;2311.2257393773107;2366.2664573982033;2369.5024044950583;2057.855950083495;2291.862488250702;2321.4892723600865;2322.8722420266877;2325.023490134139;2245.360072379372;2248.955020899581;2259.536794957038;2329.114936324203;2337.7077865266842;2265.2411442166895;2283.6117859963847;2302.26327575176;2307.792298693109;2314.67594536765;7545.434092443089;7607.201243869425;7613.733519326797;7656;7682.846343073164;4611.123886461562;4719.315699223627;4719.858404247878;4725.5510726480925;4725.5510726480925;3004.879804807805;3049.862756175977;3051.2676957530257;3052.648945371266;3140.7173354397987;1058.9629362972337;1071.892810718922;1082.4046774274198;1082.951267192978;1118.3848883114254;10224.437655928921;10271.691849244535;10281.588736450532;10318.277720559563;10319.638812641522;4094.6724262058965;4126.051008268055;4244.087521182961;4250.51119121305;4255.468066491689;8860.424072435271;8869.068747781519;8876.8668469973;8879.955600221958;8881.555922203865;4749.453812811502;4764.094821983812;4770.689905156154;4771.188897887368;4773.952260477351;4318.222719910384;4355.912881742347;4362.498312694081;4371.388005679208;4372.6939114261695;3626.4741612466573;3657.104009517685;3664.633536646314;3688.1517251032415;3693.538307711536;1093.6117678224275;1119.921605487608;1129.4691494997337;1129.6611016695124;1131.140333346646;1165.0156464890097;1178.935158566287;1197.7185361440054;1349.9325033748273;1351.3648585164956;7081.964590177016;7110.715571377087;7148.714051437884;7156.463265255023;7169.6773645186095;1151.6775302915198;1155.8786327435666;1170.449888552379;1187.0978056676815;1191.106669997502;5649.4633009864;5793.102069179284;5796.188533605298;5796.304443466726;5799.507041901401;8658.783530411663;8777.561121943878;8958.596863141174;9083.18251357381;9098.090190980849;8854.911450885538;8993.640254389882;8996.550172491348;9001.459912405277;9012.549372531346;7182.850743880954;7241.855162896818;7402.074740657418;7402.111746590336;7418.666160022812;7410.036695229585;7434.7769566913075;7444.255574442513;7476.21499742541;7486.850262994601;8136.75589732309;8192.631331589973;8292.792680183044;8294.958525207336;8462.746117616482;8359.707410240855;8506.404551681322;8513.19124683179;8528.957355213184;8538.957305213435;6722.495812813961;6883.174019117538;6890.758823441155;6907;6909.2399836019;7475.887861682188;7492.51298665585;7493.138289096823;7497.737579184921;7501.399888009056;6152.200213972155;6934.06390137325;7114.679839407343;7128.073350464407;7207.891881621675;6883.586984780929;6906.309369063053;6912.758053468105;6980.232174460907;6985.930140698528;6921.965390173017;7073.080499535027;7088.255733148151;7088.822779430556;7090.4327653788605;6470.450011748866;6473.449756770538;6478.449331806849;6483.222013358427;6523.9347606525225;6389.8722025560155;6609.636469994197;6666.433353165035;6675.098861653614;6716.83207919806 10:26:49 INFO - 2016-03-01 10:26:49,076 DEBUG : BROWSER_OUTPUT: |4;prototype.html;344.82930949180144;353.5968995345306;356.54540460912324;359.12015561873386;364.82488405565346;6035.607685500439;6122.540809439293;6126.571140020201;6134.711710540786;6136.509079273658;5747.827565173041;5859.6777177255235;5878.7648494060295;5936.851578710534;6035.18524999125;4575.107853968478;4607.539246075399;4698.271767875985;4700.788464519105;4703.764811759415;4656.790444430008;4690.9530904690855;4701.083288758693;4705.717656940595;4708.0819240248165;4425.645948324126;4510.323451482271;4654.930176047345;4663.090697314025;4664.997025639487;4542.205114105019;4637.907241855144;4644.512326205733;4663.48701642819;4665.043666048452;4117.176564687044;4117.238310912491;4125.071858831769;4126.463559737215;4129.132882094764;5745.626534275259;5753.395893431171;5753.884922301572;5758.193852860602;5763.769449222026;5384.461553844623;5460.726963651801;5489.9569081874315;5508;5512.118061110244;6442.48460123189;6459.321771214049;6472.708728107246;6473.579217350857;6483.481321494316;4635.860924172281;4636.11913736389;4639.048994956026;4642.489326174118;4649.070185962821;6511.641859697667;6699.899501507482;6700.966495167541;6702.229243636995;6713.2279787824045;4732.550407711262;4738.336632871332;4738.905221895543;4743.928841067318;4755.500672429345;4809.158397280462;4815.783289751969;4816.20532612115;4819.036192761461;4822.734749588806;5482.396936337;5483.972580137074;5486.91769623456;5489.780408783684;5499.50504454593;6350.110984462179;6472.838179045561;6514.576552524166;6523.7064332105165;6536.738530458823;1629.2749726371887;1645.489898131583;1656.942007029748;1657.6021754778767;1659.2450435052021;1103.757173421846;1103.7902798468265;1106.397013627575;1108.1799468393492;1133.625903451859;6330.841728956813;6335.556511044183;6346.746130154742;6351.9682401588625;6358.459530939829;6694.799156025327;6706.295838936939;6711.530192886548;6716.83207919806;6718.664066796739;5954.761809527657;6149.108379284979;6342.904856427157;6346.714397852108;6356.936430635635;3544.131687736521;3660.377735784923;3661.1945372018645;3662.8351724172476;3665.541807274091;6953.304669533006;6992.860142797115;6995.335443132996;7003.92996070043;7004.754833580801;6807.931920680829;6917.204521479994;6929.826754331182;6933.5493192942295;6936.618485983319;6982.685779139951;6985.545939513915;6990.895136572955;6992.230854605989;6992.79021629352;6751.966240168964;6757.831054223684;6758.526903116931;6764.966175169093;6766.18805743314;6494.6103233806125;6653.500987425866;6681.098051763144;6690.02994565795;6699.698513566998;6987.406070484066;6999.440044796506;7004.859902802017;7010.649467526501;7023.192332881784;4590.104929538707;4637.327587499861;4641.280601506772;4642.001969576557;4645.279981602857;6634.203895532567;6783.694733736895;6795.218549866631;6795.728170873307;6800.31996800316;6568;6577.638229897401;6580.473562115115;6583.736650534117;6588.670566471753;6530.510211734044;6532.738690452328;6538.901916471162;6539.869202616016;6559.540832141704;6353.52348573868;6456.7740129095255;6464.5798023128345;6466.0947467355545;6468.6442245676935;6254.6247225166635;6370.681465926593;6451.806445806634;6582.572132811352;6590.868182636416;6282.937170628235;6355.459785918064;6407.583507072025;6426.325235850169;6431.099646049651 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: |5;yui.html;893.7810236492061;912.2610685344869;934.9345545811796;943.8348289049414;956.1633570625703;13838.58484245473;13961.232132232723;14106.153630782148;14196.290185490736;14288.499902503414;13207.471701131968;13284.335783210825;13302.866971330284;13319.334033298344;13319.667008324797;750.3809357280265;757.3297631596049;895.6059333893066;906.0758026812647;909.4725059465494;902.8916530016407;904.5929331800683;909.6907051602453;911.9452832830018;912.0742446416892;776.2509178642608;952.7237101240632;958.0371726414943;958.1424624960656;958.4297343080891;776.7397921696211;938.2634631814027;948.5304774136778;950.3062764182185;950.5484894674975;705.3651713457878;706.159669992707;707.8195060259652;708.9184743754482;709.5387997801419;13665.86334136656;13685;13761.587152385444;13768.793468097987;13902.16587004783;12141.878581214163;13272.004599655065;13404.798928016087;13549.254790986492;13627.454901803916;11870.584529541424;11884.762304753856;12294.139410241287;12344.753104937941;12397.442115104843;3627.764195327322;3666.651668091527;3668.1196512836727;3686.6313368663186;3786.3373909565717;53433;53589.732051339495;53806.92386152255;54011;54271.72864135654;10977.670669879917;11079.833802492969;11136.944315278372;11189.49647265875;11322.547098115983;19090.809091908905;19099.61800764005;19120.33078842234;19137;19154.04229788505;7534.811629709301;7677.462577619513;7864.21357864209;7901.7234396796985;8054.114047454776;4292;4322.330038843984;4347.978260108679;4458.152950939376;4617.191991401492;3181.650018497963;3191.7446604271604;3195.472746996766;3196.4246435641808;3197.8560964756643;570.0821677099866;570.500811789684;571.2202843119195;574.9396313387118;586.8591538030842;676.462212541031;687.0552989639247;687.0964681443885;689.2521614048713;689.4587748617312;8842.91157088421;8862.734117976472;8900.8664870027;9273.072692730808;9385.436873787594;545.5880809988513;627.5262177056293;672.7544446277074;691.9135108111486;712.4514124124395;4385.912281754347;4433.556644335606;4600.838970636011;4620.745858977722;4695.694779839299;63994.36005639977;64354.35645643577;64408.677956609914;64422;64637.676811616584;57042.42957570371;57911.420885790605;58393;58485.70757146187;58517.707411463525;51661;53714;53784.19323710148;53908.46091539113;54139.729301352454;8720.694775682821;8808.603612837565;8818.03001669803;8875.33434992391;9079.54602269897;38283.23433531258;38315.2336953265;39012.609873900896;39654.603453965094;39935.2012959745;46673.2999005022;49177.26234106563;50758.746206268734;51043.23435148402;51254;10518.526666299882;11204.719882003015;11277.830832537682;11289.661310160709;11784.351860647746;13009.154404963647;13019.934900325437;13053.477860885458;13316.201027938354;13831;12608;12702.428390722254;12805.103642745291;12850.228986260854;13542.526011589547;12048.939755301519;12617.93691031539;12735.617931462071;12849.935750321189;12953.935230323788;12506.18709783861;12514.937425312815;12549.686257843627;12716.809247861474;12725.490980360682;10897.509612067635;10953.671389858317;11071.612493562687;11368.94315528417;11775.293482391084;10289.897101028893;11166.944165279447;11267.154963377616;11310.377929213972;11312.717182070184 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1016 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: __startTimestamp1456856809065__endTimestamp 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,078 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5550.58 Median:5761.40 stddev:611.01 (10.6%) stddev-sans-first:586.14 10:26:49 INFO - 2016-03-01 10:26:49,079 DEBUG : BROWSER_OUTPUT: Values: 3271.3 3370.9 3399.7 3399.9 3440.8 5503.6 5551.8 5566.3 5573.6 5601.0 5573.2 5610.2 5610.5 5612.5 5618.3 4911.1 5062.2 5088.6 5143.1 5199.4 5004.2 5065.9 5066.3 5067.0 5077.2 4994.2 5027.3 5073.2 5114.7 5117.5 5060.5 5085.7 5123.7 5128.7 5147.9 5008.4 5063.1 5063.4 5086.0 5123.5 5224.4 5290.0 5375.4 5381.9 5458.1 5916.5 6007.0 6013.1 6015.2 6051.7 6004.1 6015.0 6020.3 6023.1 6029.5 5349.7 5454.9 5501.9 5588.9 5594.4 6067.4 6082.4 6089.9 6092.5 6129.8 5909.3 5913.3 5913.9 5917.4 5919.6 5752.4 5833.6 5868.6 5946.0 5996.6 5966.2 6024.5 6026.4 6026.5 6031.5 5996.6 6013.8 6023.4 6023.9 6025.0 4491.1 4500.1 4579.7 4586.7 4609.9 4119.9 4126.1 4137.4 4138.3 4170.0 5445.5 5661.9 5715.2 5719.7 5731.1 5817.9 5867.6 5939.1 5985.8 6004.4 5690.3 5729.4 5731.1 5735.2 5735.4 5099.5 5245.7 5247.7 5250.2 5250.4 6068.3 6128.9 6180.8 6182.6 6184.8 5988.2 5988.8 5989.0 5994.5 6033.2 5990.0 6061.0 6121.6 6130.2 6130.9 5745.2 5815.2 5815.7 5817.1 5845.6 5956.9 6028.8 6045.9 6051.1 6051.4 5614.6 5739.6 5809.3 5846.0 5919.0 5960.9 5963.9 5963.9 5966.8 5967.6 5853.6 5861.6 5876.3 5877.7 5879.4 5596.1 5738.1 5740.8 5770.2 5960.0 5761.4 5789.5 5816.2 5817.5 5835.6 10:26:49 INFO - 2016-03-01 10:26:49,079 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,079 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:51471.41 Median:34972.48 stddev:86560.55 (247.5%) stddev-sans-first:86718.98 10:26:49 INFO - 2016-03-01 10:26:49,080 DEBUG : BROWSER_OUTPUT: Values: 563.9 609.4 613.1 621.9 624.1 32876.0 40961.8 40990.4 41054.4 41189.8 45557.0 45906.3 46061.5 46134.5 46136.5 8079.1 8250.2 9097.0 9280.0 10491.4 10684.3 10736.5 10806.8 10809.8 10810.4 10832.4 10989.9 11022.1 11023.6 11028.5 10526.2 10609.2 10622.6 10627.1 10632.1 10615.4 10757.9 10758.4 10767.2 10768.9 41964.4 42893.0 42914.6 42925.0 42941.8 3390.3 3436.1 3451.7 3609.3 3673.7 32631.8 33485.0 33503.2 33504.8 33627.2 3778.7 3779.8 3838.6 3847.9 3965.6 287734.0 290989.0 293039.0 293231.0 293348.0 58295.0 59695.0 66310.0 66786.7 66955.3 70263.6 71291.0 71537.6 71732.0 71784.0 15578.0 15636.5 15639.0 15670.8 15791.7 11395.5 11433.8 11482.4 11506.3 11602.8 21129.0 22272.9 22421.6 22444.0 22527.7 8549.5 8841.9 8845.9 8862.6 8887.0 1051.1 1099.8 1125.6 1145.7 1152.3 29571.4 29706.0 29810.0 30182.4 30513.7 1096.4 1122.9 1142.2 1174.3 1197.7 14324.9 14662.0 15237.8 17293.7 17488.7 412739.0 416934.0 419750.8 419809.0 420342.0 57584.0 58109.4 58205.4 58225.4 58244.4 1413.7 1494.6 1499.9 1509.9 1511.0 30630.0 32552.8 33600.5 33644.5 34385.8 51999.5 53613.0 53660.5 53702.7 53721.7 256640.0 257325.0 259525.0 259575.0 260795.0 42495.6 42697.0 42731.8 50402.0 50795.5 44245.0 44800.0 45024.8 45750.1 45753.5 39158.8 39640.8 39900.2 40662.4 40935.6 37450.4 43924.1 44391.1 44649.8 44673.3 38450.1 39202.6 39288.8 39554.4 39637.8 34776.3 35168.6 36161.6 36218.5 36697.8 26994.2 27020.5 27081.3 27188.5 27288.6 10:26:49 INFO - 2016-03-01 10:26:49,080 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,080 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:32219.50 Median:10407.59 stddev:101290.79 (973.2%) stddev-sans-first:101546.70 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: Values: 365.9 372.5 374.0 374.6 379.6 15461.4 17038.4 18923.4 18931.0 19436.9 12649.3 12936.6 12941.2 18562.5 19118.6 4089.5 5178.0 5308.1 5327.9 5334.1 4145.3 5736.7 5820.5 5853.2 5870.6 5799.8 5822.3 5827.1 5911.9 6375.8 6258.0 6276.4 6284.4 6294.5 6304.1 5550.4 5644.5 5649.5 5701.2 5813.9 12965.5 13023.2 13048.7 20751.3 22665.1 2288.9 2309.8 2319.6 2412.4 2431.6 10613.2 10835.5 10891.8 12199.9 14019.6 1889.5 1942.6 1996.7 1999.5 2011.8 65530.0 68360.7 68644.3 69239.3 70494.9 10728.9 10874.5 10883.2 10910.2 10957.6 14640.2 14660.4 14676.6 14761.8 14785.3 7878.0 7923.2 7945.3 7987.6 7992.5 7479.6 7485.6 7503.7 7509.3 7564.4 4415.4 4470.8 4487.6 4636.5 4719.8 1772.6 1834.0 1862.2 1883.0 1891.8 907.5 971.0 980.5 1057.0 1063.4 14903.6 16576.4 17049.6 17379.2 17477.2 1008.9 1024.4 1029.5 1032.5 1057.5 9786.4 10003.9 10027.7 10066.8 10268.8 604795.0 616876.0 619853.0 625932.0 627648.0 89222.6 91678.5 91913.5 93867.5 95004.0 1171.5 1196.5 1203.1 1206.5 1222.6 16208.9 16260.3 16313.6 16520.1 16636.9 37803.0 38076.0 38131.8 39454.4 40030.2 51805.2 52760.5 54038.7 54119.0 59204.0 10017.1 10129.3 10420.2 10557.5 10559.9 17764.6 17790.4 17891.8 18163.9 18293.7 14819.3 14870.1 15081.9 15104.9 15360.4 14671.6 14723.7 14994.9 15084.0 15480.4 14345.2 14691.6 15020.4 15713.4 15800.4 10097.3 10134.5 10147.6 10209.8 10243.2 14889.0 14986.4 15031.5 15241.2 15303.5 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5411.99 Median:6271.04 stddev:2727.80 (43.5%) stddev-sans-first:2720.08 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: Values: 1559.5 1612.2 1621.1 1739.1 1847.9 7428.6 7747.4 7748.5 7752.5 7755.0 6805.6 6952.5 7241.5 7451.6 7515.5 2307.8 2310.2 2343.6 2344.0 2346.5 2052.8 2302.8 2311.2 2366.3 2369.5 2057.9 2291.9 2321.5 2322.9 2325.0 2245.4 2249.0 2259.5 2329.1 2337.7 2265.2 2283.6 2302.3 2307.8 2314.7 7545.4 7607.2 7613.7 7656.0 7682.8 4611.1 4719.3 4719.9 4725.6 4725.6 3004.9 3049.9 3051.3 3052.6 3140.7 1059.0 1071.9 1082.4 1083.0 1118.4 10224.4 10271.7 10281.6 10318.3 10319.6 4094.7 4126.1 4244.1 4250.5 4255.5 8860.4 8869.1 8876.9 8880.0 8881.6 4749.5 4764.1 4770.7 4771.2 4774.0 4318.2 4355.9 4362.5 4371.4 4372.7 3626.5 3657.1 3664.6 3688.2 3693.5 1093.6 1119.9 1129.5 1129.7 1131.1 1165.0 1178.9 1197.7 1349.9 1351.4 7082.0 7110.7 7148.7 7156.5 7169.7 1151.7 1155.9 1170.4 1187.1 1191.1 5649.5 5793.1 5796.2 5796.3 5799.5 8658.8 8777.6 8958.6 9083.2 9098.1 8854.9 8993.6 8996.6 9001.5 9012.5 7182.9 7241.9 7402.1 7402.1 7418.7 7410.0 7434.8 7444.3 7476.2 7486.9 8136.8 8192.6 8292.8 8295.0 8462.7 8359.7 8506.4 8513.2 8529.0 8539.0 6722.5 6883.2 6890.8 6907.0 6909.2 7475.9 7492.5 7493.1 7497.7 7501.4 6152.2 6934.1 7114.7 7128.1 7207.9 6883.6 6906.3 6912.8 6980.2 6985.9 6922.0 7073.1 7088.3 7088.8 7090.4 6470.5 6473.4 6478.4 6483.2 6523.9 6389.9 6609.6 6666.4 6675.1 6716.8 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5453.70 Median:6142.81 stddev:1637.20 (26.7%) stddev-sans-first:1596.26 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: Values: 344.8 353.6 356.5 359.1 364.8 6035.6 6122.5 6126.6 6134.7 6136.5 5747.8 5859.7 5878.8 5936.9 6035.2 4575.1 4607.5 4698.3 4700.8 4703.8 4656.8 4691.0 4701.1 4705.7 4708.1 4425.6 4510.3 4654.9 4663.1 4665.0 4542.2 4637.9 4644.5 4663.5 4665.0 4117.2 4117.2 4125.1 4126.5 4129.1 5745.6 5753.4 5753.9 5758.2 5763.8 5384.5 5460.7 5490.0 5508.0 5512.1 6442.5 6459.3 6472.7 6473.6 6483.5 4635.9 4636.1 4639.0 4642.5 4649.1 6511.6 6699.9 6701.0 6702.2 6713.2 4732.6 4738.3 4738.9 4743.9 4755.5 4809.2 4815.8 4816.2 4819.0 4822.7 5482.4 5484.0 5486.9 5489.8 5499.5 6350.1 6472.8 6514.6 6523.7 6536.7 1629.3 1645.5 1656.9 1657.6 1659.2 1103.8 1103.8 1106.4 1108.2 1133.6 6330.8 6335.6 6346.7 6352.0 6358.5 6694.8 6706.3 6711.5 6716.8 6718.7 5954.8 6149.1 6342.9 6346.7 6356.9 3544.1 3660.4 3661.2 3662.8 3665.5 6953.3 6992.9 6995.3 7003.9 7004.8 6807.9 6917.2 6929.8 6933.5 6936.6 6982.7 6985.5 6990.9 6992.2 6992.8 6752.0 6757.8 6758.5 6765.0 6766.2 6494.6 6653.5 6681.1 6690.0 6699.7 6987.4 6999.4 7004.9 7010.6 7023.2 4590.1 4637.3 4641.3 4642.0 4645.3 6634.2 6783.7 6795.2 6795.7 6800.3 6568.0 6577.6 6580.5 6583.7 6588.7 6530.5 6532.7 6538.9 6539.9 6559.5 6353.5 6456.8 6464.6 6466.1 6468.6 6254.6 6370.7 6451.8 6582.6 6590.9 6282.9 6355.5 6407.6 6426.3 6431.1 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:15245.43 Median:4529.50 stddev:18008.44 (397.6%) stddev-sans-first:18026.71 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: Values: 893.8 912.3 934.9 943.8 956.2 13838.6 13961.2 14106.2 14196.3 14288.5 13207.5 13284.3 13302.9 13319.3 13319.7 750.4 757.3 895.6 906.1 909.5 902.9 904.6 909.7 911.9 912.1 776.3 952.7 958.0 958.1 958.4 776.7 938.3 948.5 950.3 950.5 705.4 706.2 707.8 708.9 709.5 13665.9 13685.0 13761.6 13768.8 13902.2 12141.9 13272.0 13404.8 13549.3 13627.5 11870.6 11884.8 12294.1 12344.8 12397.4 3627.8 3666.7 3668.1 3686.6 3786.3 53433.0 53589.7 53806.9 54011.0 54271.7 10977.7 11079.8 11136.9 11189.5 11322.5 19090.8 19099.6 19120.3 19137.0 19154.0 7534.8 7677.5 7864.2 7901.7 8054.1 4292.0 4322.3 4348.0 4458.2 4617.2 3181.7 3191.7 3195.5 3196.4 3197.9 570.1 570.5 571.2 574.9 586.9 676.5 687.1 687.1 689.3 689.5 8842.9 8862.7 8900.9 9273.1 9385.4 545.6 627.5 672.8 691.9 712.5 4385.9 4433.6 4600.8 4620.7 4695.7 63994.4 64354.4 64408.7 64422.0 64637.7 57042.4 57911.4 58393.0 58485.7 58517.7 51661.0 53714.0 53784.2 53908.5 54139.7 8720.7 8808.6 8818.0 8875.3 9079.5 38283.2 38315.2 39012.6 39654.6 39935.2 46673.3 49177.3 50758.7 51043.2 51254.0 10518.5 11204.7 11277.8 11289.7 11784.4 13009.2 13019.9 13053.5 13316.2 13831.0 12608.0 12702.4 12805.1 12850.2 13542.5 12048.9 12617.9 12735.6 12849.9 12953.9 12506.2 12514.9 12549.7 12716.8 12725.5 10897.5 10953.7 11071.6 11368.9 11775.3 10289.9 11166.9 11267.2 11310.4 11312.7 10:26:49 INFO - 2016-03-01 10:26:49,082 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:26:49 INFO - 2016-03-01 10:26:49,083 DEBUG : BROWSER_OUTPUT: 10:26:49 INFO - 2016-03-01 10:26:49,645 INFO : Browser exited with error code: 0 10:26:49 INFO - 2016-03-01 10:26:49,662 INFO : Completed test dromaeo_css (00:18:30) 10:26:49 INFO - 2016-03-01 10:26:49,662 INFO : Starting test kraken 10:26:49 INFO - 2016-03-01 10:26:49,662 DEBUG : operating with platform_type : linux_ 10:26:49 INFO - 2016-03-01 10:26:49,662 INFO : Initialising browser for kraken test... 10:26:49 INFO - 2016-03-01 10:26:49,668 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0gu2Cv/profile http://localhost:54123/getInfo.html 10:26:53 INFO - 2016-03-01 10:26:53,603 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:26:53 INFO - 2016-03-01 10:26:53,604 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:26:53 INFO - 2016-03-01 10:26:53,604 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:26:53 INFO - 2016-03-01 10:26:53,604 DEBUG : BROWSER_OUTPUT: __metrics 10:26:53 INFO - 2016-03-01 10:26:53,801 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] 10:26:54 INFO - 2016-03-01 10:26:54,641 INFO : Browser initialized. 10:26:54 INFO - 2016-03-01 10:26:54,642 INFO : Running cycle 1/1 for kraken test... 10:26:54 INFO - 2016-03-01 10:26:54,642 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0gu2Cv/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:27:06 INFO - 2016-03-01 10:27:06,326 DEBUG : BROWSER_OUTPUT: RSS: Main: 134639616 10:27:06 INFO - 2016-03-01 10:27:06,326 DEBUG : BROWSER_OUTPUT: 10:27:08 INFO - 2016-03-01 10:27:08,782 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54123/tests/kraken/driver.html?testName=audio-beat-detection) 10:27:09 INFO - 2016-03-01 10:27:09,348 DEBUG : BROWSER_OUTPUT: RSS: Main: 166522880 10:27:09 INFO - 2016-03-01 10:27:09,349 DEBUG : BROWSER_OUTPUT: 10:27:11 INFO - 2016-03-01 10:27:11,931 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54123/tests/kraken/driver.html?testName=audio-dft) 10:27:12 INFO - 2016-03-01 10:27:12,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 162766848 10:27:12 INFO - 2016-03-01 10:27:12,499 DEBUG : BROWSER_OUTPUT: 10:27:15 INFO - 2016-03-01 10:27:15,292 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54123/tests/kraken/driver.html?testName=audio-fft) 10:27:15 INFO - 2016-03-01 10:27:15,855 DEBUG : BROWSER_OUTPUT: RSS: Main: 160382976 10:27:15 INFO - 2016-03-01 10:27:15,855 DEBUG : BROWSER_OUTPUT: 10:27:17 INFO - 2016-03-01 10:27:17,822 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54123/tests/kraken/driver.html?testName=audio-oscillator) 10:27:18 INFO - 2016-03-01 10:27:18,383 DEBUG : BROWSER_OUTPUT: RSS: Main: 162594816 10:27:18 INFO - 2016-03-01 10:27:18,383 DEBUG : BROWSER_OUTPUT: 10:27:20 INFO - 2016-03-01 10:27:20,583 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54123/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:27:21 INFO - 2016-03-01 10:27:21,145 DEBUG : BROWSER_OUTPUT: RSS: Main: 160112640 10:27:21 INFO - 2016-03-01 10:27:21,145 DEBUG : BROWSER_OUTPUT: 10:27:23 INFO - 2016-03-01 10:27:23,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54123/tests/kraken/driver.html?testName=imaging-darkroom) 10:27:24 INFO - 2016-03-01 10:27:24,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 159346688 10:27:24 INFO - 2016-03-01 10:27:24,563 DEBUG : BROWSER_OUTPUT: 10:27:27 INFO - 2016-03-01 10:27:27,894 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54123/tests/kraken/driver.html?testName=imaging-desaturate) 10:27:28 INFO - 2016-03-01 10:27:28,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 159199232 10:27:28 INFO - 2016-03-01 10:27:28,461 DEBUG : BROWSER_OUTPUT: 10:27:31 INFO - 2016-03-01 10:27:31,202 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54123/tests/kraken/driver.html?testName=json-parse-financial) 10:27:31 INFO - 2016-03-01 10:27:31,770 DEBUG : BROWSER_OUTPUT: RSS: Main: 160403456 10:27:31 INFO - 2016-03-01 10:27:31,770 DEBUG : BROWSER_OUTPUT: 10:27:33 INFO - 2016-03-01 10:27:33,342 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54123/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:27:33 INFO - 2016-03-01 10:27:33,905 DEBUG : BROWSER_OUTPUT: RSS: Main: 168595456 10:27:33 INFO - 2016-03-01 10:27:33,905 DEBUG : BROWSER_OUTPUT: 10:27:35 INFO - 2016-03-01 10:27:35,639 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:27:36 INFO - 2016-03-01 10:27:36,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 165195776 10:27:36 INFO - 2016-03-01 10:27:36,233 DEBUG : BROWSER_OUTPUT: 10:27:38 INFO - 2016-03-01 10:27:38,209 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:27:38 INFO - 2016-03-01 10:27:38,782 DEBUG : BROWSER_OUTPUT: RSS: Main: 170340352 10:27:38 INFO - 2016-03-01 10:27:38,782 DEBUG : BROWSER_OUTPUT: 10:27:41 INFO - 2016-03-01 10:27:41,161 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:27:41 INFO - 2016-03-01 10:27:41,745 DEBUG : BROWSER_OUTPUT: RSS: Main: 176373760 10:27:41 INFO - 2016-03-01 10:27:41,745 DEBUG : BROWSER_OUTPUT: 10:27:44 INFO - 2016-03-01 10:27:44,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:27:44 INFO - 2016-03-01 10:27:44,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 174768128 10:27:44 INFO - 2016-03-01 10:27:44,744 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54123/tests/kraken/driver.html?testName=ai-astar) 10:27:46 INFO - 2016-03-01 10:27:46,637 DEBUG : BROWSER_OUTPUT: RSS: Main: 232960000 10:27:46 INFO - 2016-03-01 10:27:46,637 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,637 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:27:46 INFO - 2016-03-01 10:27:46,638 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:27:46 INFO - 2016-03-01 10:27:46,638 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:27:46 INFO - 2016-03-01 10:27:46,638 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:27:46 INFO - 2016-03-01 10:27:46,638 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;109;104;108;109;109;101;109;109;110 10:27:46 INFO - 2016-03-01 10:27:46,638 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;120;149;157;123;147;150;123;161;121 10:27:46 INFO - 2016-03-01 10:27:46,639 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;161;160;160;160;160;162;160;160 10:27:46 INFO - 2016-03-01 10:27:46,639 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;71;78;152;71;71;71;71;152;70 10:27:46 INFO - 2016-03-01 10:27:46,639 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;138;92;93;91;139;92;92;133 10:27:46 INFO - 2016-03-01 10:27:46,639 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;109;109 10:27:46 INFO - 2016-03-01 10:27:46,639 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;146;145;147;145;145;145;145 10:27:46 INFO - 2016-03-01 10:27:46,639 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;95;95;95;95;95;95;96;96 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;56;56;56;57;58;60;56;58;55 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;63;64;64;65;64;64;64;64 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;77;85;85;77;77;77;76;77;84 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;116;112;109;116;112;119;120;115;126;124 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;139;139;141;140;128;140;139;140;139 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;63;63;63;65;63;63;63;63;64 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,880 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:27:46 INFO - 2016-03-01 10:27:46,641 DEBUG : BROWSER_OUTPUT: __startTimestamp1456856866637__endTimestamp 10:27:46 INFO - 2016-03-01 10:27:46,642 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.80 Median:109.00 stddev:2.94 (2.7%) stddev-sans-first:3.00 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 104.0 108.0 109.0 109.0 101.0 109.0 109.0 110.0 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.60 Median:149.50 stddev:16.73 (11.2%) stddev-sans-first:16.92 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: Values: 155.0 120.0 149.0 157.0 123.0 147.0 150.0 123.0 161.0 121.0 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,643 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 10:27:46 INFO - 2016-03-01 10:27:46,644 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 161.0 160.0 160.0 160.0 160.0 162.0 160.0 160.0 10:27:46 INFO - 2016-03-01 10:27:46,644 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,644 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.00 Median:71.00 stddev:33.81 (47.6%) stddev-sans-first:35.42 10:27:46 INFO - 2016-03-01 10:27:46,644 DEBUG : BROWSER_OUTPUT: Values: 73.0 71.0 78.0 152.0 71.0 71.0 71.0 71.0 152.0 70.0 10:27:46 INFO - 2016-03-01 10:27:46,644 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,644 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.60 Median:92.00 stddev:21.50 (23.4%) stddev-sans-first:22.32 10:27:46 INFO - 2016-03-01 10:27:46,645 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 138.0 92.0 93.0 91.0 139.0 92.0 92.0 133.0 10:27:46 INFO - 2016-03-01 10:27:46,645 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,645 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 10:27:46 INFO - 2016-03-01 10:27:46,645 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 146.0 145.0 147.0 145.0 145.0 145.0 145.0 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.50 stddev:1.57 (2.8%) stddev-sans-first:1.54 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: Values: 55.0 56.0 56.0 56.0 57.0 58.0 60.0 56.0 58.0 55.0 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:64.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 63.0 64.0 64.0 65.0 64.0 64.0 64.0 64.0 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,646 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:77.00 stddev:3.79 (4.9%) stddev-sans-first:3.94 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: Values: 77.0 77.0 85.0 85.0 77.0 77.0 77.0 76.0 77.0 84.0 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.90 Median:117.50 stddev:5.40 (4.6%) stddev-sans-first:5.72 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: Values: 116.0 112.0 109.0 116.0 112.0 119.0 120.0 115.0 126.0 124.0 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:138.50 Median:140.00 stddev:3.75 (2.7%) stddev-sans-first:3.94 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: Values: 140.0 139.0 139.0 141.0 140.0 128.0 140.0 139.0 140.0 139.0 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: Values: 64.0 63.0 63.0 63.0 65.0 63.0 63.0 63.0 63.0 64.0 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:27:46 INFO - 2016-03-01 10:27:46,647 DEBUG : BROWSER_OUTPUT: 10:27:47 INFO - 2016-03-01 10:27:47,374 INFO : Browser exited with error code: 0 10:27:47 INFO - 2016-03-01 10:27:47,424 INFO : Completed test kraken (00:00:57) 10:27:47 INFO - 2016-03-01 10:27:47,424 INFO : Starting test v8_7 10:27:47 INFO - 2016-03-01 10:27:47,425 DEBUG : operating with platform_type : linux_ 10:27:47 INFO - 2016-03-01 10:27:47,425 INFO : Initialising browser for v8_7 test... 10:27:47 INFO - 2016-03-01 10:27:47,431 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpadjUSG/profile http://localhost:54123/getInfo.html 10:27:51 INFO - 2016-03-01 10:27:51,470 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:27:51 INFO - 2016-03-01 10:27:51,470 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:27:51 INFO - 2016-03-01 10:27:51,471 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:27:51 INFO - 2016-03-01 10:27:51,471 DEBUG : BROWSER_OUTPUT: __metrics 10:27:51 INFO - 2016-03-01 10:27:51,648 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] 10:27:52 INFO - 2016-03-01 10:27:52,529 INFO : Browser initialized. 10:27:52 INFO - 2016-03-01 10:27:52,530 INFO : Running cycle 1/1 for v8_7 test... 10:27:52 INFO - 2016-03-01 10:27:52,530 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpadjUSG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:28:04 INFO - 2016-03-01 10:28:04,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 134533120 10:28:04 INFO - 2016-03-01 10:28:04,185 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,515 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54123/tests/v8_7/run.html (next: http://localhost:54123/tests/v8_7/run.html) 10:28:25 INFO - 2016-03-01 10:28:25,765 DEBUG : BROWSER_OUTPUT: RSS: Main: 424529920 10:28:25 INFO - 2016-03-01 10:28:25,765 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,766 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:28:25 INFO - 2016-03-01 10:28:25,766 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:28:25 INFO - 2016-03-01 10:28:25,766 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:28:25 INFO - 2016-03-01 10:28:25,766 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:28:25 INFO - 2016-03-01 10:28:25,766 DEBUG : BROWSER_OUTPUT: |0;Richards;164.2845408247084 10:28:25 INFO - 2016-03-01 10:28:25,766 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.47368421052633 10:28:25 INFO - 2016-03-01 10:28:25,767 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.473962684489 10:28:25 INFO - 2016-03-01 10:28:25,767 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5148.717948717948 10:28:25 INFO - 2016-03-01 10:28:25,767 DEBUG : BROWSER_OUTPUT: |4;RayTrace;941.6195856873823 10:28:25 INFO - 2016-03-01 10:28:25,767 DEBUG : BROWSER_OUTPUT: |5;Earley;541.4185165132648 10:28:25 INFO - 2016-03-01 10:28:25,767 DEBUG : BROWSER_OUTPUT: |6;Boyer;10547.368421052632 10:28:25 INFO - 2016-03-01 10:28:25,767 DEBUG : BROWSER_OUTPUT: |7;RegExp;23880.95238095238 10:28:25 INFO - 2016-03-01 10:28:25,768 DEBUG : BROWSER_OUTPUT: |8;Splay;533.6179295624333 10:28:25 INFO - 2016-03-01 10:28:25,768 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 10:28:25 INFO - 2016-03-01 10:28:25,768 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:28:25 INFO - 2016-03-01 10:28:25,768 DEBUG : BROWSER_OUTPUT: __startTimestamp1456856905762__endTimestamp 10:28:25 INFO - 2016-03-01 10:28:25,768 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,768 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:28:25 INFO - 2016-03-01 10:28:25,769 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:28:25 INFO - 2016-03-01 10:28:25,769 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,769 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.28 Median:164.28 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,769 DEBUG : BROWSER_OUTPUT: Values: 164.3 10:28:25 INFO - 2016-03-01 10:28:25,769 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,769 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.47 Median:164.47 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,770 DEBUG : BROWSER_OUTPUT: Values: 164.5 10:28:25 INFO - 2016-03-01 10:28:25,770 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,770 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.47 Median:278.47 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,770 DEBUG : BROWSER_OUTPUT: Values: 278.5 10:28:25 INFO - 2016-03-01 10:28:25,770 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,770 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5148.72 Median:5148.72 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: Values: 5148.7 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:941.62 Median:941.62 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: Values: 941.6 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:541.42 Median:541.42 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: Values: 541.4 10:28:25 INFO - 2016-03-01 10:28:25,771 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10547.37 Median:10547.37 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: Values: 10547.4 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:23880.95 Median:23880.95 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: Values: 23881.0 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,772 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:533.62 Median:533.62 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,773 DEBUG : BROWSER_OUTPUT: Values: 533.6 10:28:25 INFO - 2016-03-01 10:28:25,773 DEBUG : BROWSER_OUTPUT: 10:28:25 INFO - 2016-03-01 10:28:25,773 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 10:28:25 INFO - 2016-03-01 10:28:25,773 DEBUG : BROWSER_OUTPUT: Values: 4822.1 10:28:25 INFO - 2016-03-01 10:28:25,773 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:28:25 INFO - 2016-03-01 10:28:25,773 DEBUG : BROWSER_OUTPUT: 10:28:26 INFO - 2016-03-01 10:28:26,505 INFO : Browser exited with error code: 0 10:28:26 INFO - 2016-03-01 10:28:26,522 INFO : Completed test v8_7 (00:00:39) 10:28:26 INFO - 2016-03-01 10:28:26,812 INFO : Completed test suite (00:20:07) 10:28:26 INFO - 2016-03-01 10:28:26,812 DEBUG : Working with test: dromaeo_css 10:28:26 INFO - 2016-03-01 10:28:26,812 DEBUG : Generating results file: dromaeo_css 10:28:26 INFO - 2016-03-01 10:28:26,814 DEBUG : Working with test: kraken 10:28:26 INFO - 2016-03-01 10:28:26,814 DEBUG : Generating results file: kraken 10:28:26 INFO - 2016-03-01 10:28:26,815 INFO : javascript benchmark 10:28:26 INFO - 2016-03-01 10:28:26,815 DEBUG : Working with test: v8_7 10:28:26 INFO - 2016-03-01 10:28:26,815 DEBUG : Generating results file: v8_7 10:28:26 INFO - 2016-03-01 10:28:26,816 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:28:26 INFO - 2016-03-01 10:28:26,868 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:28:26 INFO - 2016-03-01 10:28:26,868 DEBUG : process_Request line: dromaeo_css 7374.77 graph.html#tests=[[72,53,35]] 10:28:26 INFO - 2016-03-01 10:28:26,868 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:28:26 INFO - 2016-03-01 10:28:26,897 DEBUG : process_Request line: kraken 1470.70 graph.html#tests=[[232,53,35]] 10:28:26 INFO - 2016-03-01 10:28:26,897 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:28:26 INFO - 2016-03-01 10:28:26,933 DEBUG : process_Request line: v8_7 23370.00 graph.html#tests=[[230,53,35]] 10:28:26 INFO - 2016-03-01 10:28:26,934 INFO : javascript benchmark 10:28:26 INFO - 2016-03-01 10:28:26,937 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3271.280318329967, 3370.938154481337, 3399.711024562912, 3399.9150021249466, 3440.7935523868564, 5503.614746967709, 5551.750171242294, 5566.332040155182, 5573.5819813514045, 5600.971995140018, 5573.191887176367, 5610.214569960209, 5610.495055445009, 5612.494875461208, 5618.297712785902, 4911.115999120157, 5062.240663900417, 5088.643794934356, 5143.074246635604, 5199.350081239845, 5004.224345226496, 5065.898682026357, 5066.290719299301, 5067.0, 5077.238414237857, 4994.150994330972, 5027.321311622939, 5073.162928116856, 5114.718690472023, 5117.462666420028, 5060.468650791688, 5085.745712714368, 5123.718195499246, 5128.692278463304, 5147.922781158286, 5008.398992120932, 5063.088644044071, 5063.392392912837, 5086.0, 5123.513266239701, 5224.373075230958, 5290.0, 5375.354957405137, 5381.919271210935, 5458.099413596752, 5916.497097746654, 6006.969965150146, 6013.098035294697, 6015.157877897098, 6051.727672254759, 6004.0693692477735, 6015.0, 6020.337762846083, 6023.126646636257, 6029.487493563052, 5349.732513374316, 5454.863628409322, 5501.944980550224, 5588.944110558883, 5594.356648985337, 6067.423594758491, 6082.361352058058, 6089.878202436015, 6092.451679348791, 6129.816105516842, 5909.2908850938165, 5913.319968203626, 5913.940860591339, 5917.378675239124, 5919.585629005928, 5752.36723960364, 5833.649981001154, 5868.589198756132, 5946.048632218824, 5996.640201587918, 5966.224390829165, 6024.548158888101, 6026.367231440723, 6026.517878569792, 6031.547633927473, 5996.550258730613, 6013.819585412357, 6023.427774361428, 6023.939760602426, 6025.0, 4491.146682130385, 4500.099980003947, 4579.7252164870215, 4586.747728874877, 4609.861704148881, 4119.938200926988, 4126.09225970286, 4137.420761093449, 4138.255114079434, 4170.04089059518, 5445.455454454523, 5661.858453538694, 5715.199872017921, 5719.685417302005, 5731.054376027909, 5817.854553636193, 5867.589268751147, 5939.109133629903, 5985.760569577168, 6004.42957918997, 5690.2887139107615, 5729.398413166641, 5731.140328950691, 5735.1684005818925, 5735.369109397963, 5099.490050994945, 5245.6852588844795, 5247.658902171346, 5250.212468129735, 5250.422453530108, 6068.302145253264, 6128.877422451526, 6180.845478863064, 6182.629042257389, 6184.783532576338, 5988.161657367885, 5988.820335389945, 5989.041753319448, 5994.460498555151, 6033.245844269466, 5989.970050149721, 6061.0, 6121.571489995657, 6130.2030736004035, 6130.877382452415, 5745.195672605755, 5815.185873977562, 5815.738291776795, 5817.069268916953, 5845.561582881386, 5956.851078723066, 6028.819135425944, 6045.909311360245, 6051.062085376773, 6051.394860513913, 5614.5789065821045, 5739.62692424991, 5809.2738407699035, 5846.035404158266, 5919.0, 5960.8807823844145, 5963.850903727442, 5963.880722385615, 5966.820995370146, 5967.582269241111, 5853.560982926383, 5861.648301101947, 5876.265466816648, 5877.706114694333, 5879.441453061952, 5596.104623260258, 5738.053221218453, 5740.770369185186, 5770.163326317745, 5959.986802243542, 5761.39505351932, 5789.478946894799, 5816.185733997159, 5817.534597232296, 5835.56233282514], "unit": "ms", "name": "dojo.html", "value": 5511.463726220914}, {"lowerIsBetter": false, "replicates": [563.8533981164896, 609.3875654966758, 613.1109890658545, 621.8631900981783, 624.0576729138999, 32876.0, 40961.79519102404, 40990.38514422286, 41054.38418423726, 41189.794051029705, 45557.0, 45906.31140532895, 46061.53938460614, 46134.53865461345, 46136.538634613644, 8079.111297757271, 8250.174982501732, 9096.954515227431, 9280.0, 10491.37051776892, 10684.252102352839, 10736.463176841127, 10806.783864322748, 10809.83785243218, 10810.405427701473, 10832.350058996486, 10989.945050274737, 11022.118230541577, 11023.61417350393, 11028.5037173327, 10526.157907367393, 10609.204309676728, 10622.57509699615, 10627.14982801374, 10632.149428045737, 10615.416152111631, 10757.946210268978, 10758.354498730101, 10767.19246056549, 10768.946155269252, 41964.37053444201, 42893.0, 42914.570854291684, 42925.0, 42941.785291073655, 3390.304987477561, 3436.1409647588102, 3451.6893479586956, 3609.2781443711365, 3673.669369756735, 32631.836840815882, 33485.0, 33503.16242093943, 33504.832475837706, 33627.15932101692, 3778.7165962552917, 3779.79211143387, 3838.5777564467885, 3847.903802404934, 3965.643092121694, 287734.0, 290989.0, 293039.0, 293231.0, 293348.0, 58295.0, 59695.0, 66310.0, 66786.66606666935, 66955.3304466949, 70263.64868175627, 71291.0, 71537.64231178915, 71732.0, 71784.0, 15578.0, 15636.4527241546, 15639.0, 15670.843291567166, 15791.684166316609, 11395.487203075882, 11433.771324573629, 11482.368469734078, 11506.252093613888, 11602.825957610643, 21129.0, 22272.88863555672, 22421.551568968855, 22444.0, 22527.662085068736, 8549.487030778173, 8841.867371989425, 8845.911540884637, 8862.64549418029, 8887.0, 1051.1065594244983, 1099.7745462180235, 1125.6172901213463, 1145.66202970124, 1152.3316476443642, 29571.408571828444, 29705.958523417667, 29810.0, 30182.396352073276, 30513.69486305153, 1096.4079397125622, 1122.865256169265, 1142.166218660366, 1174.3423682737694, 1197.7424853656332, 14324.856751432353, 14662.0, 15237.771433428508, 17293.740593891103, 17488.737668934977, 412739.0, 416934.0, 419750.8024919773, 419809.0, 420342.0, 57584.0, 58109.418905811246, 58205.417945820845, 58225.417745822844, 58244.417555824744, 1413.7313910357, 1494.5964589560758, 1499.8800095992513, 1509.8565636264536, 1510.9773353399708, 30630.0, 32552.83723581367, 33600.49599256062, 33644.49533256954, 34385.82807085948, 51999.48000519946, 53613.0, 53660.46339536711, 53702.731486342316, 53721.73139134279, 256640.0, 257325.0, 259525.0, 259575.0, 260795.0, 42495.57504424916, 42697.0, 42731.786341068095, 50402.0, 50795.49204508055, 44245.0, 44800.0, 45024.77487612541, 45750.08499830051, 45753.54246457493, 39158.804205979926, 39640.80179599083, 39900.2019959605, 40662.39006414847, 40935.59064409318, 37450.43824342582, 43924.14493057941, 44391.11217775691, 44649.776751116035, 44673.32990005217, 38450.07749612452, 39202.6079739199, 39288.803555982035, 39554.406683899186, 39637.801810991914, 34776.30447390988, 35168.64831351756, 36161.63838361688, 36218.4567231497, 36697.81651091728, 26994.190174294803, 27020.459590808467, 27081.322966925985, 27188.45623087567, 27288.590671140348], "unit": "ms", "name": "ext.html", "value": 20479.635369144984}, {"lowerIsBetter": false, "replicates": [365.87011610878153, 372.4822496729545, 373.9551253849539, 374.59356598091074, 379.58435513113164, 15461.381544738197, 17038.403655872047, 18923.43229703111, 18931.0, 19436.90281548592, 12649.304288264164, 12936.611901642942, 12941.15882467639, 18562.53593660159, 19118.61762764744, 4089.5092588889374, 5177.974110129454, 5308.124159513675, 5327.893442131155, 5334.146536554152, 4145.336746120622, 5736.6558006519745, 5820.505257053155, 5853.151293062503, 5870.618409803349, 5799.81103873707, 5822.272215973004, 5827.125931110325, 5911.88176236475, 6375.808725738212, 6258.0, 6276.435120839124, 6284.371562843725, 6294.464970477469, 6304.054391841238, 5550.417206193332, 5644.5484361251, 5649.548036157139, 5701.230333904911, 5813.88372232553, 12965.481380744759, 13023.218606883618, 13048.739025219442, 20751.27370542039, 22665.09339626413, 2288.942776430586, 2309.7574754650855, 2319.6288593825075, 2412.3969007748065, 2431.58663027284, 10613.150947924147, 10835.512401941853, 10891.782164356748, 12199.93900030503, 14019.64950876226, 1889.4898377438153, 1942.5855833957592, 1996.7304413904083, 1999.5301104240637, 2011.7988201179912, 65530.0, 68360.6581967087, 68644.31355686429, 69239.30760692379, 70494.94257586141, 10728.946355268174, 10874.45627718611, 10883.18376121794, 10910.181736369805, 10957.616483423042, 14640.194789286692, 14660.413583456755, 14676.633084172981, 14761.77857332141, 14785.26073696311, 7877.960610196912, 7923.20767923203, 7945.284924356835, 7987.600619968978, 7992.520448772976, 7479.626018699043, 7485.5508669480005, 7503.66233519482, 7509.286617771303, 7564.432667549956, 4415.359772832921, 4470.798814053311, 4487.573680500347, 4636.4899861015265, 4719.764011799396, 1772.6367249526215, 1833.954151146232, 1862.1992543206552, 1882.9905850470934, 1891.7540719706626, 907.5099446299057, 971.0386717150112, 980.4754456365953, 1056.9894301057045, 1063.3779239145033, 14903.627409314853, 16576.419825306057, 17049.573760655836, 17379.217935192948, 17477.213525391133, 1008.9243306752023, 1024.3546565663733, 1029.4749677664295, 1032.4837581209395, 1057.4765491081964, 9786.41281523111, 10003.949980250052, 10027.699169024942, 10066.798664026679, 10268.794624107475, 604795.0, 616876.0, 619853.0, 625932.0, 627648.0, 89222.55388723015, 91678.54160729154, 91913.5404322974, 93867.53066234762, 95004.0, 1171.4845467994055, 1196.4855112301793, 1203.1397550751255, 1206.5053328135423, 1222.6148763139583, 16208.918955405148, 16260.349586016428, 16313.59216019609, 16520.09139497339, 16636.91681541633, 37803.0, 38076.0, 38131.80934095312, 39454.40818387784, 40030.1993960125, 51805.150716710945, 52760.472395277095, 54038.72980635072, 54119.0, 59204.0, 10017.14854237398, 10129.34159279674, 10420.166386689198, 10557.472126393803, 10559.947200263949, 17764.644707105526, 17790.37733679315, 17891.821081789014, 18163.909180454015, 18293.725594116364, 14819.25903704832, 14870.107793532423, 15081.924590377417, 15104.924475377553, 15360.38558457694, 14671.55985320442, 14723.705525889636, 14994.850051499781, 15084.0, 15480.380784768484, 14345.211013394364, 14691.55925322242, 15020.399184032516, 15713.371465141267, 15800.36798528092, 10097.343672661253, 10134.493275336352, 10147.64483243083, 10209.795804083727, 10243.180545556188, 14889.032212906126, 14986.400543978554, 15031.549053528412, 15241.23793810283, 15303.540893773205], "unit": "ms", "name": "jquery.html", "value": 9421.028229064703}, {"lowerIsBetter": false, "replicates": [1559.508754742256, 1612.1536193498407, 1621.0516847644128, 1739.139126132564, 1847.8891266524001, 7428.591427471487, 7747.418943579226, 7748.535087894723, 7752.457327987043, 7755.030621172354, 6805.5576387534775, 6952.4785641077, 7241.493095483318, 7451.590162541058, 7515.473916825825, 2307.826912981525, 2310.2029799719153, 2343.5898717724417, 2344.0155134843408, 2346.495503466754, 2052.7639321477996, 2302.781235782598, 2311.2257393773107, 2366.2664573982033, 2369.5024044950583, 2057.855950083495, 2291.862488250702, 2321.4892723600865, 2322.8722420266877, 2325.023490134139, 2245.360072379372, 2248.955020899581, 2259.536794957038, 2329.114936324203, 2337.7077865266842, 2265.2411442166895, 2283.6117859963847, 2302.26327575176, 2307.792298693109, 2314.67594536765, 7545.434092443089, 7607.201243869425, 7613.733519326797, 7656.0, 7682.846343073164, 4611.123886461562, 4719.315699223627, 4719.858404247878, 4725.5510726480925, 4725.5510726480925, 3004.879804807805, 3049.862756175977, 3051.2676957530257, 3052.648945371266, 3140.7173354397987, 1058.9629362972337, 1071.892810718922, 1082.4046774274198, 1082.951267192978, 1118.3848883114254, 10224.437655928921, 10271.691849244535, 10281.588736450532, 10318.277720559563, 10319.638812641522, 4094.6724262058965, 4126.051008268055, 4244.087521182961, 4250.51119121305, 4255.468066491689, 8860.424072435271, 8869.068747781519, 8876.8668469973, 8879.955600221958, 8881.555922203865, 4749.453812811502, 4764.094821983812, 4770.689905156154, 4771.188897887368, 4773.952260477351, 4318.222719910384, 4355.912881742347, 4362.498312694081, 4371.388005679208, 4372.6939114261695, 3626.4741612466573, 3657.104009517685, 3664.633536646314, 3688.1517251032415, 3693.538307711536, 1093.6117678224275, 1119.921605487608, 1129.4691494997337, 1129.6611016695124, 1131.140333346646, 1165.0156464890097, 1178.935158566287, 1197.7185361440054, 1349.9325033748273, 1351.3648585164956, 7081.964590177016, 7110.715571377087, 7148.714051437884, 7156.463265255023, 7169.6773645186095, 1151.6775302915198, 1155.8786327435666, 1170.449888552379, 1187.0978056676815, 1191.106669997502, 5649.4633009864, 5793.102069179284, 5796.188533605298, 5796.304443466726, 5799.507041901401, 8658.783530411663, 8777.561121943878, 8958.596863141174, 9083.18251357381, 9098.090190980849, 8854.911450885538, 8993.640254389882, 8996.550172491348, 9001.459912405277, 9012.549372531346, 7182.850743880954, 7241.855162896818, 7402.074740657418, 7402.111746590336, 7418.666160022812, 7410.036695229585, 7434.7769566913075, 7444.255574442513, 7476.21499742541, 7486.850262994601, 8136.75589732309, 8192.631331589973, 8292.792680183044, 8294.958525207336, 8462.746117616482, 8359.707410240855, 8506.404551681322, 8513.19124683179, 8528.957355213184, 8538.957305213435, 6722.495812813961, 6883.174019117538, 6890.758823441155, 6907.0, 6909.2399836019, 7475.887861682188, 7492.51298665585, 7493.138289096823, 7497.737579184921, 7501.399888009056, 6152.200213972155, 6934.06390137325, 7114.679839407343, 7128.073350464407, 7207.891881621675, 6883.586984780929, 6906.309369063053, 6912.758053468105, 6980.232174460907, 6985.930140698528, 6921.965390173017, 7073.080499535027, 7088.255733148151, 7088.822779430556, 7090.4327653788605, 6470.450011748866, 6473.449756770538, 6478.449331806849, 6483.222013358427, 6523.9347606525225, 6389.8722025560155, 6609.636469994197, 6666.433353165035, 6675.098861653614, 6716.83207919806], "unit": "ms", "name": "mootools.html", "value": 4493.842974388487}, {"lowerIsBetter": false, "replicates": [344.82930949180144, 353.5968995345306, 356.54540460912324, 359.12015561873386, 364.82488405565346, 6035.607685500439, 6122.540809439293, 6126.571140020201, 6134.711710540786, 6136.509079273658, 5747.827565173041, 5859.6777177255235, 5878.7648494060295, 5936.851578710534, 6035.18524999125, 4575.107853968478, 4607.539246075399, 4698.271767875985, 4700.788464519105, 4703.764811759415, 4656.790444430008, 4690.9530904690855, 4701.083288758693, 4705.717656940595, 4708.0819240248165, 4425.645948324126, 4510.323451482271, 4654.930176047345, 4663.090697314025, 4664.997025639487, 4542.205114105019, 4637.907241855144, 4644.512326205733, 4663.48701642819, 4665.043666048452, 4117.176564687044, 4117.238310912491, 4125.071858831769, 4126.463559737215, 4129.132882094764, 5745.626534275259, 5753.395893431171, 5753.884922301572, 5758.193852860602, 5763.769449222026, 5384.461553844623, 5460.726963651801, 5489.9569081874315, 5508.0, 5512.118061110244, 6442.48460123189, 6459.321771214049, 6472.708728107246, 6473.579217350857, 6483.481321494316, 4635.860924172281, 4636.11913736389, 4639.048994956026, 4642.489326174118, 4649.070185962821, 6511.641859697667, 6699.899501507482, 6700.966495167541, 6702.229243636995, 6713.2279787824045, 4732.550407711262, 4738.336632871332, 4738.905221895543, 4743.928841067318, 4755.500672429345, 4809.158397280462, 4815.783289751969, 4816.20532612115, 4819.036192761461, 4822.734749588806, 5482.396936337, 5483.972580137074, 5486.91769623456, 5489.780408783684, 5499.50504454593, 6350.110984462179, 6472.838179045561, 6514.576552524166, 6523.7064332105165, 6536.738530458823, 1629.2749726371887, 1645.489898131583, 1656.942007029748, 1657.6021754778767, 1659.2450435052021, 1103.757173421846, 1103.7902798468265, 1106.397013627575, 1108.1799468393492, 1133.625903451859, 6330.841728956813, 6335.556511044183, 6346.746130154742, 6351.9682401588625, 6358.459530939829, 6694.799156025327, 6706.295838936939, 6711.530192886548, 6716.83207919806, 6718.664066796739, 5954.761809527657, 6149.108379284979, 6342.904856427157, 6346.714397852108, 6356.936430635635, 3544.131687736521, 3660.377735784923, 3661.1945372018645, 3662.8351724172476, 3665.541807274091, 6953.304669533006, 6992.860142797115, 6995.335443132996, 7003.92996070043, 7004.754833580801, 6807.931920680829, 6917.204521479994, 6929.826754331182, 6933.5493192942295, 6936.618485983319, 6982.685779139951, 6985.545939513915, 6990.895136572955, 6992.230854605989, 6992.79021629352, 6751.966240168964, 6757.831054223684, 6758.526903116931, 6764.966175169093, 6766.18805743314, 6494.6103233806125, 6653.500987425866, 6681.098051763144, 6690.02994565795, 6699.698513566998, 6987.406070484066, 6999.440044796506, 7004.859902802017, 7010.649467526501, 7023.192332881784, 4590.104929538707, 4637.327587499861, 4641.280601506772, 4642.001969576557, 4645.279981602857, 6634.203895532567, 6783.694733736895, 6795.218549866631, 6795.728170873307, 6800.31996800316, 6568.0, 6577.638229897401, 6580.473562115115, 6583.736650534117, 6588.670566471753, 6530.510211734044, 6532.738690452328, 6538.901916471162, 6539.869202616016, 6559.540832141704, 6353.52348573868, 6456.7740129095255, 6464.5798023128345, 6466.0947467355545, 6468.6442245676935, 6254.6247225166635, 6370.681465926593, 6451.806445806634, 6582.572132811352, 6590.868182636416, 6282.937170628235, 6355.459785918064, 6407.583507072025, 6426.325235850169, 6431.099646049651], "unit": "ms", "name": "prototype.html", "value": 4924.746474069495}, {"lowerIsBetter": false, "replicates": [893.7810236492061, 912.2610685344869, 934.9345545811796, 943.8348289049414, 956.1633570625703, 13838.58484245473, 13961.232132232723, 14106.153630782148, 14196.290185490736, 14288.499902503414, 13207.471701131968, 13284.335783210825, 13302.866971330284, 13319.334033298344, 13319.667008324797, 750.3809357280265, 757.3297631596049, 895.6059333893066, 906.0758026812647, 909.4725059465494, 902.8916530016407, 904.5929331800683, 909.6907051602453, 911.9452832830018, 912.0742446416892, 776.2509178642608, 952.7237101240632, 958.0371726414943, 958.1424624960656, 958.4297343080891, 776.7397921696211, 938.2634631814027, 948.5304774136778, 950.3062764182185, 950.5484894674975, 705.3651713457878, 706.159669992707, 707.8195060259652, 708.9184743754482, 709.5387997801419, 13665.86334136656, 13685.0, 13761.587152385444, 13768.793468097987, 13902.16587004783, 12141.878581214163, 13272.004599655065, 13404.798928016087, 13549.254790986492, 13627.454901803916, 11870.584529541424, 11884.762304753856, 12294.139410241287, 12344.753104937941, 12397.442115104843, 3627.764195327322, 3666.651668091527, 3668.1196512836727, 3686.6313368663186, 3786.3373909565717, 53433.0, 53589.732051339495, 53806.92386152255, 54011.0, 54271.72864135654, 10977.670669879917, 11079.833802492969, 11136.944315278372, 11189.49647265875, 11322.547098115983, 19090.809091908905, 19099.61800764005, 19120.33078842234, 19137.0, 19154.04229788505, 7534.811629709301, 7677.462577619513, 7864.21357864209, 7901.7234396796985, 8054.114047454776, 4292.0, 4322.330038843984, 4347.978260108679, 4458.152950939376, 4617.191991401492, 3181.650018497963, 3191.7446604271604, 3195.472746996766, 3196.4246435641808, 3197.8560964756643, 570.0821677099866, 570.500811789684, 571.2202843119195, 574.9396313387118, 586.8591538030842, 676.462212541031, 687.0552989639247, 687.0964681443885, 689.2521614048713, 689.4587748617312, 8842.91157088421, 8862.734117976472, 8900.8664870027, 9273.072692730808, 9385.436873787594, 545.5880809988513, 627.5262177056293, 672.7544446277074, 691.9135108111486, 712.4514124124395, 4385.912281754347, 4433.556644335606, 4600.838970636011, 4620.745858977722, 4695.694779839299, 63994.36005639977, 64354.35645643577, 64408.677956609914, 64422.0, 64637.676811616584, 57042.42957570371, 57911.420885790605, 58393.0, 58485.70757146187, 58517.707411463525, 51661.0, 53714.0, 53784.19323710148, 53908.46091539113, 54139.729301352454, 8720.694775682821, 8808.603612837565, 8818.03001669803, 8875.33434992391, 9079.54602269897, 38283.23433531258, 38315.2336953265, 39012.609873900896, 39654.603453965094, 39935.2012959745, 46673.2999005022, 49177.26234106563, 50758.746206268734, 51043.23435148402, 51254.0, 10518.526666299882, 11204.719882003015, 11277.830832537682, 11289.661310160709, 11784.351860647746, 13009.154404963647, 13019.934900325437, 13053.477860885458, 13316.201027938354, 13831.0, 12608.0, 12702.428390722254, 12805.103642745291, 12850.228986260854, 13542.526011589547, 12048.939755301519, 12617.93691031539, 12735.617931462071, 12849.935750321189, 12953.935230323788, 12506.18709783861, 12514.937425312815, 12549.686257843627, 12716.809247861474, 12725.490980360682, 10897.509612067635, 10953.671389858317, 11071.612493562687, 11368.94315528417, 11775.293482391084, 10289.897101028893, 11166.944165279447, 11267.154963377616, 11310.377929213972, 11312.717182070184], "unit": "ms", "name": "yui.html", "value": 6835.353257496293}], "name": "dromaeo_css", "value": 7374.771283918659}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 109.0, 104.0, 108.0, 109.0, 109.0, 101.0, 109.0, 109.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 107.8}, {"lowerIsBetter": true, "replicates": [155.0, 120.0, 149.0, 157.0, 123.0, 147.0, 150.0, 123.0, 161.0, 121.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.6}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 160.0, 162.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 78.0, 152.0, 71.0, 71.0, 71.0, 71.0, 152.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 88.0}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 138.0, 92.0, 93.0, 91.0, 139.0, 92.0, 92.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 105.6}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 147.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 56.0, 56.0, 57.0, 58.0, 60.0, 56.0, 58.0, 55.0], "unit": "ms", "name": "json-parse-financial", "value": 56.7}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 63.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.8}, {"lowerIsBetter": true, "replicates": [77.0, 77.0, 85.0, 85.0, 77.0, 77.0, 77.0, 76.0, 77.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.2}, {"lowerIsBetter": true, "replicates": [116.0, 112.0, 109.0, 116.0, 112.0, 119.0, 120.0, 115.0, 126.0, 124.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.9}, {"lowerIsBetter": true, "replicates": [140.0, 139.0, 139.0, 141.0, 140.0, 128.0, 140.0, 139.0, 140.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 138.5}, {"lowerIsBetter": true, "replicates": [64.0, 63.0, 63.0, 63.0, 65.0, 63.0, 63.0, 63.0, 63.0, 64.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.4}], "name": "kraken", "value": 1470.7000000000003}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.2845408247084], "unit": "score", "name": "Richards", "value": 214.88327399999997}, {"lowerIsBetter": false, "replicates": [164.47368421052633], "unit": "score", "name": "DeltaBlue", "value": 401.9974399999998}, {"lowerIsBetter": false, "replicates": [278.473962684489], "unit": "score", "name": "Encrypt", "value": 955.8559709999997}, {"lowerIsBetter": false, "replicates": [5148.717948717948], "unit": "score", "name": "Decrypt", "value": 51.69850099601596}, {"lowerIsBetter": false, "replicates": [941.6195856873823], "unit": "score", "name": "RayTrace", "value": 785.8683179999997}, {"lowerIsBetter": false, "replicates": [541.4185165132648], "unit": "score", "name": "Earley", "value": 1230.9571610000005}, {"lowerIsBetter": false, "replicates": [10547.368421052632], "unit": "score", "name": "Boyer", "value": 63.187609780439146}, {"lowerIsBetter": false, "replicates": [23880.95238095238], "unit": "score", "name": "RegExp", "value": 38.146929212362885}, {"lowerIsBetter": false, "replicates": [533.6179295624333], "unit": "score", "name": "Splay", "value": 152.71413399999994}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23370.004357584923}]} 10:28:26 INFO - RETURN: dromaeo_css: 7374.77 10:28:26 INFO - RETURN: kraken: 1470.70 10:28:26 INFO - RETURN: v8_7: 23370.00 10:28:26 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23370.00"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1470.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7374.77"}}} 10:28:26 INFO - Return code: 0 10:28:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:28:27 INFO - # TBPL SUCCESS # 10:28:27 INFO - Running post-action listener: _resource_record_post_action 10:28:27 INFO - Running post-run listener: _resource_record_post_run 10:28:28 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 315392; Write bytes: 279289856; Read time: 68; Write time: 272864 10:28:28 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 19996672; Read time: 0; Write time: 10404 10:28:28 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 315392; Write bytes: 259293184; Read time: 68; Write time: 262460 10:28:28 INFO - Running post-run listener: _upload_blobber_files 10:28:28 INFO - Blob upload gear active. 10:28:28 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:28:28 INFO - Copying logs to upload dir... 10:28:28 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1286.076888 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-03-01 10:28:28.116226) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-03-01 10:28:28.119741) ========= 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010125 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456846783/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-03-01 10:28:28.174444) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:28:28.174824) ========= 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=14ef55db36f7623abb31b9cf000001d5-1456855620.6215-33590193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004736 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 10:28:28.224540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-01 10:28:28.224918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-01 10:28:28.225311) ========= ========= Total master_lag: 0.28 =========