builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-014 starttime: 1450532257.64 results: success (0) buildid: 20151219030215 builduid: e93ed16aadb94eb1a05a4c2c3ce37304 revision: c5cb194cc9cb56d742fb3a7a826f0080b0404edc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.639852) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.640276) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.640582) ========= 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006295 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.688425) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.688720) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.789156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.789479) ========= 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.840012) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.840318) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.840738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:37.841038) ========= 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-19 05:37:38-- 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% 84.3M=0s 2015-12-19 05:37:38 (84.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172657 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.025583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.025885) ========= 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017948 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.075634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.075968) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c5cb194cc9cb56d742fb3a7a826f0080b0404edc --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c5cb194cc9cb56d742fb3a7a826f0080b0404edc --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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-19 05:37:38,613 truncating revision to first 12 chars 2015-12-19 05:37:38,613 Setting DEBUG logging. 2015-12-19 05:37:38,613 attempt 1/10 2015-12-19 05:37:38,613 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c5cb194cc9cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-19 05:37:38,864 unpacking tar archive at: mozilla-central-c5cb194cc9cb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.531961 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.621364) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.621690) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.632136) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.632463) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-19 05:37:38.632885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-19 05:37:38.633190) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:37:39 INFO - MultiFileLogger online at 20151219 05:37:39 in /builds/slave/test-pgo 05:37:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:37:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:37:39 INFO - {'append_to_log': False, 05:37:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:37:39 INFO - 'blob_upload_branch': 'Firefox', 05:37:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:37:39 INFO - 'branch': 'Firefox', 05:37:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:39 INFO - 'config_files': ('talos/linux_config.py',), 05:37:39 INFO - 'default_actions': ('clobber', 05:37:39 INFO - 'read-buildbot-config', 05:37:39 INFO - 'download-and-extract', 05:37:39 INFO - 'populate-webroot', 05:37:39 INFO - 'create-virtualenv', 05:37:39 INFO - 'install', 05:37:39 INFO - 'run-tests'), 05:37:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:39 INFO - 'download_minidump_stackwalk': True, 05:37:39 INFO - 'download_symbols': 'ondemand', 05:37:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:39 INFO - 'installer_path': 'installer.exe', 05:37:39 INFO - 'log_level': 'info', 05:37:39 INFO - 'log_name': 'talos', 05:37:39 INFO - 'log_to_console': True, 05:37:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:37:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:37:39 INFO - 'opt_config_files': (), 05:37:39 INFO - 'pip_index': False, 05:37:39 INFO - 'sps_profile': False, 05:37:39 INFO - 'sps_profile_interval': 0, 05:37:39 INFO - 'suite': 'dromaeojs-e10s', 05:37:39 INFO - 'system_bits': '64', 05:37:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:39 INFO - 'title': 'talos-linux64-ix-014', 05:37:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:39 INFO - 'use_talos_json': True, 05:37:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:37:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:39 INFO - 'work_dir': 'build'} 05:37:39 INFO - ##### 05:37:39 INFO - ##### Running clobber step. 05:37:39 INFO - ##### 05:37:39 INFO - Running pre-action listener: _resource_record_pre_action 05:37:39 INFO - Running main action method: clobber 05:37:39 INFO - rmtree: /builds/slave/test-pgo/build 05:37:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:37:40 INFO - Running post-action listener: _resource_record_post_action 05:37:40 INFO - ##### 05:37:40 INFO - ##### Running read-buildbot-config step. 05:37:40 INFO - ##### 05:37:40 INFO - Running pre-action listener: _resource_record_pre_action 05:37:40 INFO - Running main action method: read_buildbot_config 05:37:40 INFO - Using buildbot properties: 05:37:40 INFO - { 05:37:40 INFO - "project": "", 05:37:40 INFO - "product": "firefox", 05:37:40 INFO - "script_repo_revision": "production", 05:37:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:37:40 INFO - "repository": "", 05:37:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:37:40 INFO - "buildid": "20151219030215", 05:37:40 INFO - "stage_platform": "linux64-pgo", 05:37:40 INFO - "basedir": "/builds/slave/test-pgo", 05:37:40 INFO - "nightly_build": "True", 05:37:40 INFO - "buildnumber": 588, 05:37:40 INFO - "slavename": "talos-linux64-ix-014", 05:37:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:37:40 INFO - "platform": "ubuntu64_hw", 05:37:40 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:37:40 INFO - "branch": "mozilla-central", 05:37:40 INFO - "repo_path": "mozilla-central", 05:37:40 INFO - "revision": "c5cb194cc9cb56d742fb3a7a826f0080b0404edc", 05:37:40 INFO - "pgo_build": "True", 05:37:40 INFO - "builduid": "e93ed16aadb94eb1a05a4c2c3ce37304", 05:37:40 INFO - "slavebuilddir": "test" 05:37:40 INFO - } 05:37:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:37:40 INFO - Running post-action listener: _resource_record_post_action 05:37:40 INFO - ##### 05:37:40 INFO - ##### Running download-and-extract step. 05:37:40 INFO - ##### 05:37:40 INFO - Running pre-action listener: _resource_record_pre_action 05:37:40 INFO - Running main action method: download_and_extract 05:37:40 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:37: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')]} 05:37:40 INFO - https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:37:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/test_packages.json 05:37:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/test_packages.json 05:37:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:37:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:37:48 INFO - Downloaded 1302 bytes. 05:37:48 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:37:48 INFO - Using the following test package requirements: 05:37:48 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:48 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:37:48 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'jsshell-linux-x86_64.zip'], 05:37:48 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:37:48 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:48 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:37:48 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:37:48 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:37:48 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:37:48 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:37:48 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:37:48 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:37:48 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')]} 05:37:48 INFO - https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:37:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:37:52 INFO - Downloaded 23120969 bytes. 05:37:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:37:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:52 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:37:54 INFO - Return code: 0 05:37:54 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:37:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:37:54 INFO - https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:37:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:37:55 INFO - Downloaded 23120969 bytes. 05:37:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:37:55 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:37:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:37:56 INFO - Return code: 0 05:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:37:56 INFO - https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:37:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:37:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:37:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:37:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:37:57 INFO - Downloaded 11132039 bytes. 05:37:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:37:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:37:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:37:57 INFO - Return code: 0 05:37: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')]} 05:37:57 INFO - https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:37:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:37:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:37:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:37:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:37:59 INFO - Downloaded 54646849 bytes. 05:37:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:37:59 INFO - mkdir: /builds/slave/test-pgo/properties 05:37:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:37:59 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:37:59 INFO - Contents: 05:37:59 INFO - build_url:https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:37:59 INFO - Running post-action listener: _resource_record_post_action 05:37:59 INFO - Running post-action listener: set_extra_try_arguments 05:37:59 INFO - ##### 05:37:59 INFO - ##### Running populate-webroot step. 05:37:59 INFO - ##### 05:37:59 INFO - Running pre-action listener: _resource_record_pre_action 05:37:59 INFO - Running main action method: populate_webroot 05:37:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:37:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:37:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:37:59 INFO - '--tppagecycles', 05:37:59 INFO - '7'], 05:37:59 INFO - 'tests': ['tsvgm']}}, 05:37:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:37:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:37:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:37:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:37:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'tests': ['damp', 'tps']}, 05:37:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['damp', 'tps']}, 05:37:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:37:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:59 INFO - 'other': {'tests': ['a11yr', 05:37:59 INFO - 'ts_paint', 05:37:59 INFO - 'tpaint', 05:37:59 INFO - 'sessionrestore', 05:37:59 INFO - 'sessionrestore_no_auto_restore']}, 05:37:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['a11yr', 05:37:59 INFO - 'ts_paint', 05:37:59 INFO - 'tpaint', 05:37:59 INFO - 'sessionrestore', 05:37:59 INFO - 'sessionrestore_no_auto_restore']}, 05:37:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['a11yr', 05:37:59 INFO - 'ts_paint', 05:37:59 INFO - 'tpaint', 05:37:59 INFO - 'sessionrestore', 05:37:59 INFO - 'sessionrestore_no_auto_restore']}, 05:37:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['a11yr', 05:37:59 INFO - 'ts_paint', 05:37:59 INFO - 'tpaint', 05:37:59 INFO - 'sessionrestore', 05:37:59 INFO - 'sessionrestore_no_auto_restore']}, 05:37:59 INFO - 'other_l64': {'tests': ['a11yr', 05:37:59 INFO - 'ts_paint', 05:37:59 INFO - 'tpaint', 05:37:59 INFO - 'sessionrestore', 05:37:59 INFO - 'sessionrestore_no_auto_restore']}, 05:37:59 INFO - 'other_nol64': {'tests': ['a11yr', 05:37:59 INFO - 'ts_paint', 05:37:59 INFO - 'tpaint', 05:37:59 INFO - 'sessionrestore', 05:37:59 INFO - 'sessionrestore_no_auto_restore']}, 05:37:59 INFO - 'svgr': {'tests': ['tsvgx', 05:37:59 INFO - 'tsvgr_opacity', 05:37:59 INFO - 'tart', 05:37:59 INFO - 'tscrollx', 05:37:59 INFO - 'cart']}, 05:37:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['tsvgx', 05:37:59 INFO - 'tsvgr_opacity', 05:37:59 INFO - 'tart', 05:37:59 INFO - 'tscrollx', 05:37:59 INFO - 'cart']}, 05:37:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'tests': ['tp5o']}, 05:37:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'talos_options': ['--e10s'], 05:37:59 INFO - 'tests': ['tp5o']}, 05:37:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'talos_options': ['--xperf_path', 05:37:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:59 INFO - 'tests': ['tp5n']}, 05:37:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:37:59 INFO - 'talos_options': ['--e10s', 05:37:59 INFO - '--xperf_path', 05:37:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:59 INFO - 'tests': ['tp5n']}}, 05:37:59 INFO - 'talos.zip': {'path': '', 05:37:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:37:59 INFO - Running post-action listener: _resource_record_post_action 05:37:59 INFO - ##### 05:37:59 INFO - ##### Running create-virtualenv step. 05:37:59 INFO - ##### 05:37:59 INFO - Running pre-action listener: _resource_record_pre_action 05:37:59 INFO - Running main action method: create_virtualenv 05:37:59 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:37:59 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 05:37:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:37:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:59 INFO - Using real prefix '/usr' 05:37:59 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:37:59 INFO - Installing distribute.............................................................................................................................................................................................done. 05:38:01 INFO - Installing pip.................done. 05:38:01 INFO - Return code: 0 05:38:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:38:01 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')]} 05:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 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')]} 05:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:01 INFO - Using env: {'DISPLAY': ':0', 05:38:01 INFO - 'HOME': '/home/cltbld', 05:38:01 INFO - 'LANG': 'en_US.UTF-8', 05:38:01 INFO - 'LANGUAGE': 'en_US:en', 05:38:01 INFO - 'LOGNAME': 'cltbld', 05:38:01 INFO - 'MAIL': '/var/mail/cltbld', 05:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:01 INFO - 'MOZ_NO_REMOTE': '1', 05:38:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:01 INFO - 'NO_EM_RESTART': '1', 05:38:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:01 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:01 INFO - 'SHELL': '/bin/bash', 05:38:01 INFO - 'SHLVL': '1', 05:38:01 INFO - 'TERM': 'linux', 05:38:01 INFO - 'TMOUT': '86400', 05:38:01 INFO - 'USER': 'cltbld', 05:38:01 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:01 INFO - '_': '/tools/buildbot/bin/python'} 05:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:01 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 05:38:01 INFO - Cleaning up... 05:38:01 INFO - Return code: 0 05:38:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:38:01 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')]} 05:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 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')]} 05:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:01 INFO - Using env: {'DISPLAY': ':0', 05:38:01 INFO - 'HOME': '/home/cltbld', 05:38:01 INFO - 'LANG': 'en_US.UTF-8', 05:38:01 INFO - 'LANGUAGE': 'en_US:en', 05:38:01 INFO - 'LOGNAME': 'cltbld', 05:38:01 INFO - 'MAIL': '/var/mail/cltbld', 05:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:01 INFO - 'MOZ_NO_REMOTE': '1', 05:38:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:01 INFO - 'NO_EM_RESTART': '1', 05:38:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:01 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:01 INFO - 'SHELL': '/bin/bash', 05:38:01 INFO - 'SHLVL': '1', 05:38:01 INFO - 'TERM': 'linux', 05:38:01 INFO - 'TMOUT': '86400', 05:38:01 INFO - 'USER': 'cltbld', 05:38:01 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:01 INFO - '_': '/tools/buildbot/bin/python'} 05:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:02 INFO - Downloading/unpacking psutil>=0.7.1 05:38: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 05:38: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 05:38: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 05:38: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 05:38: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 05:38: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 05:38:03 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:38:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:38:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:38:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:38:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:38:03 INFO - Installing collected packages: psutil 05:38:03 INFO - Running setup.py install for psutil 05:38:04 INFO - building 'psutil._psutil_linux' extension 05:38:04 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 05:38:04 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 05:38:04 INFO - building 'psutil._psutil_posix' extension 05:38:04 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 05:38:04 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 05:38:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:38:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:38:04 INFO - Successfully installed psutil 05:38:04 INFO - Cleaning up... 05:38:04 INFO - Return code: 0 05:38:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:04 INFO - Using env: {'DISPLAY': ':0', 05:38:04 INFO - 'HOME': '/home/cltbld', 05:38:04 INFO - 'LANG': 'en_US.UTF-8', 05:38:04 INFO - 'LANGUAGE': 'en_US:en', 05:38:04 INFO - 'LOGNAME': 'cltbld', 05:38:04 INFO - 'MAIL': '/var/mail/cltbld', 05:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:04 INFO - 'MOZ_NO_REMOTE': '1', 05:38:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:04 INFO - 'NO_EM_RESTART': '1', 05:38:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:04 INFO - 'SHELL': '/bin/bash', 05:38:04 INFO - 'SHLVL': '1', 05:38:04 INFO - 'TERM': 'linux', 05:38:04 INFO - 'TMOUT': '86400', 05:38:04 INFO - 'USER': 'cltbld', 05:38:04 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:04 INFO - '_': '/tools/buildbot/bin/python'} 05:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:04 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 05:38:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:38:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:38:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:38:06 INFO - Installing collected packages: mozsystemmonitor 05:38:06 INFO - Running setup.py install for mozsystemmonitor 05:38:06 INFO - Successfully installed mozsystemmonitor 05:38:06 INFO - Cleaning up... 05:38:06 INFO - Return code: 0 05:38:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:38:06 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')]} 05:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:06 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')]} 05:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:06 INFO - Using env: {'DISPLAY': ':0', 05:38:06 INFO - 'HOME': '/home/cltbld', 05:38:06 INFO - 'LANG': 'en_US.UTF-8', 05:38:06 INFO - 'LANGUAGE': 'en_US:en', 05:38:06 INFO - 'LOGNAME': 'cltbld', 05:38:06 INFO - 'MAIL': '/var/mail/cltbld', 05:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:06 INFO - 'MOZ_NO_REMOTE': '1', 05:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:06 INFO - 'NO_EM_RESTART': '1', 05:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:06 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:06 INFO - 'SHELL': '/bin/bash', 05:38:06 INFO - 'SHLVL': '1', 05:38:06 INFO - 'TERM': 'linux', 05:38:06 INFO - 'TMOUT': '86400', 05:38:06 INFO - 'USER': 'cltbld', 05:38:06 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:06 INFO - '_': '/tools/buildbot/bin/python'} 05:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:06 INFO - Downloading/unpacking blobuploader==1.2.4 05:38:06 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 05:38:06 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 05:38:06 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 05:38:06 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 05:38:06 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 05:38:06 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 05:38:08 INFO - Downloading blobuploader-1.2.4.tar.gz 05:38:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:38:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:38:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:38:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:38:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:09 INFO - Downloading docopt-0.6.1.tar.gz 05:38:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:38:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:09 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:09 INFO - Running setup.py install for blobuploader 05:38:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:09 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:38:09 INFO - Running setup.py install for requests 05:38:09 INFO - Running setup.py install for docopt 05:38:09 INFO - Successfully installed blobuploader requests docopt 05:38:09 INFO - Cleaning up... 05:38:09 INFO - Return code: 0 05:38:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:38:09 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')]} 05:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:09 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')]} 05:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:09 INFO - Using env: {'DISPLAY': ':0', 05:38:09 INFO - 'HOME': '/home/cltbld', 05:38:09 INFO - 'LANG': 'en_US.UTF-8', 05:38:09 INFO - 'LANGUAGE': 'en_US:en', 05:38:09 INFO - 'LOGNAME': 'cltbld', 05:38:09 INFO - 'MAIL': '/var/mail/cltbld', 05:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:09 INFO - 'MOZ_NO_REMOTE': '1', 05:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:09 INFO - 'NO_EM_RESTART': '1', 05:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:09 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:09 INFO - 'SHELL': '/bin/bash', 05:38:09 INFO - 'SHLVL': '1', 05:38:09 INFO - 'TERM': 'linux', 05:38:09 INFO - 'TMOUT': '86400', 05:38:09 INFO - 'USER': 'cltbld', 05:38:09 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:09 INFO - '_': '/tools/buildbot/bin/python'} 05:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:09 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:38:09 INFO - Running setup.py (path:/tmp/pip-i1fH3h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:38:10 INFO - Running setup.py (path:/tmp/pip-PUkxbf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:38:10 INFO - Running setup.py (path:/tmp/pip-Ns0eDX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:38:10 INFO - Running setup.py (path:/tmp/pip-MbxC30-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:38:10 INFO - Running setup.py (path:/tmp/pip-URU3mC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:38:10 INFO - Running setup.py (path:/tmp/pip-Jyv1m0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:38:10 INFO - Running setup.py (path:/tmp/pip-pqOlsg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:38:10 INFO - Running setup.py (path:/tmp/pip-OTIn1d-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:38:10 INFO - Running setup.py (path:/tmp/pip-xMqmIv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:38:10 INFO - Running setup.py (path:/tmp/pip-4vbU1u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:38:10 INFO - Running setup.py (path:/tmp/pip-0iGEa_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:38:10 INFO - Running setup.py (path:/tmp/pip-nR4H89-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:38:10 INFO - Running setup.py (path:/tmp/pip-Spp94s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:38:10 INFO - Running setup.py (path:/tmp/pip-xD3EnG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:38:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:38:10 INFO - Running setup.py (path:/tmp/pip-yVjogv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:38:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:38:11 INFO - Running setup.py (path:/tmp/pip-KloGxW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:38:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:38:11 INFO - Running setup.py (path:/tmp/pip-woVRXs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:38:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:11 INFO - Running setup.py install for manifestparser 05:38:11 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:38:11 INFO - Running setup.py install for mozcrash 05:38:11 INFO - Running setup.py install for mozdebug 05:38:11 INFO - Running setup.py install for mozdevice 05:38:11 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:38:11 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:38:11 INFO - Running setup.py install for mozfile 05:38:11 INFO - Running setup.py install for mozhttpd 05:38:11 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:38:11 INFO - Running setup.py install for mozinfo 05:38:11 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:38:11 INFO - Running setup.py install for mozInstall 05:38:12 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Running setup.py install for mozleak 05:38:12 INFO - Running setup.py install for mozlog 05:38:12 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Running setup.py install for moznetwork 05:38:12 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Running setup.py install for mozprocess 05:38:12 INFO - Running setup.py install for mozprofile 05:38:12 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Running setup.py install for mozrunner 05:38:12 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Running setup.py install for mozscreenshot 05:38:12 INFO - Running setup.py install for moztest 05:38:12 INFO - Running setup.py install for mozversion 05:38:12 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:38:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:12 INFO - Cleaning up... 05:38:13 INFO - Return code: 0 05:38:13 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:38:13 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')]} 05:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:13 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')]} 05:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:13 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:13 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:13 INFO - Using env: {'DISPLAY': ':0', 05:38:13 INFO - 'HOME': '/home/cltbld', 05:38:13 INFO - 'LANG': 'en_US.UTF-8', 05:38:13 INFO - 'LANGUAGE': 'en_US:en', 05:38:13 INFO - 'LOGNAME': 'cltbld', 05:38:13 INFO - 'MAIL': '/var/mail/cltbld', 05:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:13 INFO - 'MOZ_NO_REMOTE': '1', 05:38:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:13 INFO - 'NO_EM_RESTART': '1', 05:38:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:13 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:13 INFO - 'SHELL': '/bin/bash', 05:38:13 INFO - 'SHLVL': '1', 05:38:13 INFO - 'TERM': 'linux', 05:38:13 INFO - 'TMOUT': '86400', 05:38:13 INFO - 'USER': 'cltbld', 05:38:13 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:13 INFO - '_': '/tools/buildbot/bin/python'} 05:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:38:13 INFO - Running setup.py (path:/tmp/pip-KahTkX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:38:13 INFO - Running setup.py (path:/tmp/pip-vpwBLZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:38:13 INFO - Running setup.py (path:/tmp/pip-cwAvWp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:38:13 INFO - Running setup.py (path:/tmp/pip-h_l5v5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:38:13 INFO - Running setup.py (path:/tmp/pip-8TrCFF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:38:13 INFO - Running setup.py (path:/tmp/pip-Q9eGiW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:38:13 INFO - Running setup.py (path:/tmp/pip-kQLeNi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:38:13 INFO - Running setup.py (path:/tmp/pip-L5bTJj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:38:13 INFO - Running setup.py (path:/tmp/pip-oX3EIr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:38:13 INFO - Running setup.py (path:/tmp/pip-FaEwIG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:38:13 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)) 05:38:13 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:38:13 INFO - Running setup.py (path:/tmp/pip-oAsKR0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:38:14 INFO - Running setup.py (path:/tmp/pip-eDk0pw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:38:14 INFO - Running setup.py (path:/tmp/pip-I78Gyc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:38:14 INFO - Running setup.py (path:/tmp/pip-Hj15YN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:38:14 INFO - Running setup.py (path:/tmp/pip-f9XO7e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:38:14 INFO - Running setup.py (path:/tmp/pip-9wwmDB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:38:14 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)) 05:38:14 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:38:14 INFO - Running setup.py (path:/tmp/pip-MzXfLd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:38:14 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)) 05:38:14 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)) 05:38:14 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)) 05:38:14 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)) 05:38:14 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)) 05:38:14 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)) 05:38:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:16 INFO - Downloading blessings-1.5.1.tar.gz 05:38:16 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:38:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:16 INFO - Installing collected packages: blessings 05:38:16 INFO - Running setup.py install for blessings 05:38:16 INFO - Successfully installed blessings 05:38:16 INFO - Cleaning up... 05:38:16 INFO - Return code: 0 05:38:16 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:38:16 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:38:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:38:16 INFO - Reading from file tmpfile_stdout 05:38:16 INFO - Current package versions: 05:38:16 INFO - argparse == 1.2.1 05:38:16 INFO - blessings == 1.5.1 05:38:16 INFO - blobuploader == 1.2.4 05:38:16 INFO - docopt == 0.6.1 05:38:16 INFO - manifestparser == 1.1 05:38:16 INFO - mozInstall == 1.12 05:38:16 INFO - mozcrash == 0.16 05:38:16 INFO - mozdebug == 0.1 05:38:16 INFO - mozdevice == 0.47 05:38:16 INFO - mozfile == 1.2 05:38:16 INFO - mozhttpd == 0.7 05:38:16 INFO - mozinfo == 0.9 05:38:16 INFO - mozleak == 0.1 05:38:16 INFO - mozlog == 3.1 05:38:16 INFO - moznetwork == 0.27 05:38:16 INFO - mozprocess == 0.22 05:38:16 INFO - mozprofile == 0.27 05:38:16 INFO - mozrunner == 6.11 05:38:16 INFO - mozscreenshot == 0.1 05:38:16 INFO - mozsystemmonitor == 0.0 05:38:16 INFO - moztest == 0.7 05:38:16 INFO - mozversion == 1.4 05:38:16 INFO - psutil == 3.1.1 05:38:16 INFO - requests == 1.2.3 05:38:16 INFO - wsgiref == 0.1.2 05:38:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:38: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')]} 05:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:16 INFO - Using env: {'DISPLAY': ':0', 05:38:16 INFO - 'HOME': '/home/cltbld', 05:38:16 INFO - 'LANG': 'en_US.UTF-8', 05:38:16 INFO - 'LANGUAGE': 'en_US:en', 05:38:16 INFO - 'LOGNAME': 'cltbld', 05:38:16 INFO - 'MAIL': '/var/mail/cltbld', 05:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:16 INFO - 'MOZ_NO_REMOTE': '1', 05:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:16 INFO - 'NO_EM_RESTART': '1', 05:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:16 INFO - 'SHELL': '/bin/bash', 05:38:16 INFO - 'SHLVL': '1', 05:38:16 INFO - 'TERM': 'linux', 05:38:16 INFO - 'TMOUT': '86400', 05:38:16 INFO - 'USER': 'cltbld', 05:38:16 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:16 INFO - '_': '/tools/buildbot/bin/python'} 05:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 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)) 05:38:16 INFO - Cleaning up... 05:38:16 INFO - Return code: 0 05:38:16 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:38: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')]} 05:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38: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')]} 05:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:16 INFO - Using env: {'DISPLAY': ':0', 05:38:16 INFO - 'HOME': '/home/cltbld', 05:38:16 INFO - 'LANG': 'en_US.UTF-8', 05:38:16 INFO - 'LANGUAGE': 'en_US:en', 05:38:16 INFO - 'LOGNAME': 'cltbld', 05:38:16 INFO - 'MAIL': '/var/mail/cltbld', 05:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:16 INFO - 'MOZ_NO_REMOTE': '1', 05:38:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:16 INFO - 'NO_EM_RESTART': '1', 05:38:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:16 INFO - 'SHELL': '/bin/bash', 05:38:16 INFO - 'SHLVL': '1', 05:38:16 INFO - 'TERM': 'linux', 05:38:16 INFO - 'TMOUT': '86400', 05:38:16 INFO - 'USER': 'cltbld', 05:38:16 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:16 INFO - '_': '/tools/buildbot/bin/python'} 05:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:17 INFO - Downloading/unpacking jsonschema 05:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:38:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:38:18 INFO - Downloading/unpacking functools32 (from jsonschema) 05:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:38:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:38:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:38:18 INFO - warning: no files found matching '*.txt' 05:38:18 INFO - no previously-included directories found matching 'build' 05:38:18 INFO - no previously-included directories found matching 'dist' 05:38:18 INFO - no previously-included directories found matching '.git*' 05:38:18 INFO - Installing collected packages: jsonschema, functools32 05:38:19 INFO - Running setup.py install for functools32 05:38:19 INFO - warning: no files found matching '*.txt' 05:38:19 INFO - no previously-included directories found matching 'build' 05:38:19 INFO - no previously-included directories found matching 'dist' 05:38:19 INFO - no previously-included directories found matching '.git*' 05:38:19 INFO - Successfully installed jsonschema functools32 05:38:19 INFO - Cleaning up... 05:38:19 INFO - Return code: 0 05:38:19 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:38:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7fabcd729f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eeb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fb8600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, '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': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', '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 05:38:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 05:38:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 05:38:19 INFO - Using env: {'DISPLAY': ':0', 05:38:19 INFO - 'HOME': '/home/cltbld', 05:38:19 INFO - 'LANG': 'en_US.UTF-8', 05:38:19 INFO - 'LANGUAGE': 'en_US:en', 05:38:19 INFO - 'LOGNAME': 'cltbld', 05:38:19 INFO - 'MAIL': '/var/mail/cltbld', 05:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:19 INFO - 'MOZ_NO_REMOTE': '1', 05:38:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:19 INFO - 'NO_EM_RESTART': '1', 05:38:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:19 INFO - 'SHELL': '/bin/bash', 05:38:19 INFO - 'SHLVL': '1', 05:38:19 INFO - 'TERM': 'linux', 05:38:19 INFO - 'TMOUT': '86400', 05:38:19 INFO - 'USER': 'cltbld', 05:38:19 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:19 INFO - '_': '/tools/buildbot/bin/python'} 05:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:19 INFO - Downloading/unpacking flake8 05:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:38:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 05:38:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:38:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 05:38:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 05:38:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 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 05:38:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 05:38:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:38:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:38:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:38:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:38:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:38:21 INFO - no previously-included directories found matching 'docs/_build' 05:38:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:38:21 INFO - Running setup.py install for pep8 05:38:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:38:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:38:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:38:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:38:21 INFO - no previously-included directories found matching 'docs/_build' 05:38:21 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:38:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:38:21 INFO - Cleaning up... 05:38:21 INFO - Return code: 0 05:38:21 INFO - Running post-action listener: _resource_record_post_action 05:38:21 INFO - Running post-action listener: _start_resource_monitoring 05:38:21 INFO - Starting resource monitoring. 05:38:21 INFO - ##### 05:38:21 INFO - ##### Running install step. 05:38:21 INFO - ##### 05:38:21 INFO - Running pre-action listener: _resource_record_pre_action 05:38:21 INFO - Running main action method: install 05:38:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:38:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:38:22 INFO - Reading from file tmpfile_stdout 05:38:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:38:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:38:22 INFO - Reading from file tmpfile_stdout 05:38:22 INFO - Output received: 05:38:22 INFO - Usage: mozinstall [options] installer 05:38:22 INFO - Options: 05:38:22 INFO - -h, --help show this help message and exit 05:38:22 INFO - -d DEST, --destination=DEST 05:38:22 INFO - Directory to install application into. [default: 05:38:22 INFO - "/builds/slave/test-pgo"] 05:38:22 INFO - --app=APP Application being installed. [default: firefox] 05:38:22 INFO - mkdir: /builds/slave/test-pgo/build/application 05:38:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:38:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:38:38 INFO - Reading from file tmpfile_stdout 05:38:38 INFO - Output received: 05:38:38 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:38:38 INFO - Running post-action listener: _resource_record_post_action 05:38:38 INFO - ##### 05:38:38 INFO - ##### Running run-tests step. 05:38:38 INFO - ##### 05:38:38 INFO - Running pre-action listener: _resource_record_pre_action 05:38:38 INFO - Running main action method: run_tests 05:38:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:38:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:38:38 INFO - Python 2.7.3 05:38:38 INFO - Return code: 0 05:38:38 INFO - grabbing minidump binary from tooltool 05:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:38:38 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 0x2047350>, '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 0x2048250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20486e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:38:38 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 05:38:38 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 05:38:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:38 INFO - Return code: 0 05:38:38 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:38:38 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:38:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:38:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:38:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:38:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:38:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:38:39 INFO - Return code: 0 05:38:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:38:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --webServer localhost 05:38:39 INFO - Using env: {'DISPLAY': ':0', 05:38:39 INFO - 'HOME': '/home/cltbld', 05:38:39 INFO - 'LANG': 'en_US.UTF-8', 05:38:39 INFO - 'LANGUAGE': 'en_US:en', 05:38:39 INFO - 'LOGNAME': 'cltbld', 05:38:39 INFO - 'MAIL': '/var/mail/cltbld', 05:38:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:38:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:39 INFO - 'MOZ_NO_REMOTE': '1', 05:38:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:38:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:39 INFO - 'NO_EM_RESTART': '1', 05:38:39 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:38:39 INFO - 'PWD': '/builds/slave/test-pgo', 05:38:39 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:38:39 INFO - 'SHELL': '/bin/bash', 05:38:39 INFO - 'SHLVL': '1', 05:38:39 INFO - 'TERM': 'linux', 05:38:39 INFO - 'TMOUT': '86400', 05:38:39 INFO - 'USER': 'cltbld', 05:38:39 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1450509523.976726-763089705', 05:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:39 INFO - '_': '/tools/buildbot/bin/python'} 05:38:39 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--webServer', 'localhost'] with output_timeout 3600 05:38:39 INFO - mozversion INFO | application_buildid: 20151219030215 05:38:39 INFO - mozversion INFO | application_changeset: c5cb194cc9cb56d742fb3a7a826f0080b0404edc 05:38:39 INFO - mozversion INFO | application_display_name: Nightly 05:38:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:39 INFO - mozversion INFO | application_name: Firefox 05:38:39 INFO - mozversion INFO | application_remotingname: firefox 05:38:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:38:39 INFO - mozversion INFO | application_vendor: Mozilla 05:38:39 INFO - mozversion INFO | application_version: 46.0a1 05:38:39 INFO - mozversion INFO | platform_buildid: 20151219030215 05:38:39 INFO - mozversion INFO | platform_changeset: c5cb194cc9cb56d742fb3a7a826f0080b0404edc 05:38:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:38:39 INFO - mozversion INFO | platform_version: 46.0a1 05:38:39 INFO - 2015-12-19 05:38:39,247 DEBUG : using testdate: 1450532319 05:38:39 INFO - 2015-12-19 05:38:39,248 DEBUG : actual date: 1450532319 05:38:39 INFO - 2015-12-19 05:38:39,256 INFO : starting webserver on 'localhost:54935' 05:38:39 INFO - 2015-12-19 05:38:39,257 INFO : Starting test suite talos-linux64-ix-014.e 05:38:39 INFO - 2015-12-19 05:38:39,257 INFO : Starting test dromaeo_css 05:38:39 INFO - 2015-12-19 05:38:39,257 DEBUG : operating with platform_type : linux_ 05:38:39 INFO - 2015-12-19 05:38:39,257 INFO : Initialising browser for dromaeo_css test... 05:38:39 INFO - 2015-12-19 05:38:39,274 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTL2Z3k/profile http://localhost:54935/getInfo.html 05:38:44 INFO - 2015-12-19 05:38:44,005 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:38:44 INFO - 2015-12-19 05:38:44,005 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:38:44 INFO - 2015-12-19 05:38:44,005 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:38:44 INFO - 2015-12-19 05:38:44,006 DEBUG : BROWSER_OUTPUT: __metrics 05:38:44 INFO - 2015-12-19 05:38:44,127 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:38:45 INFO - 2015-12-19 05:38:45,001 INFO : Browser initialized. 05:38:45 INFO - 2015-12-19 05:38:45,002 INFO : Running cycle 1/1 for dromaeo_css test... 05:38:45 INFO - 2015-12-19 05:38:45,002 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTL2Z3k/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:38:56 INFO - 2015-12-19 05:38:56,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 151175168 05:38:56 INFO - 2015-12-19 05:38:56,799 DEBUG : BROWSER_OUTPUT: 05:39:46 INFO - 2015-12-19 05:39:46,389 DEBUG : BROWSER_OUTPUT: 1450532386387 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] 05:41:55 INFO - 2015-12-19 05:41:55,456 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54935/tests/dromaeo/cssquery-ext.html) 05:41:56 INFO - 2015-12-19 05:41:56,542 DEBUG : BROWSER_OUTPUT: RSS: Main: 157290496 05:41:56 INFO - 2015-12-19 05:41:56,542 DEBUG : BROWSER_OUTPUT: 05:44:58 INFO - 2015-12-19 05:44:58,591 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/dromaeo/cssquery-ext.html (next: http://localhost:54935/tests/dromaeo/cssquery-jquery.html) 05:44:59 INFO - 2015-12-19 05:44:59,172 DEBUG : BROWSER_OUTPUT: RSS: Main: 153763840 05:44:59 INFO - 2015-12-19 05:44:59,173 DEBUG : BROWSER_OUTPUT: 05:48:00 INFO - 2015-12-19 05:48:00,871 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54935/tests/dromaeo/cssquery-mootools.html) 05:48:01 INFO - 2015-12-19 05:48:01,473 DEBUG : BROWSER_OUTPUT: RSS: Main: 153591808 05:48:01 INFO - 2015-12-19 05:48:01,473 DEBUG : BROWSER_OUTPUT: 05:51:03 INFO - 2015-12-19 05:51:03,126 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54935/tests/dromaeo/cssquery-prototype.html) 05:51:03 INFO - 2015-12-19 05:51:03,731 DEBUG : BROWSER_OUTPUT: RSS: Main: 153423872 05:51:03 INFO - 2015-12-19 05:51:03,731 DEBUG : BROWSER_OUTPUT: 05:54:06 INFO - 2015-12-19 05:54:06,046 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54935/tests/dromaeo/cssquery-yui.html) 05:54:06 INFO - 2015-12-19 05:54:06,653 DEBUG : BROWSER_OUTPUT: RSS: Main: 152924160 05:54:06 INFO - 2015-12-19 05:54:06,653 DEBUG : BROWSER_OUTPUT: 05:57:08 INFO - 2015-12-19 05:57:08,906 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/dromaeo/cssquery-yui.html (next: http://localhost:54935/tests/dromaeo/cssquery-dojo.html) 05:57:09 INFO - 2015-12-19 05:57:09,156 DEBUG : BROWSER_OUTPUT: RSS: Main: 155705344 05:57:09 INFO - 2015-12-19 05:57:09,156 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,156 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:57:09 INFO - 2015-12-19 05:57:09,156 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:57:09 INFO - 2015-12-19 05:57:09,156 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:57:09 INFO - 2015-12-19 05:57:09,157 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:57:09 INFO - 2015-12-19 05:57:09,158 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3146.2134466383404;3207.3264614430955;3228.886988955387;3239.6112466504023;3254.088855120567;5149.845504634867;5301.602379821515;5340.946590534093;5356.651817631856;5358.223057656637;5268.973655131728;5310.73446327683;5335.226392173133;5343.759530821113;5353.277307563478;4561.543845615445;4858.975705121469;5029.924551131736;5047.293378926953;5079.212722028073;4828.082664293791;4977.178765503688;4990;5047.823326183584;5069.873253168682;4854.12625727369;4915.827946021872;4941.851744447672;4954.47977962316;4996.32549605804;4925.926111108336;4950.777215025332;4952.009598080398;4982;4986.401631804207;4641.396618439615;4749.076623967264;4925.679830810986;4948.356713627206;4996.825111121093;5054.797808087672;5078.339815823956;5123.87190320245;5126.2054381570915;5143.974280128576;5760.481556659921;5782.566307526952;5795.275590551181;5807.477327040587;5815.214945982323;5635.887282254373;5820.621659592113;5853.76584936602;5857.531397488234;5860.853478663067;5288.048151332759;5369.785208591652;5387.515123638891;5395.136778115482;5401.78392864285;5766.192733017381;5780.797672081457;5785.450382213683;5789.2763404574425;5807.099899515498;5428.592855535771;5656.406077361818;5665.915011274834;5672.943270567324;5679.574031947621;5702.486776190163;5727.341355744059;5754.079347304411;5789.855253618609;5818.44724751148;5717.42825717425;5762.74067666956;5771.68255745938;5778.566607504454;5789.3921138279875;5750.712464376765;5763.423657634203;5769.480746732815;5784.161296611967;5793.971030144822;4399.23013472641;4431.778411079433;4473.172463094338;4476.104779044204;4476.686631935733;3964.306246406925;3994.121293315466;4015.5984401560195;4026.8187931543152;4043.5552089270745;5477.233187353773;5478.342598888159;5490.752916118784;5512.062949298629;5517.310336207974;5636.351819540723;5748;5794.362620111784;5814.563907706939;5864.501517371071;5540;5548.4174161713245;5550.8057217997175;5558.277423934863;5605.691686957175;5021.171506701427;5062.088824011713;5062.443131255559;5063.443021267658;5111.182210846247;5684.431556844283;5803.448672376117;5858.033424484912;5898.705064746746;5928.081147422156;5736.627119237236;5790.7973220937065;5823.388544202798;5926.1999630049395;5967.850803729768;5787.247657804459;5824.912626310694;5827.388124246893;5831.212786273799;5849.532037436909;5616.438356164351;5627.352854421794;5674.06377947634;5690.829275121753;5695.686737229492;5716.885662286814;5778.8266352009505;5786.07422812348;5789.536837052941;5840.094785308284;5705.686187259741;5724.971375143097;5727.6849773262875;5750.942490575041;5751.568632352674;5350.438203988525;5662.631928924607;5679.716014199356;5696.088625819849;5703.657780533182;5410.161424979135;5415.864603384789;5507.394186639386;5511.696856672921;5563.888722225452;5443.537299329601;5612.130119831432;5617.88764224705;5644.322681278109;5696.11710184922;5398.514133727984;5506.5870059746485;5538.169274608841;5539.639923404966;5606.159076138611 05:57:09 INFO - 2015-12-19 05:57:09,159 DEBUG : BROWSER_OUTPUT: |1;ext.html;550.9476599723025;594.8721024979628;600.6786369292428;602.3765402808094;613.3437222172272;37546;38314.4252836207;38452.23095538095;38589.421158682606;38634.42048369277;42716.57283427173;43133.13733725323;43215;43286.134277314435;43373.56626433735;8619.267362274199;8802.86795698065;8820.823583528325;8859.778505537348;8879.95560022199;8503.2347088762;8654.740357789276;8660.826783464327;8663.95668021659;8687.56562171892;8551.187637174458;8637.568121593926;8655.26430253426;8658.740237792876;8666.566671666424;8563.143685631418;8648.048714641385;8652.783680407978;8655.523946182957;8663.436876603;8461.873071903927;8650.913490865136;8658.696945606873;8662.653493860238;8663.870041949376;39413.80293098545;40237.39643905344;40269.79865100656;40318.193636127406;40433.797831010954;3228.580284563015;3283.7208837248863;3308.6360500344945;3657.250263695936;3906.687465002821;28839.279018024507;29202.56196157038;29276.560851587245;29999.400011999638;30025.699743002508;3890.105275786557;3922;3935.74417662851;3974.721769476138;4082.4080508326424;260161;261740;262340;262683;263134;40506.18987620231;54020.18969715457;54271.45728542743;54328;54541.727291364085;40377.39433908494;40428;40552;40617;40735.592644073775;12310.446029928675;12346.75306493883;12374.75250494985;12478.126531142732;12492.375381231084;10825.35047897113;10873.40196289196;10933.671989840317;10945.124390048777;10950.178736594788;13804.585862424143;13849.930750346184;14237.288135593179;14241.928790356189;14260;5684.261046063985;5719.942800572025;5750.654960702372;5848.561357898175;5935.73289201987;929.9814003720023;947.4504787223394;988.9307748457683;1025.6410256410197;1074.8172810622214;27878.024269150483;28083.57874631882;28166.71833281682;28538.429231415255;28611.14166575006;1036.8030074286012;1050.2333296693305;1063.2982231727024;1080.897314755097;1136.170595465315;14832.480863169952;14834.406623735145;14842.257887105601;14951;15088.773668394982;311922;316165;317830;318615;318967;52037.7398113007;52085.47914520882;52494.47505524972;52563.737181313845;52862.73568632132;1321.8281623388898;1345.9259740714356;1352.8241328627216;1362.0329566008245;1367.8632136786441;27109.322266943484;28297;28508;31218.843905780326;32775.67224327726;50752.492475074774;51440;51441.485585143666;51510.74244628753;51574.484255158466;300734;301318;303076;304438.47780760954;306721;41695;44599.33101003423;45294.320585191905;46051.3092303609;46087.53912460832;42862.57137428711;42913.57086429096;43108.13783724245;43171.35242971295;43434.56565434305;36583.45124823076;36830.079248017944;36977.26045479129;40381.798091009354;42369.788151059045;36189.0952726184;36236.63763362333;36672.26655466929;37140.257194856495;37309.62690373061;35848;36217;36326.636733632324;36359.454608181426;36582.08544786295;32039.67960320367;33154.5026824593;33302;33566.62234175499;33863;25078.62382064307;25135;25346.873265633552;25549.3612659685;25579.98889593947 05:57:09 INFO - 2015-12-19 05:57:09,163 DEBUG : BROWSER_OUTPUT: |2;jquery.html;343.8572992208232;355.5111721383098;358.3603268166322;358.39431360999924;359.4105666706601;16395.590110247234;17087.658246835057;17747.29010839565;17880.55298617532;18494.907525462386;16055.678886422265;16137.677246455063;16378.262978165982;16521.504354869372;16537.586560335996;4789.688670236432;4901.6078713703;4910.263460480921;4921.778519966592;4928.162212423878;4739.73931433771;4847.563719265266;4851.102546028978;4896.265560165986;4905.215165573509;4811.735354555498;4813.518648135194;5085.821996230133;5272.367315922094;5332.866678333034;5263.34208223972;5277.366715994086;5278.1291086970605;5309.920351194735;5604.439556044404;5419.918701219485;5437.075697131497;5451.563874889999;5457.672539647594;5462.863428414282;12334.5066197352;12484.126111172221;12683.429245683967;12855.485780568766;12930.741385172243;2055.9486012849648;2104.442322784463;2114.260008996854;2156.7088443060143;2216.0803266644325;9567.282453815991;9743.317967742261;9938.701838944844;11406.885931140749;11627.76744465106;1803.8196180381988;1827.3512902919406;1844.7786265648208;1863.2081365419779;1876.8592355573387;61958;64268.678656606884;65282.673586632234;65427;65429.672851635434;10382.688519344432;10385.84421233682;10409.42748148859;10510.842337364946;10553.84169237462;13733.862661373458;13800.93099534496;13807.240601767;13830.37763300654;13891.86108138926;7347.742829000959;7376.299251571092;7404.074490688664;7429.108506979089;7461.515001524884;6950.54821436605;7099.432045436352;7109.82225444368;7164.21193668696;7219.494635375473;4421.712588681726;4517.457905051415;4524.660650451229;4527.071950250191;4539.591436770706;1792.2024699008816;1854.5641774183057;1863.6552237836045;1907.5898681783483;1925.6052509235574;439.13490423864937;442.66799900074943;867.6616119713317;882.4264228251687;897.1521911793424;10364;10640.414777187174;10665.89334106649;10792.136629069655;10812.135029197645;426.6458839163487;443.08502941425854;444.8487514245172;453.5419226581177;453.65748859610784;9738.513074346254;10149.949250253701;10216.314308139223;10298.948505257425;10378;503299;503392.762506621;506991.3158183952;507739;508147;82288.58855705684;83807.5809620948;84507.15492844993;84667;84881.57559212288;1177.952881884715;1181.8936295733254;1218.415160722858;1228.342836582442;1243.3348158735034;15679.372825086868;15827.446039388566;15891.920540397225;16056.91971540135;16288.918555407146;35258.8237058813;35419.29161416706;35558;35717.28565428625;36406.81796591;44752.10495790131;46495;46641.53358466508;47065.764671176425;49339.766505837644;9548.140667339972;9672.371295865747;9877.901220987698;9895.65365212214;9966.700998970042;11961.342126183143;11968.640940771791;12017.579384721494;12135.939320303341;15366.308516116578;13834.515791947233;13985.650358741113;14956.775648365501;15079.623009424851;15406.69186616239;14818.777718334019;14833.480828170963;14850.257487125817;15355.385784568492;15396.07623542591;9152.17630413266;9265.953670231605;9352.251819854531;15142.167180805161;15749.370025198863;14346.713065738419;14676.926615366854;14939;14992.175430351435;15230.619234519225;9796.559154837907;10032.949835250776;10281.84577231357;10287.43419111926;10296.536655850654 05:57:09 INFO - 2015-12-19 05:57:09,164 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1519.1492764052139;1653.9338426462941;1724.7671564338814;1763.2858692229643;1772.5480002599336;7287;7352.19125896151;7425.443091768126;7618.047744031996;7634.847303053943;7548.92451075489;7562.470627056108;7636.350910172642;7641.388688904888;7660.540367577949;2179.3135162423864;2201.8458707890454;2204.2285200179967;2212.037763572843;2221.2336743823357;2217.4899773052207;2230.888455577223;2252.6170551006367;2272.602294598447;2284.1434462076722;2161.5352699169675;2179.226374637005;2179.814715749163;2193.2104442400723;2201.878896660683;2190.518086021073;2203.7465691445527;2243.450354663118;2253.86476811391;2254.549090181962;2202.2072054060527;2219.1567204462362;2221.489057516764;2225.043231410493;2233.944151396212;7218.061651985261;7290.781276561712;7300.744473943387;7303.963480182618;7307.561546307238;4401.625861801751;4461.286194208943;4464.620507256855;4466.575675310873;4479.216137175981;2992.5361568956846;3042.4523585754555;3065.432894914448;3081.275900163482;3103;976.6435251133356;980.3088822380205;982.6511588386091;1013.6806905824642;1019.938803671782;9706.757331066709;9711.90288097117;9741.805163896754;9743.220542356594;9759;3859.7684138951754;3862.092408284051;3891.747036442622;3905.433712111728;3907.199024200032;8144.511329320259;8148.592570371457;8165.79585510367;8169.428140030141;8179.509229446252;4389.18800021997;4418.469783625986;4428.844990425384;4433.844815431445;4451.821927122944;3873.0511024799102;3952.545457272393;4000.6799456043445;4077.082656402345;4084.9795751021647;3422.1273575238156;3422.828858557062;3471.7049050830456;3472.2013936794174;3482.1120614243205;1060.612876300145;1068.8503609494676;1080.7139503990227;1081.9621313254;1099.0273607857105;1245.5640525816073;1254.0907841814794;1258.9622311330675;1300.9739805203842;1312.330711337206;6957;6989.650517474106;7016.017757513952;7040.225575186725;7054.823629409306;431.20227578978637;432.71657064622724;438.72141190343723;442.46019855775904;456.18798538601345;5551.056320425538;5588.441155884379;5589.217509548666;5598.692071936001;5639.718014099279;8584.527850968258;8597.785055373666;8785.34109941757;8905.243054340453;8933.240674542736;8583.399162058597;8624.568771561397;8699.608517616722;8702.260307873776;8766.167214114648;6893.620850853251;6977.616231107237;7026.508144429943;7073.540219885691;7074.327938845801;6746.325367463214;6772.5936443813525;6784.660766961731;6803.217629972616;6816.147981502312;6973.19808222061;7790.493617914817;7801.843963120819;7868.921310786819;7874.34638725874;7700.191479894755;7775.727849525239;7906.723264685708;7941.047074351115;7965.721199757981;6419.133416988647;6649.966750166218;6660.333966603494;6720.32796720324;6736.79789606293;7168.211496735459;7170.42636589082;7183.5330703504105;7189;7221.783346499614;6645.0696902434665;6789.660516974231;6805.149356330458;6827.317268273133;6951.131108611424;6600.966995165186;6637.1703537057865;6747.021681856201;6776.7628133015105;6800.557963732342;6658.334166583303;6746.257911629815;6791.422729068085;6804.863902722017;6835.1114355135505;5741.5406767459335;5762.193292939076;5785.218995435563;5789.884202316014;5790.044642634087;6226.439620434183;6241.937580624136;6246.094316324016;6254.030625253093;6261.123442717932 05:57:09 INFO - 2015-12-19 05:57:09,165 DEBUG : BROWSER_OUTPUT: |4;prototype.html;328.382640635605;336.4616613418531;337.93410284994434;338.74932549913063;346.29356113528405;6189.845253868655;6271.498280137578;6284.497240220777;6328.050792381139;6339.302676705564;6075.969620151904;6258.186435763356;6266.686665666722;6274.152989346437;6313.810585682437;4753.595944344735;4774.665773395864;4785.258284965827;4794.688345257565;4803.207470767319;4763.976180119094;4788.736619485927;4797.90404191916;4800.039992001613;4807.134715751164;4640.164770341337;4764.404449443819;4787.473377928425;4788.186008378584;4798.088363210979;4754.144254034272;4776.211925032366;4777.832775852846;4782.402199725035;4792.257200133985;4086.5504794472586;4193.97903010483;4195;4196.454460920061;4199.832006719727;5925.259342582177;5941.821745347604;5961.046232602806;5975.312839023481;6000.699965001732;5576.470235327637;5580.051391263454;5589.720513974285;5591.9720401398135;5593.8321850344555;6549;6663.600183988976;6674.599524028526;6680.83297917551;6690.866182676368;4676.3453116563705;4686.062787442525;4692;4695.16888413519;4703.7883295251795;6850.6574671266735;6881.518293719441;6897.1033765610655;6915.412189963859;6918.273581273995;4701.976490117528;4703.377334819482;4704.929426058612;4707.435107787088;4713.387259656222;4639.280911458729;4720.527947205252;4732.645051621143;4760.7619619018915;4783.210770222874;5527.50252477279;5529.502344788908;5531.363893152339;5533.584981126432;5548.4174161713245;6692.397684208445;6736.865262694719;6738.157730283715;6739.797806065826;6740.427063699641;1676.1032847426568;1694.7966244050792;1697.7538256952673;1698.5753561609597;1698.9721218662778;1143.8341440491083;1155.1451925575013;1161.7269941563725;1175.6884425627215;1185.271058299157;6430.324815894357;6475.158229430238;6489.448396886318;6492.642904640195;6498.935010649833;6898.448124150056;6933.583984960918;6953.721851126;6955.130608673916;6976.197737260179;6319.115323854664;6430.196225471816;6481.870362592722;6483.578567393104;6513.381228783258;3772.2078363543415;3775.3770627846648;3789.7157713171623;3793.3551296279697;3796.468494410785;6974;7077.009218709385;7127.180374256923;7156.642167891585;7174.210836807947;7130.893036604351;7147.964260178665;7157.355837974607;7173.210946795848;7180.92819071813;6927.445804335641;7111.64441777909;7129;7132.821679458055;7138.678759455837;6939.861202775815;7009.7897063088185;7012.403945664676;7017.649117544204;7019.3682568569075;6870.45036397097;6888.483363747639;6890.552114112566;6899.551529150589;6899.655017249017;6950;7105.75129870452;7111.857762844817;7121.893171602326;7141.9642901785155;4675.298705194248;4696.178168820511;4718.457377401643;4719.457262414866;4727.669063165546;6989.720411183495;6993.685284162123;7005.124359455068;7029.507934444539;7032.472564557781;6700.597964122168;6708.530402871752;6792.150981127359;6809.591424514545;6817.693203805939;6734.764283250062;6786.762463313958;6793.6942837573415;6802.591844489149;6806.65966701653;6665.200175978914;6672.9666351669875;6673.466122710269;6678.866422671616;6695.263521012782;6709.966450167718;6848.9657551711925;6850.8972365413565;6856.280090590416;6859.519833611599;6602.636854973022;6610.173728283965;6639.070530125689;6663.400293973566;6668.66656667155 05:57:09 INFO - 2015-12-19 05:57:09,166 DEBUG : BROWSER_OUTPUT: |5;yui.html;664.9601023938562;728.6866647341641;748.9662965166567;758.6699785593268;762.3101093510371;13197.406116724747;13310.667233319173;13510.256935868523;13577.456901723932;13588.660283492905;12160.331181784999;12191.146619736623;12269.877301226985;12273.877261227384;12667.859892609664;824.3734761581196;835.6907944060675;837.5560952695051;839.0309192882231;840.1808236968967;826.6156237349632;833.9249467547919;834.8163404051099;835.5488036460334;836.1596595421623;870.9390342676015;882.4308321132897;883.1124719656736;884.3102380143498;888.6312180445108;873.0745409865563;877.714742708619;878.5826732302137;882.4616983639975;884.8451520983803;646.3116780628662;646.9482441404676;647.6988200486796;648.1023782061878;650.1158424542618;12362.320072396014;12391.566295179626;12421;12424.627261182179;12546;9401.858972115424;9446.385984911027;9583.897851747068;9587.664431744855;9670.613175473043;9761;9769.609215631366;9873.407595544219;9965.103140717298;10032.197424206119;3525.1715846775987;3545.840437180333;3624.1845584743487;3646.033801042726;3732.832022558964;42395;42639;48036.51963480355;51323.48676513224;51704.74147629275;9014.459132452073;9076.500792456478;9275.860862087075;9349.39228950116;9560.713178604654;17575.91212043957;17748.37880674196;17874.838135521408;17944.37194698179;17950.640987180184;7287.744928927462;7389.076365454318;7427.220141885133;7596.3922886168975;7674.155842857282;4285.507166675872;4300.376445415397;4326.6754993375625;4343.587359200871;4411.933820992687;2936.4861149298786;2954.6749857515656;2959.0383125484304;2968.539876319174;2978.880844766185;523.657004661947;538.7306346826587;546.3471151973354;546.6064433607796;547.0890966540666;500.3563294577369;571.8770464350184;588.4233451217831;605.449041372349;624.095062159861;8386.706465273686;8444.788880278042;8448.957755211184;8579.699710510102;8699.956500217584;530.7425898439254;545.8248878220289;563.5885803363485;647.7894684227645;660.9834754131185;3970.50368703912;3987.8404863805704;4001.751574924564;4032.415299781574;4036.1524079942956;61601.383986160465;63191;63296;63467.365326346146;63478.68260658667;52845.471545284825;52963.73518132462;53148.73425632847;53407.19889201588;53800.73099634477;41933;44042.779786101506;47732.5226747735;54244.18633720497;54356.45643543514;8232.34141268709;8434.789130271793;8570.314374849871;8951;9015.954920225618;35146.47280290849;35532.64467355293;35899.46150807688;36421.81789091037;36437.817810910776;45247.32129018133;46518.30222546732;47018.529814702786;47028;47831.28253076137;11050.889491104987;11160.55357785712;11175.329480231212;11294.717632059264;11342.149338799458;12130.33283169434;12150.75698486043;12259.877401226231;12413.875861241271;12436.440360183995;11068.723281918017;11144.2199046066;11326.433678316216;11366.772664546497;11701.88298117008;11758.470868810753;11901.166918315635;12019.519219231133;12363;12374.443150058449;11462.312261264351;11551.76896462083;11624.94187529057;11884.227525210834;11887.821682674594;9947.900520994697;10114.754343843875;10307.587696492057;10396.896031039592;10928.907109289008;8901.154390332907;9226.630934762803;9401.811963760823;9784.412935223909;9792.314537982273 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,309 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: __startTimestamp1450533429154__endTimestamp 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:57:09 INFO - 2015-12-19 05:57:09,167 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:57:09 INFO - 2015-12-19 05:57:09,168 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,168 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5348.78 Median:5548.42 stddev:581.99 (10.5%) stddev-sans-first:557.54 05:57:09 INFO - 2015-12-19 05:57:09,168 DEBUG : BROWSER_OUTPUT: Values: 3146.2 3207.3 3228.9 3239.6 3254.1 5149.8 5301.6 5340.9 5356.7 5358.2 5269.0 5310.7 5335.2 5343.8 5353.3 4561.5 4859.0 5029.9 5047.3 5079.2 4828.1 4977.2 4990.0 5047.8 5069.9 4854.1 4915.8 4941.9 4954.5 4996.3 4925.9 4950.8 4952.0 4982.0 4986.4 4641.4 4749.1 4925.7 4948.4 4996.8 5054.8 5078.3 5123.9 5126.2 5144.0 5760.5 5782.6 5795.3 5807.5 5815.2 5635.9 5820.6 5853.8 5857.5 5860.9 5288.0 5369.8 5387.5 5395.1 5401.8 5766.2 5780.8 5785.5 5789.3 5807.1 5428.6 5656.4 5665.9 5672.9 5679.6 5702.5 5727.3 5754.1 5789.9 5818.4 5717.4 5762.7 5771.7 5778.6 5789.4 5750.7 5763.4 5769.5 5784.2 5794.0 4399.2 4431.8 4473.2 4476.1 4476.7 3964.3 3994.1 4015.6 4026.8 4043.6 5477.2 5478.3 5490.8 5512.1 5517.3 5636.4 5748.0 5794.4 5814.6 5864.5 5540.0 5548.4 5550.8 5558.3 5605.7 5021.2 5062.1 5062.4 5063.4 5111.2 5684.4 5803.4 5858.0 5898.7 5928.1 5736.6 5790.8 5823.4 5926.2 5967.9 5787.2 5824.9 5827.4 5831.2 5849.5 5616.4 5627.4 5674.1 5690.8 5695.7 5716.9 5778.8 5786.1 5789.5 5840.1 5705.7 5725.0 5727.7 5750.9 5751.6 5350.4 5662.6 5679.7 5696.1 5703.7 5410.2 5415.9 5507.4 5511.7 5563.9 5443.5 5612.1 5617.9 5644.3 5696.1 5398.5 5506.6 5538.2 5539.6 5606.2 05:57:09 INFO - 2015-12-19 05:57:09,168 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,168 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46041.99 Median:38383.33 stddev:77082.21 (200.8%) stddev-sans-first:77222.77 05:57:09 INFO - 2015-12-19 05:57:09,169 DEBUG : BROWSER_OUTPUT: Values: 550.9 594.9 600.7 602.4 613.3 37546.0 38314.4 38452.2 38589.4 38634.4 42716.6 43133.1 43215.0 43286.1 43373.6 8619.3 8802.9 8820.8 8859.8 8880.0 8503.2 8654.7 8660.8 8664.0 8687.6 8551.2 8637.6 8655.3 8658.7 8666.6 8563.1 8648.0 8652.8 8655.5 8663.4 8461.9 8650.9 8658.7 8662.7 8663.9 39413.8 40237.4 40269.8 40318.2 40433.8 3228.6 3283.7 3308.6 3657.3 3906.7 28839.3 29202.6 29276.6 29999.4 30025.7 3890.1 3922.0 3935.7 3974.7 4082.4 260161.0 261740.0 262340.0 262683.0 263134.0 40506.2 54020.2 54271.5 54328.0 54541.7 40377.4 40428.0 40552.0 40617.0 40735.6 12310.4 12346.8 12374.8 12478.1 12492.4 10825.4 10873.4 10933.7 10945.1 10950.2 13804.6 13849.9 14237.3 14241.9 14260.0 5684.3 5719.9 5750.7 5848.6 5935.7 930.0 947.5 988.9 1025.6 1074.8 27878.0 28083.6 28166.7 28538.4 28611.1 1036.8 1050.2 1063.3 1080.9 1136.2 14832.5 14834.4 14842.3 14951.0 15088.8 311922.0 316165.0 317830.0 318615.0 318967.0 52037.7 52085.5 52494.5 52563.7 52862.7 1321.8 1345.9 1352.8 1362.0 1367.9 27109.3 28297.0 28508.0 31218.8 32775.7 50752.5 51440.0 51441.5 51510.7 51574.5 300734.0 301318.0 303076.0 304438.5 306721.0 41695.0 44599.3 45294.3 46051.3 46087.5 42862.6 42913.6 43108.1 43171.4 43434.6 36583.5 36830.1 36977.3 40381.8 42369.8 36189.1 36236.6 36672.3 37140.3 37309.6 35848.0 36217.0 36326.6 36359.5 36582.1 32039.7 33154.5 33302.0 33566.6 33863.0 25078.6 25135.0 25346.9 25549.4 25580.0 05:57:09 INFO - 2015-12-19 05:57:09,169 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27694.30 Median:17956.76 stddev:82909.75 (461.7%) stddev-sans-first:83116.90 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: Values: 343.9 355.5 358.4 358.4 359.4 16395.6 17087.7 17747.3 17880.6 18494.9 16055.7 16137.7 16378.3 16521.5 16537.6 4789.7 4901.6 4910.3 4921.8 4928.2 4739.7 4847.6 4851.1 4896.3 4905.2 4811.7 4813.5 5085.8 5272.4 5332.9 5263.3 5277.4 5278.1 5309.9 5604.4 5419.9 5437.1 5451.6 5457.7 5462.9 12334.5 12484.1 12683.4 12855.5 12930.7 2055.9 2104.4 2114.3 2156.7 2216.1 9567.3 9743.3 9938.7 11406.9 11627.8 1803.8 1827.4 1844.8 1863.2 1876.9 61958.0 64268.7 65282.7 65427.0 65429.7 10382.7 10385.8 10409.4 10510.8 10553.8 13733.9 13800.9 13807.2 13830.4 13891.9 7347.7 7376.3 7404.1 7429.1 7461.5 6950.5 7099.4 7109.8 7164.2 7219.5 4421.7 4517.5 4524.7 4527.1 4539.6 1792.2 1854.6 1863.7 1907.6 1925.6 439.1 442.7 867.7 882.4 897.2 10364.0 10640.4 10665.9 10792.1 10812.1 426.6 443.1 444.8 453.5 453.7 9738.5 10149.9 10216.3 10298.9 10378.0 503299.0 503392.8 506991.3 507739.0 508147.0 82288.6 83807.6 84507.2 84667.0 84881.6 1178.0 1181.9 1218.4 1228.3 1243.3 15679.4 15827.4 15891.9 16056.9 16288.9 35258.8 35419.3 35558.0 35717.3 36406.8 44752.1 46495.0 46641.5 47065.8 49339.8 9548.1 9672.4 9877.9 9895.7 9966.7 11961.3 11968.6 12017.6 12135.9 15366.3 13834.5 13985.7 14956.8 15079.6 15406.7 14818.8 14833.5 14850.3 15355.4 15396.1 9152.2 9266.0 9352.3 15142.2 15749.4 14346.7 14676.9 14939.0 14992.2 15230.6 9796.6 10032.9 10281.8 10287.4 10296.5 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5154.69 Median:6257.58 stddev:2632.75 (42.1%) stddev-sans-first:2625.96 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: Values: 1519.1 1653.9 1724.8 1763.3 1772.5 7287.0 7352.2 7425.4 7618.0 7634.8 7548.9 7562.5 7636.4 7641.4 7660.5 2179.3 2201.8 2204.2 2212.0 2221.2 2217.5 2230.9 2252.6 2272.6 2284.1 2161.5 2179.2 2179.8 2193.2 2201.9 2190.5 2203.7 2243.5 2253.9 2254.5 2202.2 2219.2 2221.5 2225.0 2233.9 7218.1 7290.8 7300.7 7304.0 7307.6 4401.6 4461.3 4464.6 4466.6 4479.2 2992.5 3042.5 3065.4 3081.3 3103.0 976.6 980.3 982.7 1013.7 1019.9 9706.8 9711.9 9741.8 9743.2 9759.0 3859.8 3862.1 3891.7 3905.4 3907.2 8144.5 8148.6 8165.8 8169.4 8179.5 4389.2 4418.5 4428.8 4433.8 4451.8 3873.1 3952.5 4000.7 4077.1 4085.0 3422.1 3422.8 3471.7 3472.2 3482.1 1060.6 1068.9 1080.7 1082.0 1099.0 1245.6 1254.1 1259.0 1301.0 1312.3 6957.0 6989.7 7016.0 7040.2 7054.8 431.2 432.7 438.7 442.5 456.2 5551.1 5588.4 5589.2 5598.7 5639.7 8584.5 8597.8 8785.3 8905.2 8933.2 8583.4 8624.6 8699.6 8702.3 8766.2 6893.6 6977.6 7026.5 7073.5 7074.3 6746.3 6772.6 6784.7 6803.2 6816.1 6973.2 7790.5 7801.8 7868.9 7874.3 7700.2 7775.7 7906.7 7941.0 7965.7 6419.1 6650.0 6660.3 6720.3 6736.8 7168.2 7170.4 7183.5 7189.0 7221.8 6645.1 6789.7 6805.1 6827.3 6951.1 6601.0 6637.2 6747.0 6776.8 6800.6 6658.3 6746.3 6791.4 6804.9 6835.1 5741.5 5762.2 5785.2 5789.9 5790.0 6226.4 6241.9 6246.1 6254.0 6261.1 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,170 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5607.17 Median:6384.75 stddev:1700.09 (26.6%) stddev-sans-first:1658.05 05:57:09 INFO - 2015-12-19 05:57:09,171 DEBUG : BROWSER_OUTPUT: Values: 328.4 336.5 337.9 338.7 346.3 6189.8 6271.5 6284.5 6328.1 6339.3 6076.0 6258.2 6266.7 6274.2 6313.8 4753.6 4774.7 4785.3 4794.7 4803.2 4764.0 4788.7 4797.9 4800.0 4807.1 4640.2 4764.4 4787.5 4788.2 4798.1 4754.1 4776.2 4777.8 4782.4 4792.3 4086.6 4194.0 4195.0 4196.5 4199.8 5925.3 5941.8 5961.0 5975.3 6000.7 5576.5 5580.1 5589.7 5592.0 5593.8 6549.0 6663.6 6674.6 6680.8 6690.9 4676.3 4686.1 4692.0 4695.2 4703.8 6850.7 6881.5 6897.1 6915.4 6918.3 4702.0 4703.4 4704.9 4707.4 4713.4 4639.3 4720.5 4732.6 4760.8 4783.2 5527.5 5529.5 5531.4 5533.6 5548.4 6692.4 6736.9 6738.2 6739.8 6740.4 1676.1 1694.8 1697.8 1698.6 1699.0 1143.8 1155.1 1161.7 1175.7 1185.3 6430.3 6475.2 6489.4 6492.6 6498.9 6898.4 6933.6 6953.7 6955.1 6976.2 6319.1 6430.2 6481.9 6483.6 6513.4 3772.2 3775.4 3789.7 3793.4 3796.5 6974.0 7077.0 7127.2 7156.6 7174.2 7130.9 7148.0 7157.4 7173.2 7180.9 6927.4 7111.6 7129.0 7132.8 7138.7 6939.9 7009.8 7012.4 7017.6 7019.4 6870.5 6888.5 6890.6 6899.6 6899.7 6950.0 7105.8 7111.9 7121.9 7142.0 4675.3 4696.2 4718.5 4719.5 4727.7 6989.7 6993.7 7005.1 7029.5 7032.5 6700.6 6708.5 6792.2 6809.6 6817.7 6734.8 6786.8 6793.7 6802.6 6806.7 6665.2 6673.0 6673.5 6678.9 6695.3 6710.0 6849.0 6850.9 6856.3 6859.5 6602.6 6610.2 6639.1 6663.4 6668.7 05:57:09 INFO - 2015-12-19 05:57:09,171 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,172 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13988.89 Median:52904.60 stddev:16778.99 (31.7%) stddev-sans-first:16796.23 05:57:09 INFO - 2015-12-19 05:57:09,173 DEBUG : BROWSER_OUTPUT: Values: 665.0 728.7 749.0 758.7 762.3 13197.4 13310.7 13510.3 13577.5 13588.7 12160.3 12191.1 12269.9 12273.9 12667.9 824.4 835.7 837.6 839.0 840.2 826.6 833.9 834.8 835.5 836.2 870.9 882.4 883.1 884.3 888.6 873.1 877.7 878.6 882.5 884.8 646.3 646.9 647.7 648.1 650.1 12362.3 12391.6 12421.0 12424.6 12546.0 9401.9 9446.4 9583.9 9587.7 9670.6 9761.0 9769.6 9873.4 9965.1 10032.2 3525.2 3545.8 3624.2 3646.0 3732.8 42395.0 42639.0 48036.5 51323.5 51704.7 9014.5 9076.5 9275.9 9349.4 9560.7 17575.9 17748.4 17874.8 17944.4 17950.6 7287.7 7389.1 7427.2 7596.4 7674.2 4285.5 4300.4 4326.7 4343.6 4411.9 2936.5 2954.7 2959.0 2968.5 2978.9 523.7 538.7 546.3 546.6 547.1 500.4 571.9 588.4 605.4 624.1 8386.7 8444.8 8449.0 8579.7 8700.0 530.7 545.8 563.6 647.8 661.0 3970.5 3987.8 4001.8 4032.4 4036.2 61601.4 63191.0 63296.0 63467.4 63478.7 52845.5 52963.7 53148.7 53407.2 53800.7 41933.0 44042.8 47732.5 54244.2 54356.5 8232.3 8434.8 8570.3 8951.0 9016.0 35146.5 35532.6 35899.5 36421.8 36437.8 45247.3 46518.3 47018.5 47028.0 47831.3 11050.9 11160.6 11175.3 11294.7 11342.1 12130.3 12150.8 12259.9 12413.9 12436.4 11068.7 11144.2 11326.4 11366.8 11701.9 11758.5 11901.2 12019.5 12363.0 12374.4 11462.3 11551.8 11624.9 11884.2 11887.8 9947.9 10114.8 10307.6 10396.9 10928.9 8901.2 9226.6 9401.8 9784.4 9792.3 05:57:09 INFO - 2015-12-19 05:57:09,173 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:57:09 INFO - 2015-12-19 05:57:09,173 DEBUG : BROWSER_OUTPUT: 05:57:09 INFO - 2015-12-19 05:57:09,861 INFO : Browser exited with error code: 0 05:57:09 INFO - 2015-12-19 05:57:09,880 INFO : Completed test dromaeo_css (00:18:30) 05:57:09 INFO - 2015-12-19 05:57:09,880 INFO : Starting test kraken 05:57:09 INFO - 2015-12-19 05:57:09,880 DEBUG : operating with platform_type : linux_ 05:57:09 INFO - 2015-12-19 05:57:09,880 INFO : Initialising browser for kraken test... 05:57:09 INFO - 2015-12-19 05:57:09,886 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmiebXt/profile http://localhost:54935/getInfo.html 05:57:14 INFO - 2015-12-19 05:57:14,608 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:57:14 INFO - 2015-12-19 05:57:14,609 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:57:14 INFO - 2015-12-19 05:57:14,609 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:57:14 INFO - 2015-12-19 05:57:14,609 DEBUG : BROWSER_OUTPUT: __metrics 05:57:14 INFO - 2015-12-19 05:57:14,710 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:57:15 INFO - 2015-12-19 05:57:15,609 INFO : Browser initialized. 05:57:15 INFO - 2015-12-19 05:57:15,610 INFO : Running cycle 1/1 for kraken test... 05:57:15 INFO - 2015-12-19 05:57:15,610 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmiebXt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:57:27 INFO - 2015-12-19 05:57:27,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 151523328 05:57:27 INFO - 2015-12-19 05:57:27,360 DEBUG : BROWSER_OUTPUT: 05:57:29 INFO - 2015-12-19 05:57:29,875 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54935/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:30 INFO - 2015-12-19 05:57:30,497 DEBUG : BROWSER_OUTPUT: RSS: Main: 152244224 05:57:30 INFO - 2015-12-19 05:57:30,497 DEBUG : BROWSER_OUTPUT: 05:57:33 INFO - 2015-12-19 05:57:33,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54935/tests/kraken/driver.html?testName=audio-dft) 05:57:33 INFO - 2015-12-19 05:57:33,740 DEBUG : BROWSER_OUTPUT: RSS: Main: 152068096 05:57:33 INFO - 2015-12-19 05:57:33,740 DEBUG : BROWSER_OUTPUT: 05:57:36 INFO - 2015-12-19 05:57:36,598 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54935/tests/kraken/driver.html?testName=audio-fft) 05:57:37 INFO - 2015-12-19 05:57:37,171 DEBUG : BROWSER_OUTPUT: RSS: Main: 146059264 05:57:37 INFO - 2015-12-19 05:57:37,171 DEBUG : BROWSER_OUTPUT: 05:57:39 INFO - 2015-12-19 05:57:39,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54935/tests/kraken/driver.html?testName=audio-oscillator) 05:57:39 INFO - 2015-12-19 05:57:39,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 145825792 05:57:39 INFO - 2015-12-19 05:57:39,789 DEBUG : BROWSER_OUTPUT: 05:57:42 INFO - 2015-12-19 05:57:42,016 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54935/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:42 INFO - 2015-12-19 05:57:42,597 DEBUG : BROWSER_OUTPUT: RSS: Main: 146800640 05:57:42 INFO - 2015-12-19 05:57:42,597 DEBUG : BROWSER_OUTPUT: 05:57:45 INFO - 2015-12-19 05:57:45,608 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54935/tests/kraken/driver.html?testName=imaging-darkroom) 05:57:46 INFO - 2015-12-19 05:57:46,195 DEBUG : BROWSER_OUTPUT: RSS: Main: 146743296 05:57:46 INFO - 2015-12-19 05:57:46,195 DEBUG : BROWSER_OUTPUT: 05:57:49 INFO - 2015-12-19 05:57:49,558 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54935/tests/kraken/driver.html?testName=imaging-desaturate) 05:57:50 INFO - 2015-12-19 05:57:50,144 DEBUG : BROWSER_OUTPUT: RSS: Main: 147931136 05:57:50 INFO - 2015-12-19 05:57:50,144 DEBUG : BROWSER_OUTPUT: 05:57:52 INFO - 2015-12-19 05:57:52,941 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54935/tests/kraken/driver.html?testName=json-parse-financial) 05:57:53 INFO - 2015-12-19 05:57:53,543 DEBUG : BROWSER_OUTPUT: RSS: Main: 148406272 05:57:53 INFO - 2015-12-19 05:57:53,544 DEBUG : BROWSER_OUTPUT: 05:57:55 INFO - 2015-12-19 05:57:55,137 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54935/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:55 INFO - 2015-12-19 05:57:55,710 DEBUG : BROWSER_OUTPUT: RSS: Main: 149364736 05:57:55 INFO - 2015-12-19 05:57:55,710 DEBUG : BROWSER_OUTPUT: 05:57:57 INFO - 2015-12-19 05:57:57,526 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:58 INFO - 2015-12-19 05:57:58,132 DEBUG : BROWSER_OUTPUT: RSS: Main: 148135936 05:57:58 INFO - 2015-12-19 05:57:58,133 DEBUG : BROWSER_OUTPUT: 05:58:00 INFO - 2015-12-19 05:58:00,205 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:58:00 INFO - 2015-12-19 05:58:00,793 DEBUG : BROWSER_OUTPUT: RSS: Main: 148762624 05:58:00 INFO - 2015-12-19 05:58:00,793 DEBUG : BROWSER_OUTPUT: 05:58:03 INFO - 2015-12-19 05:58:03,253 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:58:03 INFO - 2015-12-19 05:58:03,854 DEBUG : BROWSER_OUTPUT: RSS: Main: 149008384 05:58:03 INFO - 2015-12-19 05:58:03,855 DEBUG : BROWSER_OUTPUT: 05:58:06 INFO - 2015-12-19 05:58:06,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:07 INFO - 2015-12-19 05:58:07,028 DEBUG : BROWSER_OUTPUT: RSS: Main: 149495808 05:58:07 INFO - 2015-12-19 05:58:07,029 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,703 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54935/tests/kraken/driver.html?testName=ai-astar) 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: RSS: Main: 148127744 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:58:08 INFO - 2015-12-19 05:58:08,953 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;107;112;113;111;122;111;112;112;111 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;161;127;148;166;128;148;147;124;161;126 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;162;160;161;163;159;160;162;171;163 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;72;73;72;73;135;71;74;74 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;92;148;95;94;93;132;92;93;139 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;114;110;109;110;111;111;110;111 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;147;146;147;146;146;146;147;147 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;97;97;96;96;95;96;96;96 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;63;63;71;61;61;63;61;59;66 05:58:08 INFO - 2015-12-19 05:58:08,954 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;70;67;67;68;69;67;68;68;67 05:58:08 INFO - 2015-12-19 05:58:08,955 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;85;86;85;88;86;87;84;88;83 05:58:08 INFO - 2015-12-19 05:58:08,955 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;125;122;118;132;125;129;117;118;132;125 05:58:08 INFO - 2015-12-19 05:58:08,955 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;152;149;153;143;166;141;150;148;151;141 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;68;67;71;69;65;65;67;71;66 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,736 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: __startTimestamp1450533488952__endTimestamp 05:58:08 INFO - 2015-12-19 05:58:08,956 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,957 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:58:08 INFO - 2015-12-19 05:58:08,957 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:58:08 INFO - 2015-12-19 05:58:08,957 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,958 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:112.50 Median:112.00 stddev:3.81 (3.4%) stddev-sans-first:4.00 05:58:08 INFO - 2015-12-19 05:58:08,958 DEBUG : BROWSER_OUTPUT: Values: 114.0 107.0 112.0 113.0 111.0 122.0 111.0 112.0 112.0 111.0 05:58:08 INFO - 2015-12-19 05:58:08,958 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,958 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.60 Median:148.00 stddev:16.23 (11.0%) stddev-sans-first:15.95 05:58:08 INFO - 2015-12-19 05:58:08,958 DEBUG : BROWSER_OUTPUT: Values: 161.0 127.0 148.0 166.0 128.0 148.0 147.0 124.0 161.0 126.0 05:58:08 INFO - 2015-12-19 05:58:08,958 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:162.50 Median:162.50 stddev:3.37 (2.1%) stddev-sans-first:3.54 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: Values: 164.0 162.0 160.0 161.0 163.0 159.0 160.0 162.0 171.0 163.0 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:78.70 Median:72.50 stddev:19.81 (27.3%) stddev-sans-first:20.82 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 72.0 73.0 72.0 73.0 135.0 71.0 74.0 74.0 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,959 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.10 Median:93.00 stddev:22.81 (24.5%) stddev-sans-first:23.61 05:58:08 INFO - 2015-12-19 05:58:08,960 DEBUG : BROWSER_OUTPUT: Values: 93.0 92.0 148.0 95.0 94.0 93.0 132.0 92.0 93.0 139.0 05:58:08 INFO - 2015-12-19 05:58:08,960 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,960 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.60 Median:110.50 stddev:1.35 (1.2%) stddev-sans-first:1.41 05:58:08 INFO - 2015-12-19 05:58:08,960 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 114.0 110.0 109.0 110.0 111.0 111.0 110.0 111.0 05:58:08 INFO - 2015-12-19 05:58:08,960 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,960 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.53 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 147.0 146.0 147.0 146.0 146.0 146.0 147.0 147.0 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 97.0 97.0 96.0 96.0 95.0 96.0 96.0 96.0 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:63.00 Median:63.00 stddev:3.37 (5.3%) stddev-sans-first:3.55 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: Values: 62.0 63.0 63.0 71.0 61.0 61.0 63.0 61.0 59.0 66.0 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,961 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.05 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: Values: 67.0 70.0 67.0 67.0 68.0 69.0 67.0 68.0 68.0 67.0 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:86.00 stddev:1.84 (2.1%) stddev-sans-first:1.72 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: Values: 83.0 85.0 86.0 85.0 88.0 86.0 87.0 84.0 88.0 83.0 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.30 Median:125.00 stddev:5.58 (4.5%) stddev-sans-first:5.91 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: Values: 125.0 122.0 118.0 132.0 125.0 129.0 117.0 118.0 132.0 125.0 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.40 Median:150.50 stddev:7.32 (4.9%) stddev-sans-first:7.70 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: Values: 152.0 149.0 153.0 143.0 166.0 141.0 150.0 148.0 151.0 141.0 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: 05:58:08 INFO - 2015-12-19 05:58:08,962 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:2.16 (3.2%) stddev-sans-first:2.29 05:58:08 INFO - 2015-12-19 05:58:08,963 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 67.0 71.0 69.0 65.0 65.0 67.0 71.0 66.0 05:58:08 INFO - 2015-12-19 05:58:08,963 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:58:08 INFO - 2015-12-19 05:58:08,963 DEBUG : BROWSER_OUTPUT: 05:58:09 INFO - 2015-12-19 05:58:09,809 INFO : Browser exited with error code: 0 05:58:09 INFO - 2015-12-19 05:58:09,862 INFO : Completed test kraken (00:00:59) 05:58:09 INFO - 2015-12-19 05:58:09,862 INFO : Starting test v8_7 05:58:09 INFO - 2015-12-19 05:58:09,862 DEBUG : operating with platform_type : linux_ 05:58:09 INFO - 2015-12-19 05:58:09,862 INFO : Initialising browser for v8_7 test... 05:58:09 INFO - 2015-12-19 05:58:09,868 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKEsZ58/profile http://localhost:54935/getInfo.html 05:58:14 INFO - 2015-12-19 05:58:14,553 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:58:14 INFO - 2015-12-19 05:58:14,553 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:58:14 INFO - 2015-12-19 05:58:14,553 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/670 05:58:14 INFO - 2015-12-19 05:58:14,553 DEBUG : BROWSER_OUTPUT: __metrics 05:58:14 INFO - 2015-12-19 05:58:14,682 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:58:15 INFO - 2015-12-19 05:58:15,593 INFO : Browser initialized. 05:58:15 INFO - 2015-12-19 05:58:15,594 INFO : Running cycle 1/1 for v8_7 test... 05:58:15 INFO - 2015-12-19 05:58:15,594 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpKEsZ58/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:58:27 INFO - 2015-12-19 05:58:27,432 DEBUG : BROWSER_OUTPUT: RSS: Main: 151285760 05:58:27 INFO - 2015-12-19 05:58:27,433 DEBUG : BROWSER_OUTPUT: 05:58:48 INFO - 2015-12-19 05:58:48,830 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54935/tests/v8_7/run.html (next: http://localhost:54935/tests/v8_7/run.html) 05:58:49 INFO - 2015-12-19 05:58:49,081 DEBUG : BROWSER_OUTPUT: RSS: Main: 146198528 05:58:49 INFO - 2015-12-19 05:58:49,081 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,081 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:58:49 INFO - 2015-12-19 05:58:49,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:58:49 INFO - 2015-12-19 05:58:49,081 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:58:49 INFO - 2015-12-19 05:58:49,082 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:58:49 INFO - 2015-12-19 05:58:49,082 DEBUG : BROWSER_OUTPUT: |0;Richards;166.91704223001167 05:58:49 INFO - 2015-12-19 05:58:49,082 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.11136192626034 05:58:49 INFO - 2015-12-19 05:58:49,082 DEBUG : BROWSER_OUTPUT: |2;Encrypt;281.8489289740699 05:58:49 INFO - 2015-12-19 05:58:49,082 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5208.333333333333 05:58:49 INFO - 2015-12-19 05:58:49,082 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1075.268817204301 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: |5;Earley;712.2507122507122 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: |6;Boyer;12192.77108433735 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: |7;RegExp;25025 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: |8;Splay;1092.5925925925926 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:58:49 INFO - 2015-12-19 05:58:49,083 DEBUG : BROWSER_OUTPUT: __startTimestamp1450533529078__endTimestamp 05:58:49 INFO - 2015-12-19 05:58:49,084 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,084 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:58:49 INFO - 2015-12-19 05:58:49,084 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:58:49 INFO - 2015-12-19 05:58:49,084 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,084 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.92 Median:166.92 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,084 DEBUG : BROWSER_OUTPUT: Values: 166.9 05:58:49 INFO - 2015-12-19 05:58:49,085 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,085 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.11 Median:188.11 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,085 DEBUG : BROWSER_OUTPUT: Values: 188.1 05:58:49 INFO - 2015-12-19 05:58:49,085 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,085 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:281.85 Median:281.85 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,086 DEBUG : BROWSER_OUTPUT: Values: 281.8 05:58:49 INFO - 2015-12-19 05:58:49,086 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,086 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,086 DEBUG : BROWSER_OUTPUT: Values: 5208.3 05:58:49 INFO - 2015-12-19 05:58:49,087 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,087 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1075.27 Median:1075.27 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,087 DEBUG : BROWSER_OUTPUT: Values: 1075.3 05:58:49 INFO - 2015-12-19 05:58:49,087 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,088 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:712.25 Median:712.25 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,088 DEBUG : BROWSER_OUTPUT: Values: 712.3 05:58:49 INFO - 2015-12-19 05:58:49,088 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,088 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12192.77 Median:12192.77 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,088 DEBUG : BROWSER_OUTPUT: Values: 12192.8 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25025.00 Median:25025.00 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: Values: 25025.0 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:1092.59 Median:1092.59 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: Values: 1092.6 05:58:49 INFO - 2015-12-19 05:58:49,089 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,090 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 05:58:49 INFO - 2015-12-19 05:58:49,091 DEBUG : BROWSER_OUTPUT: Values: 4840.6 05:58:49 INFO - 2015-12-19 05:58:49,091 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:58:49 INFO - 2015-12-19 05:58:49,091 DEBUG : BROWSER_OUTPUT: 05:58:49 INFO - 2015-12-19 05:58:49,933 INFO : Browser exited with error code: 0 05:58:49 INFO - 2015-12-19 05:58:49,945 INFO : Completed test v8_7 (00:00:40) 05:58:50 INFO - 2015-12-19 05:58:50,133 INFO : Completed test suite (00:20:10) 05:58:50 INFO - 2015-12-19 05:58:50,133 DEBUG : Working with test: dromaeo_css 05:58:50 INFO - 2015-12-19 05:58:50,133 DEBUG : Generating results file: dromaeo_css 05:58:50 INFO - 2015-12-19 05:58:50,134 DEBUG : Working with test: kraken 05:58:50 INFO - 2015-12-19 05:58:50,134 DEBUG : Generating results file: kraken 05:58:50 INFO - 2015-12-19 05:58:50,135 INFO : javascript benchmark 05:58:50 INFO - 2015-12-19 05:58:50,135 DEBUG : Working with test: v8_7 05:58:50 INFO - 2015-12-19 05:58:50,135 DEBUG : Generating results file: v8_7 05:58:50 INFO - 2015-12-19 05:58:50,135 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:58:50 INFO - 2015-12-19 05:58:50,271 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,1,43]] 05:58:50 INFO - 2015-12-19 05:58:50,271 DEBUG : process_Request line: dromaeo_css 6884.50 graph.html#tests=[[72,1,43]] 05:58:50 INFO - 2015-12-19 05:58:50,271 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:58:50 INFO - 2015-12-19 05:58:50,301 DEBUG : process_Request line: kraken 1515.10 graph.html#tests=[[232,1,43]] 05:58:50 INFO - 2015-12-19 05:58:50,302 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:58:50 INFO - 2015-12-19 05:58:50,335 DEBUG : process_Request line: v8_7 20138.51 graph.html#tests=[[230,1,43]] 05:58:50 INFO - 2015-12-19 05:58:50,336 INFO : javascript benchmark 05:58:50 INFO - 2015-12-19 05:58:50,340 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3146.2134466383404, 3207.3264614430955, 3228.886988955387, 3239.6112466504023, 3254.088855120567, 5149.845504634867, 5301.602379821515, 5340.946590534093, 5356.651817631856, 5358.223057656637, 5268.973655131728, 5310.73446327683, 5335.226392173133, 5343.759530821113, 5353.277307563478, 4561.543845615445, 4858.975705121469, 5029.924551131736, 5047.293378926953, 5079.212722028073, 4828.082664293791, 4977.178765503688, 4990.0, 5047.823326183584, 5069.873253168682, 4854.12625727369, 4915.827946021872, 4941.851744447672, 4954.47977962316, 4996.32549605804, 4925.926111108336, 4950.777215025332, 4952.009598080398, 4982.0, 4986.401631804207, 4641.396618439615, 4749.076623967264, 4925.679830810986, 4948.356713627206, 4996.825111121093, 5054.797808087672, 5078.339815823956, 5123.87190320245, 5126.2054381570915, 5143.974280128576, 5760.481556659921, 5782.566307526952, 5795.275590551181, 5807.477327040587, 5815.214945982323, 5635.887282254373, 5820.621659592113, 5853.76584936602, 5857.531397488234, 5860.853478663067, 5288.048151332759, 5369.785208591652, 5387.515123638891, 5395.136778115482, 5401.78392864285, 5766.192733017381, 5780.797672081457, 5785.450382213683, 5789.2763404574425, 5807.099899515498, 5428.592855535771, 5656.406077361818, 5665.915011274834, 5672.943270567324, 5679.574031947621, 5702.486776190163, 5727.341355744059, 5754.079347304411, 5789.855253618609, 5818.44724751148, 5717.42825717425, 5762.74067666956, 5771.68255745938, 5778.566607504454, 5789.3921138279875, 5750.712464376765, 5763.423657634203, 5769.480746732815, 5784.161296611967, 5793.971030144822, 4399.23013472641, 4431.778411079433, 4473.172463094338, 4476.104779044204, 4476.686631935733, 3964.306246406925, 3994.121293315466, 4015.5984401560195, 4026.8187931543152, 4043.5552089270745, 5477.233187353773, 5478.342598888159, 5490.752916118784, 5512.062949298629, 5517.310336207974, 5636.351819540723, 5748.0, 5794.362620111784, 5814.563907706939, 5864.501517371071, 5540.0, 5548.4174161713245, 5550.8057217997175, 5558.277423934863, 5605.691686957175, 5021.171506701427, 5062.088824011713, 5062.443131255559, 5063.443021267658, 5111.182210846247, 5684.431556844283, 5803.448672376117, 5858.033424484912, 5898.705064746746, 5928.081147422156, 5736.627119237236, 5790.7973220937065, 5823.388544202798, 5926.1999630049395, 5967.850803729768, 5787.247657804459, 5824.912626310694, 5827.388124246893, 5831.212786273799, 5849.532037436909, 5616.438356164351, 5627.352854421794, 5674.06377947634, 5690.829275121753, 5695.686737229492, 5716.885662286814, 5778.8266352009505, 5786.07422812348, 5789.536837052941, 5840.094785308284, 5705.686187259741, 5724.971375143097, 5727.6849773262875, 5750.942490575041, 5751.568632352674, 5350.438203988525, 5662.631928924607, 5679.716014199356, 5696.088625819849, 5703.657780533182, 5410.161424979135, 5415.864603384789, 5507.394186639386, 5511.696856672921, 5563.888722225452, 5443.537299329601, 5612.130119831432, 5617.88764224705, 5644.322681278109, 5696.11710184922, 5398.514133727984, 5506.5870059746485, 5538.169274608841, 5539.639923404966, 5606.159076138611], "unit": "score", "name": "dojo.html", "value": 5311.827682762094}, {"lowerIsBetter": false, "replicates": [550.9476599723025, 594.8721024979628, 600.6786369292428, 602.3765402808094, 613.3437222172272, 37546.0, 38314.4252836207, 38452.23095538095, 38589.421158682606, 38634.42048369277, 42716.57283427173, 43133.13733725323, 43215.0, 43286.134277314435, 43373.56626433735, 8619.267362274199, 8802.86795698065, 8820.823583528325, 8859.778505537348, 8879.95560022199, 8503.2347088762, 8654.740357789276, 8660.826783464327, 8663.95668021659, 8687.56562171892, 8551.187637174458, 8637.568121593926, 8655.26430253426, 8658.740237792876, 8666.566671666424, 8563.143685631418, 8648.048714641385, 8652.783680407978, 8655.523946182957, 8663.436876603, 8461.873071903927, 8650.913490865136, 8658.696945606873, 8662.653493860238, 8663.870041949376, 39413.80293098545, 40237.39643905344, 40269.79865100656, 40318.193636127406, 40433.797831010954, 3228.580284563015, 3283.7208837248863, 3308.6360500344945, 3657.250263695936, 3906.687465002821, 28839.279018024507, 29202.56196157038, 29276.560851587245, 29999.400011999638, 30025.699743002508, 3890.105275786557, 3922.0, 3935.74417662851, 3974.721769476138, 4082.4080508326424, 260161.0, 261740.0, 262340.0, 262683.0, 263134.0, 40506.18987620231, 54020.18969715457, 54271.45728542743, 54328.0, 54541.727291364085, 40377.39433908494, 40428.0, 40552.0, 40617.0, 40735.592644073775, 12310.446029928675, 12346.75306493883, 12374.75250494985, 12478.126531142732, 12492.375381231084, 10825.35047897113, 10873.40196289196, 10933.671989840317, 10945.124390048777, 10950.178736594788, 13804.585862424143, 13849.930750346184, 14237.288135593179, 14241.928790356189, 14260.0, 5684.261046063985, 5719.942800572025, 5750.654960702372, 5848.561357898175, 5935.73289201987, 929.9814003720023, 947.4504787223394, 988.9307748457683, 1025.6410256410197, 1074.8172810622214, 27878.024269150483, 28083.57874631882, 28166.71833281682, 28538.429231415255, 28611.14166575006, 1036.8030074286012, 1050.2333296693305, 1063.2982231727024, 1080.897314755097, 1136.170595465315, 14832.480863169952, 14834.406623735145, 14842.257887105601, 14951.0, 15088.773668394982, 311922.0, 316165.0, 317830.0, 318615.0, 318967.0, 52037.7398113007, 52085.47914520882, 52494.47505524972, 52563.737181313845, 52862.73568632132, 1321.8281623388898, 1345.9259740714356, 1352.8241328627216, 1362.0329566008245, 1367.8632136786441, 27109.322266943484, 28297.0, 28508.0, 31218.843905780326, 32775.67224327726, 50752.492475074774, 51440.0, 51441.485585143666, 51510.74244628753, 51574.484255158466, 300734.0, 301318.0, 303076.0, 304438.47780760954, 306721.0, 41695.0, 44599.33101003423, 45294.320585191905, 46051.3092303609, 46087.53912460832, 42862.57137428711, 42913.57086429096, 43108.13783724245, 43171.35242971295, 43434.56565434305, 36583.45124823076, 36830.079248017944, 36977.26045479129, 40381.798091009354, 42369.788151059045, 36189.0952726184, 36236.63763362333, 36672.26655466929, 37140.257194856495, 37309.62690373061, 35848.0, 36217.0, 36326.636733632324, 36359.454608181426, 36582.08544786295, 32039.67960320367, 33154.5026824593, 33302.0, 33566.62234175499, 33863.0, 25078.62382064307, 25135.0, 25346.873265633552, 25549.3612659685, 25579.98889593947], "unit": "score", "name": "ext.html", "value": 18082.20591962231}, {"lowerIsBetter": false, "replicates": [343.8572992208232, 355.5111721383098, 358.3603268166322, 358.39431360999924, 359.4105666706601, 16395.590110247234, 17087.658246835057, 17747.29010839565, 17880.55298617532, 18494.907525462386, 16055.678886422265, 16137.677246455063, 16378.262978165982, 16521.504354869372, 16537.586560335996, 4789.688670236432, 4901.6078713703, 4910.263460480921, 4921.778519966592, 4928.162212423878, 4739.73931433771, 4847.563719265266, 4851.102546028978, 4896.265560165986, 4905.215165573509, 4811.735354555498, 4813.518648135194, 5085.821996230133, 5272.367315922094, 5332.866678333034, 5263.34208223972, 5277.366715994086, 5278.1291086970605, 5309.920351194735, 5604.439556044404, 5419.918701219485, 5437.075697131497, 5451.563874889999, 5457.672539647594, 5462.863428414282, 12334.5066197352, 12484.126111172221, 12683.429245683967, 12855.485780568766, 12930.741385172243, 2055.9486012849648, 2104.442322784463, 2114.260008996854, 2156.7088443060143, 2216.0803266644325, 9567.282453815991, 9743.317967742261, 9938.701838944844, 11406.885931140749, 11627.76744465106, 1803.8196180381988, 1827.3512902919406, 1844.7786265648208, 1863.2081365419779, 1876.8592355573387, 61958.0, 64268.678656606884, 65282.673586632234, 65427.0, 65429.672851635434, 10382.688519344432, 10385.84421233682, 10409.42748148859, 10510.842337364946, 10553.84169237462, 13733.862661373458, 13800.93099534496, 13807.240601767, 13830.37763300654, 13891.86108138926, 7347.742829000959, 7376.299251571092, 7404.074490688664, 7429.108506979089, 7461.515001524884, 6950.54821436605, 7099.432045436352, 7109.82225444368, 7164.21193668696, 7219.494635375473, 4421.712588681726, 4517.457905051415, 4524.660650451229, 4527.071950250191, 4539.591436770706, 1792.2024699008816, 1854.5641774183057, 1863.6552237836045, 1907.5898681783483, 1925.6052509235574, 439.13490423864937, 442.66799900074943, 867.6616119713317, 882.4264228251687, 897.1521911793424, 10364.0, 10640.414777187174, 10665.89334106649, 10792.136629069655, 10812.135029197645, 426.6458839163487, 443.08502941425854, 444.8487514245172, 453.5419226581177, 453.65748859610784, 9738.513074346254, 10149.949250253701, 10216.314308139223, 10298.948505257425, 10378.0, 503299.0, 503392.762506621, 506991.3158183952, 507739.0, 508147.0, 82288.58855705684, 83807.5809620948, 84507.15492844993, 84667.0, 84881.57559212288, 1177.952881884715, 1181.8936295733254, 1218.415160722858, 1228.342836582442, 1243.3348158735034, 15679.372825086868, 15827.446039388566, 15891.920540397225, 16056.91971540135, 16288.918555407146, 35258.8237058813, 35419.29161416706, 35558.0, 35717.28565428625, 36406.81796591, 44752.10495790131, 46495.0, 46641.53358466508, 47065.764671176425, 49339.766505837644, 9548.140667339972, 9672.371295865747, 9877.901220987698, 9895.65365212214, 9966.700998970042, 11961.342126183143, 11968.640940771791, 12017.579384721494, 12135.939320303341, 15366.308516116578, 13834.515791947233, 13985.650358741113, 14956.775648365501, 15079.623009424851, 15406.69186616239, 14818.777718334019, 14833.480828170963, 14850.257487125817, 15355.385784568492, 15396.07623542591, 9152.17630413266, 9265.953670231605, 9352.251819854531, 15142.167180805161, 15749.370025198863, 14346.713065738419, 14676.926615366854, 14939.0, 14992.175430351435, 15230.619234519225, 9796.559154837907, 10032.949835250776, 10281.84577231357, 10287.43419111926, 10296.536655850654], "unit": "score", "name": "jquery.html", "value": 8380.002574436356}, {"lowerIsBetter": false, "replicates": [1519.1492764052139, 1653.9338426462941, 1724.7671564338814, 1763.2858692229643, 1772.5480002599336, 7287.0, 7352.19125896151, 7425.443091768126, 7618.047744031996, 7634.847303053943, 7548.92451075489, 7562.470627056108, 7636.350910172642, 7641.388688904888, 7660.540367577949, 2179.3135162423864, 2201.8458707890454, 2204.2285200179967, 2212.037763572843, 2221.2336743823357, 2217.4899773052207, 2230.888455577223, 2252.6170551006367, 2272.602294598447, 2284.1434462076722, 2161.5352699169675, 2179.226374637005, 2179.814715749163, 2193.2104442400723, 2201.878896660683, 2190.518086021073, 2203.7465691445527, 2243.450354663118, 2253.86476811391, 2254.549090181962, 2202.2072054060527, 2219.1567204462362, 2221.489057516764, 2225.043231410493, 2233.944151396212, 7218.061651985261, 7290.781276561712, 7300.744473943387, 7303.963480182618, 7307.561546307238, 4401.625861801751, 4461.286194208943, 4464.620507256855, 4466.575675310873, 4479.216137175981, 2992.5361568956846, 3042.4523585754555, 3065.432894914448, 3081.275900163482, 3103.0, 976.6435251133356, 980.3088822380205, 982.6511588386091, 1013.6806905824642, 1019.938803671782, 9706.757331066709, 9711.90288097117, 9741.805163896754, 9743.220542356594, 9759.0, 3859.7684138951754, 3862.092408284051, 3891.747036442622, 3905.433712111728, 3907.199024200032, 8144.511329320259, 8148.592570371457, 8165.79585510367, 8169.428140030141, 8179.509229446252, 4389.18800021997, 4418.469783625986, 4428.844990425384, 4433.844815431445, 4451.821927122944, 3873.0511024799102, 3952.545457272393, 4000.6799456043445, 4077.082656402345, 4084.9795751021647, 3422.1273575238156, 3422.828858557062, 3471.7049050830456, 3472.2013936794174, 3482.1120614243205, 1060.612876300145, 1068.8503609494676, 1080.7139503990227, 1081.9621313254, 1099.0273607857105, 1245.5640525816073, 1254.0907841814794, 1258.9622311330675, 1300.9739805203842, 1312.330711337206, 6957.0, 6989.650517474106, 7016.017757513952, 7040.225575186725, 7054.823629409306, 431.20227578978637, 432.71657064622724, 438.72141190343723, 442.46019855775904, 456.18798538601345, 5551.056320425538, 5588.441155884379, 5589.217509548666, 5598.692071936001, 5639.718014099279, 8584.527850968258, 8597.785055373666, 8785.34109941757, 8905.243054340453, 8933.240674542736, 8583.399162058597, 8624.568771561397, 8699.608517616722, 8702.260307873776, 8766.167214114648, 6893.620850853251, 6977.616231107237, 7026.508144429943, 7073.540219885691, 7074.327938845801, 6746.325367463214, 6772.5936443813525, 6784.660766961731, 6803.217629972616, 6816.147981502312, 6973.19808222061, 7790.493617914817, 7801.843963120819, 7868.921310786819, 7874.34638725874, 7700.191479894755, 7775.727849525239, 7906.723264685708, 7941.047074351115, 7965.721199757981, 6419.133416988647, 6649.966750166218, 6660.333966603494, 6720.32796720324, 6736.79789606293, 7168.211496735459, 7170.42636589082, 7183.5330703504105, 7189.0, 7221.783346499614, 6645.0696902434665, 6789.660516974231, 6805.149356330458, 6827.317268273133, 6951.131108611424, 6600.966995165186, 6637.1703537057865, 6747.021681856201, 6776.7628133015105, 6800.557963732342, 6658.334166583303, 6746.257911629815, 6791.422729068085, 6804.863902722017, 6835.1114355135505, 5741.5406767459335, 5762.193292939076, 5785.218995435563, 5789.884202316014, 5790.044642634087, 6226.439620434183, 6241.937580624136, 6246.094316324016, 6254.030625253093, 6261.123442717932], "unit": "score", "name": "mootools.html", "value": 4195.525874373145}, {"lowerIsBetter": false, "replicates": [328.382640635605, 336.4616613418531, 337.93410284994434, 338.74932549913063, 346.29356113528405, 6189.845253868655, 6271.498280137578, 6284.497240220777, 6328.050792381139, 6339.302676705564, 6075.969620151904, 6258.186435763356, 6266.686665666722, 6274.152989346437, 6313.810585682437, 4753.595944344735, 4774.665773395864, 4785.258284965827, 4794.688345257565, 4803.207470767319, 4763.976180119094, 4788.736619485927, 4797.90404191916, 4800.039992001613, 4807.134715751164, 4640.164770341337, 4764.404449443819, 4787.473377928425, 4788.186008378584, 4798.088363210979, 4754.144254034272, 4776.211925032366, 4777.832775852846, 4782.402199725035, 4792.257200133985, 4086.5504794472586, 4193.97903010483, 4195.0, 4196.454460920061, 4199.832006719727, 5925.259342582177, 5941.821745347604, 5961.046232602806, 5975.312839023481, 6000.699965001732, 5576.470235327637, 5580.051391263454, 5589.720513974285, 5591.9720401398135, 5593.8321850344555, 6549.0, 6663.600183988976, 6674.599524028526, 6680.83297917551, 6690.866182676368, 4676.3453116563705, 4686.062787442525, 4692.0, 4695.16888413519, 4703.7883295251795, 6850.6574671266735, 6881.518293719441, 6897.1033765610655, 6915.412189963859, 6918.273581273995, 4701.976490117528, 4703.377334819482, 4704.929426058612, 4707.435107787088, 4713.387259656222, 4639.280911458729, 4720.527947205252, 4732.645051621143, 4760.7619619018915, 4783.210770222874, 5527.50252477279, 5529.502344788908, 5531.363893152339, 5533.584981126432, 5548.4174161713245, 6692.397684208445, 6736.865262694719, 6738.157730283715, 6739.797806065826, 6740.427063699641, 1676.1032847426568, 1694.7966244050792, 1697.7538256952673, 1698.5753561609597, 1698.9721218662778, 1143.8341440491083, 1155.1451925575013, 1161.7269941563725, 1175.6884425627215, 1185.271058299157, 6430.324815894357, 6475.158229430238, 6489.448396886318, 6492.642904640195, 6498.935010649833, 6898.448124150056, 6933.583984960918, 6953.721851126, 6955.130608673916, 6976.197737260179, 6319.115323854664, 6430.196225471816, 6481.870362592722, 6483.578567393104, 6513.381228783258, 3772.2078363543415, 3775.3770627846648, 3789.7157713171623, 3793.3551296279697, 3796.468494410785, 6974.0, 7077.009218709385, 7127.180374256923, 7156.642167891585, 7174.210836807947, 7130.893036604351, 7147.964260178665, 7157.355837974607, 7173.210946795848, 7180.92819071813, 6927.445804335641, 7111.64441777909, 7129.0, 7132.821679458055, 7138.678759455837, 6939.861202775815, 7009.7897063088185, 7012.403945664676, 7017.649117544204, 7019.3682568569075, 6870.45036397097, 6888.483363747639, 6890.552114112566, 6899.551529150589, 6899.655017249017, 6950.0, 7105.75129870452, 7111.857762844817, 7121.893171602326, 7141.9642901785155, 4675.298705194248, 4696.178168820511, 4718.457377401643, 4719.457262414866, 4727.669063165546, 6989.720411183495, 6993.685284162123, 7005.124359455068, 7029.507934444539, 7032.472564557781, 6700.597964122168, 6708.530402871752, 6792.150981127359, 6809.591424514545, 6817.693203805939, 6734.764283250062, 6786.762463313958, 6793.6942837573415, 6802.591844489149, 6806.65966701653, 6665.200175978914, 6672.9666351669875, 6673.466122710269, 6678.866422671616, 6695.263521012782, 6709.966450167718, 6848.9657551711925, 6850.8972365413565, 6856.280090590416, 6859.519833611599, 6602.636854973022, 6610.173728283965, 6639.070530125689, 6663.400293973566, 6668.66656667155], "unit": "score", "name": "prototype.html", "value": 5050.392603134568}, {"lowerIsBetter": false, "replicates": [664.9601023938562, 728.6866647341641, 748.9662965166567, 758.6699785593268, 762.3101093510371, 13197.406116724747, 13310.667233319173, 13510.256935868523, 13577.456901723932, 13588.660283492905, 12160.331181784999, 12191.146619736623, 12269.877301226985, 12273.877261227384, 12667.859892609664, 824.3734761581196, 835.6907944060675, 837.5560952695051, 839.0309192882231, 840.1808236968967, 826.6156237349632, 833.9249467547919, 834.8163404051099, 835.5488036460334, 836.1596595421623, 870.9390342676015, 882.4308321132897, 883.1124719656736, 884.3102380143498, 888.6312180445108, 873.0745409865563, 877.714742708619, 878.5826732302137, 882.4616983639975, 884.8451520983803, 646.3116780628662, 646.9482441404676, 647.6988200486796, 648.1023782061878, 650.1158424542618, 12362.320072396014, 12391.566295179626, 12421.0, 12424.627261182179, 12546.0, 9401.858972115424, 9446.385984911027, 9583.897851747068, 9587.664431744855, 9670.613175473043, 9761.0, 9769.609215631366, 9873.407595544219, 9965.103140717298, 10032.197424206119, 3525.1715846775987, 3545.840437180333, 3624.1845584743487, 3646.033801042726, 3732.832022558964, 42395.0, 42639.0, 48036.51963480355, 51323.48676513224, 51704.74147629275, 9014.459132452073, 9076.500792456478, 9275.860862087075, 9349.39228950116, 9560.713178604654, 17575.91212043957, 17748.37880674196, 17874.838135521408, 17944.37194698179, 17950.640987180184, 7287.744928927462, 7389.076365454318, 7427.220141885133, 7596.3922886168975, 7674.155842857282, 4285.507166675872, 4300.376445415397, 4326.6754993375625, 4343.587359200871, 4411.933820992687, 2936.4861149298786, 2954.6749857515656, 2959.0383125484304, 2968.539876319174, 2978.880844766185, 523.657004661947, 538.7306346826587, 546.3471151973354, 546.6064433607796, 547.0890966540666, 500.3563294577369, 571.8770464350184, 588.4233451217831, 605.449041372349, 624.095062159861, 8386.706465273686, 8444.788880278042, 8448.957755211184, 8579.699710510102, 8699.956500217584, 530.7425898439254, 545.8248878220289, 563.5885803363485, 647.7894684227645, 660.9834754131185, 3970.50368703912, 3987.8404863805704, 4001.751574924564, 4032.415299781574, 4036.1524079942956, 61601.383986160465, 63191.0, 63296.0, 63467.365326346146, 63478.68260658667, 52845.471545284825, 52963.73518132462, 53148.73425632847, 53407.19889201588, 53800.73099634477, 41933.0, 44042.779786101506, 47732.5226747735, 54244.18633720497, 54356.45643543514, 8232.34141268709, 8434.789130271793, 8570.314374849871, 8951.0, 9015.954920225618, 35146.47280290849, 35532.64467355293, 35899.46150807688, 36421.81789091037, 36437.817810910776, 45247.32129018133, 46518.30222546732, 47018.529814702786, 47028.0, 47831.28253076137, 11050.889491104987, 11160.55357785712, 11175.329480231212, 11294.717632059264, 11342.149338799458, 12130.33283169434, 12150.75698486043, 12259.877401226231, 12413.875861241271, 12436.440360183995, 11068.723281918017, 11144.2199046066, 11326.433678316216, 11366.772664546497, 11701.88298117008, 11758.470868810753, 11901.166918315635, 12019.519219231133, 12363.0, 12374.443150058449, 11462.312261264351, 11551.76896462083, 11624.94187529057, 11884.227525210834, 11887.821682674594, 9947.900520994697, 10114.754343843875, 10307.587696492057, 10396.896031039592, 10928.907109289008, 8901.154390332907, 9226.630934762803, 9401.811963760823, 9784.412935223909, 9792.314537982273], "unit": "score", "name": "yui.html", "value": 6242.316279512107}], "name": "dromaeo_css", "value": 6884.501590036449}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 107.0, 112.0, 113.0, 111.0, 122.0, 111.0, 112.0, 112.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.5}, {"lowerIsBetter": true, "replicates": [161.0, 127.0, 148.0, 166.0, 128.0, 148.0, 147.0, 124.0, 161.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 143.6}, {"lowerIsBetter": true, "replicates": [164.0, 162.0, 160.0, 161.0, 163.0, 159.0, 160.0, 162.0, 171.0, 163.0], "unit": "score", "name": "audio-dft", "value": 162.5}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 72.0, 73.0, 72.0, 73.0, 135.0, 71.0, 74.0, 74.0], "unit": "score", "name": "audio-fft", "value": 78.7}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 148.0, 95.0, 94.0, 93.0, 132.0, 92.0, 93.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 107.1}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 114.0, 110.0, 109.0, 110.0, 111.0, 111.0, 110.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.6}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [62.0, 63.0, 63.0, 71.0, 61.0, 61.0, 63.0, 61.0, 59.0, 66.0], "unit": "score", "name": "json-parse-financial", "value": 63.0}, {"lowerIsBetter": true, "replicates": [67.0, 70.0, 67.0, 67.0, 68.0, 69.0, 67.0, 68.0, 68.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [83.0, 85.0, 86.0, 85.0, 88.0, 86.0, 87.0, 84.0, 88.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.5}, {"lowerIsBetter": true, "replicates": [125.0, 122.0, 118.0, 132.0, 125.0, 129.0, 117.0, 118.0, 132.0, 125.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.3}, {"lowerIsBetter": true, "replicates": [152.0, 149.0, 153.0, 143.0, 166.0, 141.0, 150.0, 148.0, 151.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.4}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 67.0, 71.0, 69.0, 65.0, 65.0, 67.0, 71.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1515.1000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.91704223001167], "unit": "score", "name": "Richards", "value": 211.49428200000003}, {"lowerIsBetter": false, "replicates": [188.11136192626034], "unit": "score", "name": "DeltaBlue", "value": 351.483288}, {"lowerIsBetter": false, "replicates": [281.8489289740699], "unit": "score", "name": "Encrypt", "value": 944.410188}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [1075.268817204301], "unit": "score", "name": "RayTrace", "value": 688.1897699999998}, {"lowerIsBetter": false, "replicates": [712.2507122507122], "unit": "score", "name": "Earley", "value": 935.7140519999998}, {"lowerIsBetter": false, "replicates": [12192.77108433735], "unit": "score", "name": "Boyer", "value": 54.66050296442688}, {"lowerIsBetter": false, "replicates": [25025.0], "unit": "score", "name": "RegExp", "value": 36.40299700299697}, {"lowerIsBetter": false, "replicates": [1092.5925925925926], "unit": "score", "name": "Splay", "value": 74.58498305084747}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 20138.512007815607}]} 05:58:50 INFO - RETURN: dromaeo_css: 6884.50 05:58:50 INFO - RETURN: kraken: 1515.10 05:58:50 INFO - RETURN: v8_7: 20138.51 05:58:50 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,43]]", "result": "20138.51"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,43]]", "result": "1515.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,43]]", "result": "6884.50"}}} 05:58:50 INFO - Return code: 0 05:58:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:58:50 INFO - # TBPL SUCCESS # 05:58:50 INFO - Running post-action listener: _resource_record_post_action 05:58:50 INFO - Running post-run listener: _resource_record_post_run 05:58:51 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 28672; Write bytes: 556720128; Read time: 48; Write time: 538344 05:58:51 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234434560; Read time: 0; Write time: 366788 05:58:51 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 28672; Write bytes: 321994752; Read time: 48; Write time: 171532 05:58:51 INFO - Running post-run listener: _upload_blobber_files 05:58:51 INFO - Blob upload gear active. 05:58:51 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:58:51 INFO - Copying logs to upload dir... 05:58:51 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1272.430944 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2015-12-19 05:58:51.081411) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-19 05:58:51.085449) ========= 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009931 build_url: 'https://queue.taskcluster.net/v1/task/j2PMn7dYSauNl_03Hun3Dw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-19 05:58:51.136388) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:58:51.136734) ========= 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=01deea79d00cc2c334834894000001d2-1450509523.976726-763089705 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-19 05:58:51.185764) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-19 05:58:51.186081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-19 05:58:51.186358) ========= ========= Total master_lag: 0.25 =========