builder: larch_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1465339049.63 results: success (0) buildid: 20160607123004 builduid: cad6cd871fc0490fb04acaf942fe8ca0 revision: ee6ea28c089918834cb26b67c77653114af3dce5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.628049) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.628873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.629166) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.645130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.645716) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1465339049.407221-1269813280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 15:37:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 19.5M=0.001s 2016-06-07 15:37:30 (19.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.069655 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.730981) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.731296) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1465339049.407221-1269813280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017180 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.783248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:29.784098) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev ee6ea28c089918834cb26b67c77653114af3dce5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev ee6ea28c089918834cb26b67c77653114af3dce5 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1465339049.407221-1269813280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 15:37:30,320 truncating revision to first 12 chars 2016-06-07 15:37:30,320 Setting DEBUG logging. 2016-06-07 15:37:30,320 attempt 1/10 2016-06-07 15:37:30,320 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/ee6ea28c0899?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 15:37:30,623 unpacking tar archive at: larch-ee6ea28c0899/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632996 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:30.432168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:30.432507) ========= script_repo_revision: ee6ea28c089918834cb26b67c77653114af3dce5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:30.432935) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:30.433282) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 15:37:30.441570) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-07 15:37:30.441837) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1465339049.407221-1269813280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:37:31 INFO - MultiFileLogger online at 20160607 15:37:31 in /builds/slave/test 15:37:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 15:37:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:37:31 INFO - {'append_to_log': False, 15:37:31 INFO - 'base_work_dir': '/builds/slave/test', 15:37:31 INFO - 'blob_upload_branch': 'Larch', 15:37:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:37:31 INFO - 'branch': 'Larch', 15:37:31 INFO - 'buildbot_json_path': 'buildprops.json', 15:37:31 INFO - 'config_files': ('talos/linux_config.py',), 15:37:31 INFO - 'default_actions': ('clobber', 15:37:31 INFO - 'read-buildbot-config', 15:37:31 INFO - 'download-and-extract', 15:37:31 INFO - 'populate-webroot', 15:37:31 INFO - 'create-virtualenv', 15:37:31 INFO - 'install', 15:37:31 INFO - 'run-tests'), 15:37:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:37:31 INFO - 'download_minidump_stackwalk': True, 15:37:31 INFO - 'download_symbols': 'ondemand', 15:37:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:37:31 INFO - 'tooltool.py': '/tools/tooltool.py', 15:37:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:37:31 INFO - '/tools/misc-python/virtualenv.py')}, 15:37:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:37:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:37:31 INFO - 'installer_path': 'installer.exe', 15:37:31 INFO - 'log_level': 'info', 15:37:31 INFO - 'log_name': 'talos', 15:37:31 INFO - 'log_to_console': True, 15:37:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:37:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:37:31 INFO - 'opt_config_files': (), 15:37:31 INFO - 'pip_index': False, 15:37:31 INFO - 'sps_profile': False, 15:37:31 INFO - 'sps_profile_interval': 0, 15:37:31 INFO - 'suite': 'dromaeojs', 15:37:31 INFO - 'system_bits': '32', 15:37:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:37:31 INFO - 'title': 'talos-linux64-ix-032', 15:37:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:37:31 INFO - 'use_talos_json': True, 15:37:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:37:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:37:31 INFO - 'work_dir': 'build'} 15:37:31 INFO - ##### 15:37:31 INFO - ##### Running clobber step. 15:37:31 INFO - ##### 15:37:31 INFO - Running pre-action listener: _resource_record_pre_action 15:37:31 INFO - Running main action method: clobber 15:37:31 INFO - rmtree: /builds/slave/test/build 15:37:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:37:31 INFO - Running post-action listener: _resource_record_post_action 15:37:31 INFO - ##### 15:37:31 INFO - ##### Finished clobber step (success) 15:37:31 INFO - ##### 15:37:31 INFO - ##### 15:37:31 INFO - ##### Running read-buildbot-config step. 15:37:31 INFO - ##### 15:37:31 INFO - Running pre-action listener: _resource_record_pre_action 15:37:31 INFO - Running main action method: read_buildbot_config 15:37:31 INFO - Using buildbot properties: 15:37:31 INFO - { 15:37:31 INFO - "project": "", 15:37:31 INFO - "product": "firefox", 15:37:31 INFO - "script_repo_revision": "production", 15:37:31 INFO - "builddir": "larch_ubuntu64_hw_test-dromaeojs", 15:37:31 INFO - "repository": "", 15:37:31 INFO - "buildername": "Ubuntu HW 12.04 x64 larch pgo talos dromaeojs", 15:37:31 INFO - "buildid": "20160607123004", 15:37:31 INFO - "pgo_build": "True", 15:37:31 INFO - "basedir": "/builds/slave/test", 15:37:31 INFO - "buildnumber": 1, 15:37:31 INFO - "slavename": "talos-linux64-ix-032", 15:37:31 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:37:31 INFO - "platform": "ubuntu64_hw", 15:37:31 INFO - "scheduler": "tests-larch-linux64-pgo-talos", 15:37:31 INFO - "branch": "larch", 15:37:31 INFO - "repo_path": "projects/larch", 15:37:31 INFO - "revision": "ee6ea28c089918834cb26b67c77653114af3dce5", 15:37:31 INFO - "stage_platform": "linux64-pgo", 15:37:31 INFO - "builduid": "cad6cd871fc0490fb04acaf942fe8ca0", 15:37:31 INFO - "slavebuilddir": "test" 15:37:31 INFO - } 15:37:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:37:31 INFO - Running post-action listener: _resource_record_post_action 15:37:31 INFO - ##### 15:37:31 INFO - ##### Finished read-buildbot-config step (success) 15:37:31 INFO - ##### 15:37:31 INFO - ##### 15:37:31 INFO - ##### Running download-and-extract step. 15:37:31 INFO - ##### 15:37:31 INFO - Running pre-action listener: _resource_record_pre_action 15:37:31 INFO - Running main action method: download_and_extract 15:37:31 INFO - mkdir: /builds/slave/test/build/tests 15:37:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:31 INFO - https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:37:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:37:32 INFO - Downloaded 1361 bytes. 15:37:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:37:32 INFO - Using the following test package requirements: 15:37:32 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:37:32 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:37:32 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:37:32 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'jsshell-linux-x86_64.zip'], 15:37:32 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:37:32 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:37:32 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:37:32 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:37:32 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:37:32 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:37:32 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:37:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:37:32 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')]} 15:37:32 INFO - https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:37:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:37:33 INFO - Downloaded 24450270 bytes. 15:37:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:37:34 INFO - Return code: 0 15:37:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:34 INFO - https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:37:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:37:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:37:37 INFO - Downloaded 24450270 bytes. 15:37:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 15:37:38 INFO - Return code: 0 15:37: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')]} 15:37:38 INFO - https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:37:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:37:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:37:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:37:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:37:39 INFO - Downloaded 10833369 bytes. 15:37:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 15:37:39 INFO - Return code: 0 15:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:39 INFO - https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:37:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:37:41 INFO - Downloaded 59181095 bytes. 15:37:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:41 INFO - mkdir: /builds/slave/test/properties 15:37:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:37:41 INFO - Writing to file /builds/slave/test/properties/build_url 15:37:41 INFO - Contents: 15:37:41 INFO - build_url:https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:37:42 INFO - Running post-action listener: _resource_record_post_action 15:37:42 INFO - Running post-action listener: set_extra_try_arguments 15:37:42 INFO - ##### 15:37:42 INFO - ##### Finished download-and-extract step (success) 15:37:42 INFO - ##### 15:37:42 INFO - ##### 15:37:42 INFO - ##### Running populate-webroot step. 15:37:42 INFO - ##### 15:37:42 INFO - Running pre-action listener: _resource_record_pre_action 15:37:42 INFO - Running main action method: populate_webroot 15:37:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:37:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:37:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:37:42 INFO - '--tppagecycles', 15:37:42 INFO - '7'], 15:37:42 INFO - 'tests': ['tsvgm']}}, 15:37:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:37:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:37:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:37:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:37:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['damp', 'tps']}, 15:37:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'tests': ['damp', 'tps']}, 15:37:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['dromaeo_dom']}, 15:37:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:37:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['a11yr', 15:37:42 INFO - 'ts_paint', 15:37:42 INFO - 'tpaint', 15:37:42 INFO - 'sessionrestore', 15:37:42 INFO - 'sessionrestore_no_auto_restore', 15:37:42 INFO - 'tabpaint']}, 15:37:42 INFO - 'other-e10s': {'tests': ['a11yr', 15:37:42 INFO - 'ts_paint', 15:37:42 INFO - 'tpaint', 15:37:42 INFO - 'sessionrestore', 15:37:42 INFO - 'sessionrestore_no_auto_restore', 15:37:42 INFO - 'tabpaint']}, 15:37:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:37:42 INFO - 'ts_paint', 15:37:42 INFO - 'tpaint', 15:37:42 INFO - 'sessionrestore', 15:37:42 INFO - 'sessionrestore_no_auto_restore', 15:37:42 INFO - 'tabpaint']}, 15:37:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:37:42 INFO - 'ts_paint', 15:37:42 INFO - 'tpaint', 15:37:42 INFO - 'sessionrestore', 15:37:42 INFO - 'sessionrestore_no_auto_restore', 15:37:42 INFO - 'tabpaint']}, 15:37:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['a11yr', 15:37:42 INFO - 'ts_paint', 15:37:42 INFO - 'tpaint', 15:37:42 INFO - 'sessionrestore', 15:37:42 INFO - 'sessionrestore_no_auto_restore', 15:37:42 INFO - 'tabpaint']}, 15:37:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['a11yr', 15:37:42 INFO - 'ts_paint', 15:37:42 INFO - 'tpaint', 15:37:42 INFO - 'sessionrestore', 15:37:42 INFO - 'sessionrestore_no_auto_restore', 15:37:42 INFO - 'tabpaint']}, 15:37:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['tsvgx', 15:37:42 INFO - 'tsvgr_opacity', 15:37:42 INFO - 'tart', 15:37:42 INFO - 'tscrollx', 15:37:42 INFO - 'cart']}, 15:37:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:37:42 INFO - 'tsvgr_opacity', 15:37:42 INFO - 'tart', 15:37:42 INFO - 'tscrollx', 15:37:42 INFO - 'cart']}, 15:37:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'talos_options': ['--disable-e10s'], 15:37:42 INFO - 'tests': ['tp5o']}, 15:37:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'tests': ['tp5o']}, 15:37:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'talos_options': ['--disable-e10s', 15:37:42 INFO - '--xperf_path', 15:37:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:42 INFO - 'tests': ['tp5n']}, 15:37:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:42 INFO - 'talos_options': ['--xperf_path', 15:37:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:42 INFO - 'tests': ['tp5n']}}, 15:37:42 INFO - 'talos.zip': {'path': '', 15:37:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:37:42 INFO - Running post-action listener: _resource_record_post_action 15:37:42 INFO - ##### 15:37:42 INFO - ##### Finished populate-webroot step (success) 15:37:42 INFO - ##### 15:37:42 INFO - ##### 15:37:42 INFO - ##### Running create-virtualenv step. 15:37:42 INFO - ##### 15:37:42 INFO - Running pre-action listener: _resource_record_pre_action 15:37:42 INFO - Running main action method: create_virtualenv 15:37:42 INFO - Creating virtualenv /builds/slave/test/build/venv 15:37:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:37:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:37:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:37:42 INFO - Using real prefix '/usr' 15:37:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:37:42 INFO - Installing distribute.............................................................................................................................................................................................done. 15:37:44 INFO - Installing pip.................done. 15:37:44 INFO - Return code: 0 15:37:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:37:44 INFO - Using env: {'DISPLAY': ':0', 15:37:44 INFO - 'HOME': '/home/cltbld', 15:37:44 INFO - 'LANG': 'en_US.UTF-8', 15:37:44 INFO - 'LANGUAGE': 'en_US:en', 15:37:44 INFO - 'LOGNAME': 'cltbld', 15:37:44 INFO - 'MAIL': '/var/mail/cltbld', 15:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:44 INFO - 'MOZ_NO_REMOTE': '1', 15:37:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:44 INFO - 'NO_EM_RESTART': '1', 15:37:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:44 INFO - 'PWD': '/builds/slave/test', 15:37:44 INFO - 'SHELL': '/bin/bash', 15:37:44 INFO - 'SHLVL': '1', 15:37:44 INFO - 'TERM': 'linux', 15:37:44 INFO - 'TMOUT': '86400', 15:37:44 INFO - 'USER': 'cltbld', 15:37:44 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:44 INFO - '_': '/tools/buildbot/bin/python'} 15:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:44 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 15:37:44 INFO - Cleaning up... 15:37:44 INFO - Return code: 0 15:37:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:37:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:37:44 INFO - Using env: {'DISPLAY': ':0', 15:37:44 INFO - 'HOME': '/home/cltbld', 15:37:44 INFO - 'LANG': 'en_US.UTF-8', 15:37:44 INFO - 'LANGUAGE': 'en_US:en', 15:37:44 INFO - 'LOGNAME': 'cltbld', 15:37:44 INFO - 'MAIL': '/var/mail/cltbld', 15:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:44 INFO - 'MOZ_NO_REMOTE': '1', 15:37:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:44 INFO - 'NO_EM_RESTART': '1', 15:37:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:44 INFO - 'PWD': '/builds/slave/test', 15:37:44 INFO - 'SHELL': '/bin/bash', 15:37:44 INFO - 'SHLVL': '1', 15:37:44 INFO - 'TERM': 'linux', 15:37:44 INFO - 'TMOUT': '86400', 15:37:44 INFO - 'USER': 'cltbld', 15:37:44 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:44 INFO - '_': '/tools/buildbot/bin/python'} 15:37:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:45 INFO - Downloading/unpacking psutil>=3.1.1 15:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:37:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:47 INFO - Installing collected packages: psutil 15:37:47 INFO - Running setup.py install for psutil 15:37:47 INFO - building 'psutil._psutil_linux' extension 15:37:47 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 15:37:47 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 15:37:47 INFO - building 'psutil._psutil_posix' extension 15:37:47 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 15:37:47 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 15:37:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:47 INFO - Successfully installed psutil 15:37:47 INFO - Cleaning up... 15:37:47 INFO - Return code: 0 15:37:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:37:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:37:47 INFO - Using env: {'DISPLAY': ':0', 15:37:47 INFO - 'HOME': '/home/cltbld', 15:37:47 INFO - 'LANG': 'en_US.UTF-8', 15:37:47 INFO - 'LANGUAGE': 'en_US:en', 15:37:47 INFO - 'LOGNAME': 'cltbld', 15:37:47 INFO - 'MAIL': '/var/mail/cltbld', 15:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:47 INFO - 'MOZ_NO_REMOTE': '1', 15:37:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:47 INFO - 'NO_EM_RESTART': '1', 15:37:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:47 INFO - 'PWD': '/builds/slave/test', 15:37:47 INFO - 'SHELL': '/bin/bash', 15:37:47 INFO - 'SHLVL': '1', 15:37:47 INFO - 'TERM': 'linux', 15:37:47 INFO - 'TMOUT': '86400', 15:37:47 INFO - 'USER': 'cltbld', 15:37:47 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:47 INFO - '_': '/tools/buildbot/bin/python'} 15:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:37:50 INFO - Installing collected packages: mozsystemmonitor 15:37:50 INFO - Running setup.py install for mozsystemmonitor 15:37:50 INFO - Successfully installed mozsystemmonitor 15:37:50 INFO - Cleaning up... 15:37:50 INFO - Return code: 0 15:37:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:37:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:37:50 INFO - Using env: {'DISPLAY': ':0', 15:37:50 INFO - 'HOME': '/home/cltbld', 15:37:50 INFO - 'LANG': 'en_US.UTF-8', 15:37:50 INFO - 'LANGUAGE': 'en_US:en', 15:37:50 INFO - 'LOGNAME': 'cltbld', 15:37:50 INFO - 'MAIL': '/var/mail/cltbld', 15:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:50 INFO - 'MOZ_NO_REMOTE': '1', 15:37:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:50 INFO - 'NO_EM_RESTART': '1', 15:37:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:50 INFO - 'PWD': '/builds/slave/test', 15:37:50 INFO - 'SHELL': '/bin/bash', 15:37:50 INFO - 'SHLVL': '1', 15:37:50 INFO - 'TERM': 'linux', 15:37:50 INFO - 'TMOUT': '86400', 15:37:50 INFO - 'USER': 'cltbld', 15:37:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:50 INFO - '_': '/tools/buildbot/bin/python'} 15:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:50 INFO - Downloading/unpacking jsonschema==2.5.1 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:50 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 15:37:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:37:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:37:52 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 15:37:52 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 15:37:52 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 15:37:52 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 15:37:52 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 15:37:52 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 15:37:52 INFO - Downloading functools32-3.2.3-2.tar.gz 15:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:37:52 INFO - warning: no files found matching '*.txt' 15:37:52 INFO - no previously-included directories found matching 'build' 15:37:52 INFO - no previously-included directories found matching 'dist' 15:37:52 INFO - no previously-included directories found matching '.git*' 15:37:52 INFO - Installing collected packages: jsonschema, functools32 15:37:52 INFO - Running setup.py install for functools32 15:37:52 INFO - warning: no files found matching '*.txt' 15:37:52 INFO - no previously-included directories found matching 'build' 15:37:52 INFO - no previously-included directories found matching 'dist' 15:37:52 INFO - no previously-included directories found matching '.git*' 15:37:52 INFO - Successfully installed jsonschema functools32 15:37:52 INFO - Cleaning up... 15:37:52 INFO - Return code: 0 15:37:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:37:52 INFO - Using env: {'DISPLAY': ':0', 15:37:52 INFO - 'HOME': '/home/cltbld', 15:37:52 INFO - 'LANG': 'en_US.UTF-8', 15:37:52 INFO - 'LANGUAGE': 'en_US:en', 15:37:52 INFO - 'LOGNAME': 'cltbld', 15:37:52 INFO - 'MAIL': '/var/mail/cltbld', 15:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:52 INFO - 'MOZ_NO_REMOTE': '1', 15:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:52 INFO - 'NO_EM_RESTART': '1', 15:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:52 INFO - 'PWD': '/builds/slave/test', 15:37:52 INFO - 'SHELL': '/bin/bash', 15:37:52 INFO - 'SHLVL': '1', 15:37:52 INFO - 'TERM': 'linux', 15:37:52 INFO - 'TMOUT': '86400', 15:37:52 INFO - 'USER': 'cltbld', 15:37:52 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:52 INFO - '_': '/tools/buildbot/bin/python'} 15:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:53 INFO - Downloading/unpacking blobuploader==1.2.4 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:53 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 15:37:54 INFO - Downloading blobuploader-1.2.4.tar.gz 15:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:37:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:37:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:37:55 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 15:37:55 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 15:37:55 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 15:37:55 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 15:37:55 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 15:37:55 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 15:37:55 INFO - Downloading docopt-0.6.1.tar.gz 15:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:37:55 INFO - Installing collected packages: blobuploader, requests, docopt 15:37:55 INFO - Running setup.py install for blobuploader 15:37:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:37:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:37:55 INFO - Running setup.py install for requests 15:37:56 INFO - Running setup.py install for docopt 15:37:56 INFO - Successfully installed blobuploader requests docopt 15:37:56 INFO - Cleaning up... 15:37:56 INFO - Return code: 0 15:37:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15: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')]} 15:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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')]} 15:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 15:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 15:37:56 INFO - Using env: {'DISPLAY': ':0', 15:37:56 INFO - 'HOME': '/home/cltbld', 15:37:56 INFO - 'LANG': 'en_US.UTF-8', 15:37:56 INFO - 'LANGUAGE': 'en_US:en', 15:37:56 INFO - 'LOGNAME': 'cltbld', 15:37:56 INFO - 'MAIL': '/var/mail/cltbld', 15:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:56 INFO - 'MOZ_NO_REMOTE': '1', 15:37:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:56 INFO - 'NO_EM_RESTART': '1', 15:37:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:56 INFO - 'PWD': '/builds/slave/test', 15:37:56 INFO - 'SHELL': '/bin/bash', 15:37:56 INFO - 'SHLVL': '1', 15:37:56 INFO - 'TERM': 'linux', 15:37:56 INFO - 'TMOUT': '86400', 15:37:56 INFO - 'USER': 'cltbld', 15:37:56 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:56 INFO - '_': '/tools/buildbot/bin/python'} 15:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:56 INFO - Running setup.py (path:/tmp/pip-SNJr5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:56 INFO - Running setup.py (path:/tmp/pip-4lS63R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:56 INFO - Running setup.py (path:/tmp/pip-87xeQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:56 INFO - Running setup.py (path:/tmp/pip-dO0uIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:56 INFO - Running setup.py (path:/tmp/pip-tbV574-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:56 INFO - Running setup.py (path:/tmp/pip-OKp7kN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:56 INFO - Running setup.py (path:/tmp/pip-beiKZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:57 INFO - Running setup.py (path:/tmp/pip-PhT9AI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:57 INFO - Running setup.py (path:/tmp/pip-UaK1Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:57 INFO - Running setup.py (path:/tmp/pip-UOP1xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:57 INFO - Running setup.py (path:/tmp/pip-5sbYF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:57 INFO - Running setup.py (path:/tmp/pip-HvVHLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:57 INFO - Running setup.py (path:/tmp/pip-rAar6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:57 INFO - Running setup.py (path:/tmp/pip-LMbqF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:57 INFO - Running setup.py (path:/tmp/pip-M6nSyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:57 INFO - Running setup.py (path:/tmp/pip-J3ii8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:57 INFO - Running setup.py (path:/tmp/pip-8fkNo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:37:57 INFO - Running setup.py install for manifestparser 15:37:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:37:57 INFO - Running setup.py install for mozcrash 15:37:57 INFO - Running setup.py install for mozdebug 15:37:58 INFO - Running setup.py install for mozdevice 15:37:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozfile 15:37:58 INFO - Running setup.py install for mozhttpd 15:37:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozinfo 15:37:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozInstall 15:37:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozleak 15:37:58 INFO - Running setup.py install for mozlog 15:37:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for moznetwork 15:37:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozprocess 15:37:58 INFO - Running setup.py install for mozprofile 15:37:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Running setup.py install for mozrunner 15:37:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Running setup.py install for mozscreenshot 15:37:59 INFO - Running setup.py install for moztest 15:37:59 INFO - Running setup.py install for mozversion 15:37:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:37:59 INFO - Cleaning up... 15:37:59 INFO - Return code: 0 15:37:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 15:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 15:37:59 INFO - Using env: {'DISPLAY': ':0', 15:37:59 INFO - 'HOME': '/home/cltbld', 15:37:59 INFO - 'LANG': 'en_US.UTF-8', 15:37:59 INFO - 'LANGUAGE': 'en_US:en', 15:37:59 INFO - 'LOGNAME': 'cltbld', 15:37:59 INFO - 'MAIL': '/var/mail/cltbld', 15:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:59 INFO - 'MOZ_NO_REMOTE': '1', 15:37:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:37:59 INFO - 'NO_EM_RESTART': '1', 15:37:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:59 INFO - 'PWD': '/builds/slave/test', 15:37:59 INFO - 'SHELL': '/bin/bash', 15:37:59 INFO - 'SHLVL': '1', 15:37:59 INFO - 'TERM': 'linux', 15:37:59 INFO - 'TMOUT': '86400', 15:37:59 INFO - 'USER': 'cltbld', 15:37:59 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:59 INFO - '_': '/tools/buildbot/bin/python'} 15:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:59 INFO - Running setup.py (path:/tmp/pip-IMdjkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:59 INFO - Running setup.py (path:/tmp/pip-NULlle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:59 INFO - Running setup.py (path:/tmp/pip-1eoxJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:37:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:59 INFO - Running setup.py (path:/tmp/pip-EnMhF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:00 INFO - Running setup.py (path:/tmp/pip-FvaPgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:00 INFO - Running setup.py (path:/tmp/pip-nfADdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:00 INFO - Running setup.py (path:/tmp/pip-revvYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:00 INFO - Running setup.py (path:/tmp/pip-G_bQah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:00 INFO - Running setup.py (path:/tmp/pip-9ovMJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:00 INFO - Running setup.py (path:/tmp/pip-NbxKcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:00 INFO - Running setup.py (path:/tmp/pip-pqj_i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:00 INFO - Running setup.py (path:/tmp/pip-zqoeFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:00 INFO - Running setup.py (path:/tmp/pip-iaNuuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:00 INFO - Running setup.py (path:/tmp/pip-tnwoJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:00 INFO - Running setup.py (path:/tmp/pip-KjlYJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:00 INFO - Running setup.py (path:/tmp/pip-IBuM4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:00 INFO - Running setup.py (path:/tmp/pip-v1VxE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:38:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:38:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:02 INFO - Downloading blessings-1.6.tar.gz 15:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:38:02 INFO - Installing collected packages: blessings 15:38:02 INFO - Running setup.py install for blessings 15:38:03 INFO - Successfully installed blessings 15:38:03 INFO - Cleaning up... 15:38:03 INFO - Return code: 0 15:38:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:38:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:03 INFO - Reading from file tmpfile_stdout 15:38:03 INFO - Current package versions: 15:38:03 INFO - argparse == 1.2.1 15:38:03 INFO - blessings == 1.6 15:38:03 INFO - blobuploader == 1.2.4 15:38:03 INFO - docopt == 0.6.1 15:38:03 INFO - functools32 == 3.2.3-2 15:38:03 INFO - jsonschema == 2.5.1 15:38:03 INFO - manifestparser == 1.1 15:38:03 INFO - mozInstall == 1.12 15:38:03 INFO - mozcrash == 0.17 15:38:03 INFO - mozdebug == 0.1 15:38:03 INFO - mozdevice == 0.48 15:38:03 INFO - mozfile == 1.2 15:38:03 INFO - mozhttpd == 0.7 15:38:03 INFO - mozinfo == 0.9 15:38:03 INFO - mozleak == 0.1 15:38:03 INFO - mozlog == 3.2 15:38:03 INFO - moznetwork == 0.27 15:38:03 INFO - mozprocess == 0.22 15:38:03 INFO - mozprofile == 0.28 15:38:03 INFO - mozrunner == 6.11 15:38:03 INFO - mozscreenshot == 0.1 15:38:03 INFO - mozsystemmonitor == 0.3 15:38:03 INFO - moztest == 0.7 15:38:03 INFO - mozversion == 1.4 15:38:03 INFO - psutil == 3.1.1 15:38:03 INFO - requests == 1.2.3 15:38:03 INFO - wsgiref == 0.1.2 15:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 15:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 15:38:03 INFO - Using env: {'DISPLAY': ':0', 15:38:03 INFO - 'HOME': '/home/cltbld', 15:38:03 INFO - 'LANG': 'en_US.UTF-8', 15:38:03 INFO - 'LANGUAGE': 'en_US:en', 15:38:03 INFO - 'LOGNAME': 'cltbld', 15:38:03 INFO - 'MAIL': '/var/mail/cltbld', 15:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:03 INFO - 'MOZ_NO_REMOTE': '1', 15:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:03 INFO - 'NO_EM_RESTART': '1', 15:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:03 INFO - 'PWD': '/builds/slave/test', 15:38:03 INFO - 'SHELL': '/bin/bash', 15:38:03 INFO - 'SHLVL': '1', 15:38:03 INFO - 'TERM': 'linux', 15:38:03 INFO - 'TMOUT': '86400', 15:38:03 INFO - 'USER': 'cltbld', 15:38:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:03 INFO - '_': '/tools/buildbot/bin/python'} 15:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:38:03 INFO - Cleaning up... 15:38:03 INFO - Return code: 0 15:38:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:38:03 INFO - Using env: {'DISPLAY': ':0', 15:38:03 INFO - 'HOME': '/home/cltbld', 15:38:03 INFO - 'LANG': 'en_US.UTF-8', 15:38:03 INFO - 'LANGUAGE': 'en_US:en', 15:38:03 INFO - 'LOGNAME': 'cltbld', 15:38:03 INFO - 'MAIL': '/var/mail/cltbld', 15:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:03 INFO - 'MOZ_NO_REMOTE': '1', 15:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:03 INFO - 'NO_EM_RESTART': '1', 15:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:03 INFO - 'PWD': '/builds/slave/test', 15:38:03 INFO - 'SHELL': '/bin/bash', 15:38:03 INFO - 'SHLVL': '1', 15:38:03 INFO - 'TERM': 'linux', 15:38:03 INFO - 'TMOUT': '86400', 15:38:03 INFO - 'USER': 'cltbld', 15:38:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:03 INFO - '_': '/tools/buildbot/bin/python'} 15:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:38:03 INFO - Cleaning up... 15:38:03 INFO - Return code: 0 15:38:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7646f07f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1abd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 15:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:38:03 INFO - Using env: {'DISPLAY': ':0', 15:38:03 INFO - 'HOME': '/home/cltbld', 15:38:03 INFO - 'LANG': 'en_US.UTF-8', 15:38:03 INFO - 'LANGUAGE': 'en_US:en', 15:38:03 INFO - 'LOGNAME': 'cltbld', 15:38:03 INFO - 'MAIL': '/var/mail/cltbld', 15:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:03 INFO - 'MOZ_NO_REMOTE': '1', 15:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:03 INFO - 'NO_EM_RESTART': '1', 15:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:03 INFO - 'PWD': '/builds/slave/test', 15:38:03 INFO - 'SHELL': '/bin/bash', 15:38:03 INFO - 'SHLVL': '1', 15:38:03 INFO - 'TERM': 'linux', 15:38:03 INFO - 'TMOUT': '86400', 15:38:03 INFO - 'USER': 'cltbld', 15:38:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:03 INFO - '_': '/tools/buildbot/bin/python'} 15:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:04 INFO - Downloading/unpacking flake8 15: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 15: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 15: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 15: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 15: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 15: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 15:38:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:38:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:38:05 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 15:38:05 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 15:38:05 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 15:38:05 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 15:38:05 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 15:38:05 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 15:38:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:38:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15: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 15: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 15: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 15: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 15: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 15: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 15:38:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15: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 15: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 15: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 15: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 15: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 15: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 15:38:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:38:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:38:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:38:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:38:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:38:06 INFO - no previously-included directories found matching 'docs/_build' 15:38:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:38:06 INFO - Running setup.py install for pep8 15:38:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:38:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:38:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:38:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:38:06 INFO - no previously-included directories found matching 'docs/_build' 15:38:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:38:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:38:06 INFO - Cleaning up... 15:38:06 INFO - Return code: 0 15:38:06 INFO - Running post-action listener: _resource_record_post_action 15:38:06 INFO - Running post-action listener: _start_resource_monitoring 15:38:06 INFO - Starting resource monitoring. 15:38:06 INFO - ##### 15:38:06 INFO - ##### Finished create-virtualenv step (success) 15:38:06 INFO - ##### 15:38:06 INFO - ##### 15:38:06 INFO - ##### Running install step. 15:38:06 INFO - ##### 15:38:06 INFO - Running pre-action listener: _resource_record_pre_action 15:38:06 INFO - Running main action method: install 15:38:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:07 INFO - Reading from file tmpfile_stdout 15:38:07 INFO - Detecting whether we're running mozinstall >=1.0... 15:38:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:38:07 INFO - Reading from file tmpfile_stdout 15:38:07 INFO - Output received: 15:38:07 INFO - Usage: mozinstall [options] installer 15:38:07 INFO - Options: 15:38:07 INFO - -h, --help show this help message and exit 15:38:07 INFO - -d DEST, --destination=DEST 15:38:07 INFO - Directory to install application into. [default: 15:38:07 INFO - "/builds/slave/test"] 15:38:07 INFO - --app=APP Application being installed. [default: firefox] 15:38:07 INFO - mkdir: /builds/slave/test/build/application 15:38:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:38:24 INFO - Reading from file tmpfile_stdout 15:38:24 INFO - Output received: 15:38:24 INFO - /builds/slave/test/build/application/firefox/firefox 15:38:24 INFO - Running post-action listener: _resource_record_post_action 15:38:24 INFO - ##### 15:38:24 INFO - ##### Finished install step (success) 15:38:24 INFO - ##### 15:38:24 INFO - ##### 15:38:24 INFO - ##### Running run-tests step. 15:38:24 INFO - ##### 15:38:24 INFO - Running pre-action listener: _resource_record_pre_action 15:38:24 INFO - Running main action method: run_tests 15:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:38:24 INFO - Python 2.7.3 15:38:24 INFO - Return code: 0 15:38:24 INFO - grabbing minidump binary from tooltool 15:38:24 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')]} 15:38:24 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4c1c0>, '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 0x1b4d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b4d550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:38:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:38:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:38:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:38:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:38:24 INFO - Return code: 0 15:38:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:38:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:38:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:38:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:38:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:38:25 INFO - Return code: 0 15:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:38:25 INFO - Using env: {'DISPLAY': ':0', 15:38:25 INFO - 'HOME': '/home/cltbld', 15:38:25 INFO - 'LANG': 'en_US.UTF-8', 15:38:25 INFO - 'LANGUAGE': 'en_US:en', 15:38:25 INFO - 'LOGNAME': 'cltbld', 15:38:25 INFO - 'MAIL': '/var/mail/cltbld', 15:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:25 INFO - 'MOZ_NO_REMOTE': '1', 15:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:25 INFO - 'NO_EM_RESTART': '1', 15:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:25 INFO - 'PWD': '/builds/slave/test', 15:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:25 INFO - 'SHELL': '/bin/bash', 15:38:25 INFO - 'SHLVL': '1', 15:38:25 INFO - 'TERM': 'linux', 15:38:25 INFO - 'TMOUT': '86400', 15:38:25 INFO - 'USER': 'cltbld', 15:38:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1465339049.407221-1269813280', 15:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:25 INFO - '_': '/tools/buildbot/bin/python'} 15:38:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:38:25 INFO - mozversion application_buildid: 20160607123004 15:38:25 INFO - mozversion application_changeset: ee6ea28c089918834cb26b67c77653114af3dce5 15:38:25 INFO - mozversion application_display_name: Nightly 15:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:38:25 INFO - mozversion application_name: Firefox 15:38:25 INFO - mozversion application_remotingname: firefox 15:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 15:38:25 INFO - mozversion application_vendor: Mozilla 15:38:25 INFO - mozversion application_version: 50.0a1 15:38:25 INFO - mozversion platform_buildid: 20160607123004 15:38:25 INFO - mozversion platform_changeset: ee6ea28c089918834cb26b67c77653114af3dce5 15:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 15:38:25 INFO - mozversion platform_version: 50.0a1 15:38:25 INFO - using testdate: 1465339105 15:38:25 INFO - actual date: 1465339105 15:38:25 INFO - starting webserver on 'localhost:50104' 15:38:25 INFO - SUITE-START | Running 2 tests 15:38:25 INFO - TEST-START | dromaeo_css 15:38:25 INFO - operating with platform_type : linux_ 15:38:25 INFO - Initialising browser for dromaeo_css test... 15:38:25 INFO - TEST-INFO | started process 11717 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2Rskew/profile http://localhost:50104/getInfo.html) 15:38:30 INFO - PROCESS | 11717 | __metrics Screen width/height:1600/1200 15:38:30 INFO - PROCESS | 11717 | colorDepth:24 15:38:30 INFO - PROCESS | 11717 | Browser inner width/height: 1024/697 15:38:30 INFO - PROCESS | 11717 | __metrics 15:38:31 ERROR - PROCESS | 11717 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:38:32 INFO - PROCESS | 11717 | console.error: PushService: 15:38:32 INFO - PROCESS | 11717 | stateChangeProcessEnqueue: Error transitioning state 15:38:32 INFO - PROCESS | 11717 | UnknownError 15:38:33 INFO - TEST-INFO | 11717: exit 0 15:38:33 INFO - Browser initialized. 15:38:33 INFO - Running cycle 1/1 for dromaeo_css test... 15:38:33 INFO - TEST-INFO | started process 11799 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2Rskew/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:44 INFO - PROCESS | 11799 | RSS: Main: 156655616 15:38:44 INFO - PROCESS | 11799 | 15:39:36 INFO - PROCESS | 11799 | 1465339176289 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] 15:41:44 INFO - PROCESS | 11799 | Cycle 1(1): loaded http://localhost:50104/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50104/tests/dromaeo/cssquery-ext.html) 15:41:45 INFO - PROCESS | 11799 | RSS: Main: 246849536 15:41:45 INFO - PROCESS | 11799 | 15:44:47 INFO - PROCESS | 11799 | Cycle 1(1): loaded http://localhost:50104/tests/dromaeo/cssquery-ext.html (next: http://localhost:50104/tests/dromaeo/cssquery-jquery.html) 15:44:48 INFO - PROCESS | 11799 | RSS: Main: 243458048 15:44:48 INFO - PROCESS | 11799 | 15:47:50 INFO - PROCESS | 11799 | Cycle 1(1): loaded http://localhost:50104/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50104/tests/dromaeo/cssquery-mootools.html) 15:47:50 INFO - PROCESS | 11799 | RSS: Main: 253779968 15:47:50 INFO - PROCESS | 11799 | 15:50:52 INFO - PROCESS | 11799 | Cycle 1(1): loaded http://localhost:50104/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50104/tests/dromaeo/cssquery-prototype.html) 15:50:53 INFO - PROCESS | 11799 | RSS: Main: 249610240 15:50:53 INFO - PROCESS | 11799 | 15:53:56 INFO - PROCESS | 11799 | Cycle 1(1): loaded http://localhost:50104/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50104/tests/dromaeo/cssquery-yui.html) 15:53:56 INFO - PROCESS | 11799 | RSS: Main: 215322624 15:53:56 INFO - PROCESS | 11799 | 15:56:59 INFO - PROCESS | 11799 | Cycle 1(1): loaded http://localhost:50104/tests/dromaeo/cssquery-yui.html (next: http://localhost:50104/tests/dromaeo/cssquery-dojo.html) 15:57:00 INFO - PROCESS | 11799 | RSS: Main: 279785472 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | __start_tp_report 15:57:00 INFO - PROCESS | 11799 | _x_x_mozilla_page_load 15:57:00 INFO - PROCESS | 11799 | _x_x_mozilla_page_load_details 15:57:00 INFO - PROCESS | 11799 | |i|pagename|runs| 15:57:00 INFO - PROCESS | 11799 | |0;dojo.html;3153.8107713537174;3158.6367567729712;3174.8253846038465;3197.6642452542496;3230.4346739320613;5272.156454967206;5310.548603368714;5365.839024829252;5402.918956215655;5441.646292990953;5340.54605358545;5371.946280537203;5376.758045887935;5380.596455265861;5422.969635769201;4816.614670826334;4897.2898929655175;4903.362562866822;4919.4096708395045;5075.010372977271;4973.030259099477;4995.250712393134;5017.9247311290355;5032.194848824189;5047.192449208127;4807.639427042968;4808.831690890819;4856.660033797618;4883.804647814083;4912.263160525914;4879;4890.413150421937;4898.2162853943555;4901.534354236377;5001.824936127255;4902.828401005948;4924.704517728947;4933.506649335073;4937.111319962405;4948.752562371867;5222.112240919053;5246.396664383607;5312.946870531323;5321.281626980347;5354.892902141974;5800.8259752207505;5814.622049566807;5843.386444423317;5849.970750146264;5851.23933888596;5877.823665290005;5880.353161152269;5887.116932460122;5895.528357731371;5899.528037756969;5285.524302812727;5346.03771321162;5400.972995134999;5405.351357837046;5409.702466364309;5862.677552734641;5932.436418540232;5947.256592925884;5955.79154729591;5956.76172953077;5518.117101263779;5720.513756330759;5723.7996670116345;5723.8569035774435;5727.971360143172;5705.857353566194;5793.8841223175305;5805.651660900359;5846.239988801429;5846.736896839652;5807.157962095473;5814.418558144236;5820.301563812285;5821.650700957956;5825.970870145622;5655.915161272585;5732.140178973104;5776.797812076557;5840.707964601753;5843.357230704619;4195.307774217281;4224.324108142682;4224.577542245751;4263.87208383749;4266.914661706748;3772;3795.7532760370486;3796.867109651149;3820.9617903821163;3836.175222327213;5217.452167522356;5335.866603334947;5378.462153784669;5476.288082549243;5510.063289240758;5776.797812076641;5814.360420353758;5834.387389324144;5838.795642152504;5900.085486749561;5442.591805614516;5450.073487507133;5451.618386712971;5452.536534394541;5457.918131228034;4745.596624286905;4768.260919557405;4812.205986012269;4866.367372241586;4924.064427758714;5958.404159584094;5961.970190149108;5965.463108320272;5965.880682386328;6001.2498437695285;5780.2485676863425;5792.4497172768515;5792.82621521355;5793.623414478046;5798.072308430799;5783.450572195635;5828.300603927556;5857.736401862012;5912;5954.196183515343;5666.575006874418;5678.091505359288;5680.261565996394;5680.914786278292;5684.8010319638615;5690.146478028329;5778.797742079175;5780.971095144498;5782.884342313214;5785.508231800344;5452.972735136299;5581.162825576196;5597.692126933058;5620.4660557247;5681.460261275172;5685.545156387562;5685.60200785941;5688.146777983336;5689.402612725604;5701.40135285789;5364.061289274439;5522.337319521683;5591.049521581423;5592.077307244259;5598.636088654224;5580.720963951868;5603.859903502445;5639.436056394327;5727.8568035799435;5744.712764361681;5555.833325000256;5556.249906262765;5557;5561.610687251854;5565.026120428828 15:57:00 INFO - PROCESS | 11799 | |1;ext.html;610.4322979628945;621.5462712220079;623.3921926122032;638.9904151437727;640.3500447046251;42508.14983700324;43962.340564891514;44168;48515.272270915964;48608.51391486084;46294;46760.53239467604;46778.76610616942;46781;46833.76583117079;8908;9106.86339704905;9110.772230694252;9115.544222788867;9120.270378369747;8882.422642528216;8975.596098175598;8987.505687187202;8995.55022248885;8999;9013.36906416551;9050.456972581665;9054.954725226366;9059.320550958671;9065.274778017743;8982.86525702115;9036;9039.412438191563;9040.321975851837;9049.638014479413;8925.598348074287;9027.63889444428;9030.142136497088;9031.50326732036;9033.23217526511;43725.34411983791;44212.55787442149;44268.55731442677;44309.33535996963;44390;3553.893383198508;3599.1002249437643;3637.2543628556346;3683.37382644951;3849.287881741887;33685.83157084223;33873.83063084693;33971;34289.82855085734;35462.64537354619;4311.439512863339;4341.088371442001;4363.672724545671;4400.339949007643;4407.471103467572;336840;337446;337465;338589;339315;51001.429899907394;59406.70296648489;60203.39796602065;60272.39727602756;60349;59669.40330596725;61073.38926610766;61428.69285653543;67169.32830671729;67260.66369668121;17421.564460888578;17509.562260943578;17589.7361539577;17666.823331766776;17668.558286042695;12162.08784341178;12438.875611243953;13052.869471305356;13125.54060607874;13130;16915.323387064378;16974.915125424293;17360;17761.7335739964;17833.375831845824;5899.4985426239255;5963.075723262901;5981.820545383645;6031.728572214261;6068.666223357758;1019.6838979916249;1091.9672409827558;1126.0541145437708;1169.4386694386742;1182.739797244605;31578.526322105186;31685.841570792;31731.84134079361;31886.84056579702;32499.837500812817;1121.7027487715761;1138.2715062360096;1148.3224897310627;1176.9470373833199;1234.154604096197;16805;17001.914990424968;17010.744838827428;17053.31786728542;17392.304307827544;493233;494661;498397;499090;499434;58114;58222;58246.70876645589;58300.12549811756;58374;1276.9106162568717;1365.4128724648497;1420.801087847702;1427.5289154578757;1429.5997120806192;31831.522527161647;32707.836460817543;33143;33210.50184247286;33636.831815840764;51038.74480627573;51782.48217517776;52033.47966520286;52039;52074.218886717485;308262;310395;311015;311084;311674;34908;40363.394549082375;41065.178696426505;41069;43914.56085439105;48168.759156203996;48292.5170748288;48941.755291223315;48943.5105648939;49639;40055.59944400635;40431.3935290965;40991;41604.79197603992;42039.36940945827;40526;40711.98220044404;40771.38842917418;41236.79381603073;41262;40212.1957560853;40560;41509.37735933903;41825.58174418217;41841.79079104707;34357.312853743286;36148.27703445969;36302.455463168604;36786.63213367832;36803.815980921;27590.862045689642;27885.860570697016;28014.2996425091;28187.859060704566;28198 15:57:00 INFO - PROCESS | 11799 | |2;jquery.html;395.09720289139284;397.82495701891185;400.0798164222288;401.45602708330205;413.4996654048601;17878;19066.523336916584;19280;19300.903495482537;20203.696944545805;18375.632487350245;18395.26418943244;18988.240470381203;19020.429387118373;19216.519587010298;5601.579881508883;5706.80026199081;5775.768969241225;5781.537477001851;5783.277090363705;5587.217789509472;5643.294588176478;5670.886582268352;5688.544916406678;5690.06113991191;5558.027345214591;5612.494875461208;5667.206591077253;5682.630629009122;5684.602077854552;5653.745581448845;5661.405552416999;5664.546836253131;5675.5459563234845;5680.602357834953;4817.349657796188;4976.0545496355935;4980.626453016038;4982.377202849644;4989.077020751172;19562.217511299532;19812.70280945787;22387.55224895493;22888.427789305235;23026.884865575565;2248.87631180285;2355.799757020638;2433.051110067075;2595.545779488582;2970.2425881400322;13620;13631.727365452734;13753.518626848012;14202.92898535511;14558.70882582353;1893.5455490682136;1958.4026871804094;1985;1991.8705284156483;2058;75525.62237188779;75803;76068.61965690191;76201.23798761997;78760.21239787586;9789.608415663437;9995.900040999642;10675.786484270271;10746.462676866282;10873;14769.18769467668;14858.851411485746;14898.702025959421;14974.251287435585;15003.774943375858;8090.797730056795;8113.959430202929;8152.959235203904;8246.793830154294;8259.710910118238;7689.538627682245;7777.496236454924;7804.2585954334245;7961.641726122339;7998.960005199937;6538.378854008862;6592.967035164794;6620.933790662128;6622.735090596419;6627.337266273431;1894.488488108203;1898.924043038263;1917.338518211215;1935.4967708395918;1936.80886254955;941.7410212191566;1012.372329155928;1046.3460337289193;1092.9234953553334;1131.2194585735815;18442.35451759182;18519.90740046291;19427.902860485607;19587.80412195888;19752.90123549373;1059.8410238464137;1066.4347895615335;1115.6206889657556;1118.8433619293305;1151.3092144713273;10734.785304294026;11219.439028048564;11258.380789056517;11271.38007409584;11302.321860688386;451499;456522;459612;462063;464780;72292.63853680698;74384;74660.62669686726;75463.62268188734;76316.23683763202;1033.6950599573145;1037.8806437259661;1056.4770438632927;1057.603398725478;1067.8611780468489;16686.24911878944;16841.41055063116;17039.82960170432;17136.914315428345;17432.21555030052;37211.44182837205;37255;38289.40726042215;38778.61221387863;39155.41266880942;49233;50911.23633145432;51597.226041610156;51665;51879.48120518746;8239.217274358927;8251.504909705436;8290.668373265002;8296.70961516344;8406.705765298186;12489.562865299671;12706.87293127057;12708.491660333482;12764;12778.68053298675;11156.776864462827;11230.55077796879;11385.203035787747;11410.54357825711;11440.199186057229;10947.616833410792;11051.086143805584;11091.168162387692;11445.713857153305;11459.598914037968;11105.111591072857;11349.319040857576;11491.655250342503;11513.53945842157;11842.703932401759;8299.08710041907;8321.625526851427;8375.748727538183;8383.329333653415;8435.216378594927;11449.198556100993;11566.47950842231;11615.825762613398;11710.355930423908;11783.469743861719 15:57:00 INFO - PROCESS | 11799 | |3;mootools.html;1266.0314859132766;1320.8414990201177;1400.7898815177723;1416.490063577112;1841.723741438784;8553.529555874424;8921.687740929068;8976.596053177607;9217.815643687121;9234.630614775419;8918.687845925393;9163.862542061874;9166.450012999225;9167.220786233178;9180.403273787217;2072.2332736887374;2273.147569661377;2328.4877326988035;2351;2358.0921345282104;2385.6063749481314;2397.304781613339;2400.051979468109;2400.2559206645997;2405.4226985523437;2293.552757212344;2307.8038366738847;2324.9651255231183;2326.220716060122;2326.755690652474;1990.8009199080047;2069.9275525356616;2226.832987525942;2308.6883270758567;2312.6531020346915;2298.138198175684;2301.746807851135;2324.1981516376823;2327.138958585317;2329.4991576811067;9044.773880653036;9129.908700912973;9137.40606860552;9138.177564019205;9143.862842057375;5216.66091704038;5349.866253343658;5360.705161216131;5361.329833770778;5364.490373414519;3380.7633465657414;3401;3443.517907492953;3449.2066824630474;3548.538689970313;1055.7400887419303;1073.420353009374;1078.3260462211117;1084.7342401111778;1096.155959910872;13189;13217.867821321759;13220.338983050906;13224;13229.536966206137;4652.97673511637;4702.976485117552;4773.451053128934;4872.196087645499;4908.656394052382;10896.67309980686;10898.72753181161;10909.509072091774;10919.454027298603;10928.726781830517;5445.101558242845;5445.37378201504;5452.836414907559;5454.018276710231;5454.072807622634;4293.806778694966;4768.690035147704;4913.459519452857;4918.606511479073;4957.107720610253;3853.133045064894;3950.3876899080688;4048.39274108886;4078.510578730512;4124.298869192245;1221.780079585683;1222.7371115210271;1229.3484453239798;1238.0714464151886;1239.8884100431005;981.1513041219293;998.0817647764075;1023.5394072667181;1052.163529993657;1065.8827528971806;6101.359357267423;6341.651209183539;6397.52018598607;6473.320301368382;6664.366885145903;953.7615596100975;958.8130314588727;1008.0927165550917;1016.2936758952457;1030.4744580264119;4310.374995625617;4398.3842262083335;4418.425604671372;4420.447444069491;4441.844535441309;10171.135453486388;10855.67432977012;11050.33697978124;11104.27822191571;11195.608153714744;10744.409057501756;11174.10607151426;11186.16103792219;11269.71825704364;11304.321740695585;8365.916340836635;8497.277731392776;8532.488050716976;8535.487870727777;8541.572921353907;5979.940200598112;6363.204599425072;6385.744570217139;6410;6489.091527186292;10450.895491044992;10582.523786429476;10626.84059739089;10805;10821.134309255083;10690.893091069302;10819.459027048773;10862.402567858528;10865.348079115278;10865.891341086804;8364.163583641588;8648.481091134552;8680;8682.479051256692;8752.824943500967;8988.595513202052;9009.144131307516;9016.954915225382;9086.409383390059;9089.727308180765;7743.845123097619;7936.761897142864;7978;8044.4771089881315;8172.346212302882;7516.248375162659;7758.806029849299;7862.803429914298;7873.055233372032;8147.307478864363;7212.459065570209;7226.566406015656;7293.124825021031;7296.379807716191;7302.56184628924;5069.138246498178;5098.719570423662;5149.588032957429;5194.480551944926;5273.209018647233;4850.975745121393;4991.101601711727;5023.296738456546;5029.497050295087;5144.151215049475 15:57:00 INFO - PROCESS | 11799 | |4;prototype.html;323.0389590966874;323.5793468490961;327.59705562158535;327.8590206211328;329.44817430803414;5295.523402893738;5350.5184533391985;5352.384475785286;5362.865928351793;5371.462853714632;5262.500062494057;5303.522682958533;5322.840314790553;5337.359516857973;5340.62615616907;3631.0151688481537;3936.1734035852505;4024.134811015631;4040.7777572233517;4069.1454794493193;3976.0855003349247;4050.3519436890106;4083.8979025524304;4095.5494895561465;4111.218868414991;4014.3777714454304;4050.0887300357476;4063.634272915437;4067.4102255172984;4091.161311931062;3915.2365288768415;4034.616711412411;4047.4738284023183;4074.164796216798;4095;3580.905170475275;3584.964150358489;3627.8730244441317;3637.1816341323256;3657.56109266887;5006.4492905780335;5018.422881368653;5021.799128034911;5024.899502009976;5029.572486338666;4701.224297990828;4705.55297246761;4710.952890471086;4712.434507859079;4749.240121580565;5210.869728256786;5421.430749771257;5443.8094666686875;5481.698506582136;5481.890362192734;3909.5113110861143;3930.2532518821617;3933.9213215735813;3938.960610393888;3947.328954077785;5331.493508116684;5401.4868587484125;5666.5183459406;5764.213093940892;5791.160281759164;3895.045713800137;3912.68698504119;3915.1190982028816;3921.9607803922167;3951;4046.069404037088;4064.9796751016056;4065.7435123506148;4072.289638351899;4073.7778666399963;4545.568171023748;4573.91260898892;4635.629149668019;4638.420197475316;4661.813527458864;5318.7726369175925;5368.597355198298;5431.321084864392;5442.836714898559;5471.23402723619;1391.3234552364377;1394.741972735027;1402.894782891267;1409.485537778724;1419.7577120019982;821.5112008355019;851.9275861551714;894.8299823033602;895.2703546609492;905.8505346617866;4690.334931099411;4713.434387873477;4716.834910778175;4762.261849413347;4763.380760501182;4955.603551715854;4978.5768209701855;4992.301077849104;5015.793051057523;5077.365329333833;4662.9766851166205;4764.404449443819;4827.2035114206465;4841.942174575791;4859.80560777572;3009.2326456753826;3029.4698427775493;3040.559118927743;3042.915602812081;3066.1874603230162;5877.942720047949;5907.2911250650195;5911.172435858984;5928.822135335947;5932.970335148383;5527.585431092684;5741.942580574141;5742.511886489611;5797.115937320388;5850.3272123705465;5796.246487956623;5853.619514731614;5878.735456904449;5911.940880591139;5918.171455996078;5385.8114965976;5421.701806400686;5676.886462270649;5725.713714314351;5731.312242530923;5541.2796336476;5549.5837812165055;5603.5797315202335;5606.579506537108;5632.5493960482245;5689.402612725769;5834.795782147605;5850.707464626837;5853.239078919713;5854.326752423526;3973.9403908941968;3975.2844487991283;3990.1421194443333;4004.0790618157403;4012.1574469360594;5588.497035266845;5607.579431542732;5642.379338272869;5712.457316554921;5725.770969161187;5309.628326017141;5467.781288748405;5536.584756143225;5546.168074788814;5576.163575463713;5478.589105817159;5513.255710479034;5531.695756733418;5539.944600553943;5546.251256080377;5449.3188351456065;5450.509454148987;5465.9726701366235;5485.4788795066;5488.615796894179;5360.303160589098;5575.609707320448;5582.49757521825;5606.411326810626;5628.915566266591;5410.3778065522965;5452.236686863763;5482.2873026506295;5509.173623956439;5517.2275881377445 15:57:00 INFO - PROCESS | 11799 | |5;yui.html;761.2045412543891;770.6300975531744;782.4210084537444;785.3206975965788;825.6738588257637;15148.318325675344;15765;15916.52250432486;16052.83947160528;16270.349186032545;15060.322285497152;15158.090514569134;15212.923935380308;15225.84774152261;15226.162561059138;818.2145140664954;823.180934969706;831.2518733140192;836.2056046755577;837.1753822484848;678.297961609734;798.5847359373151;823.4071468542639;828.5484410995991;828.7886588919818;872.2978002707817;873.2010210657241;874.3617159473588;875.0724232313768;875.1948207648946;703.3212949503702;863.1713554987219;863.7020228021386;864.1099667342647;864.4337958637103;610.6671863834176;624.0296339192554;625.0343219725535;626.1077963901422;626.7023163997111;15379;15403.691926161415;15425.15161666108;15442.382304707799;15474.690506189925;14001.649958751012;14501.854981450157;14575.635609109857;14753.92623036878;14922.552323430315;12439.440225189888;12628;12666.36668166588;12775.74448511034;13264.801027984588;4438.578335058164;4479.9552004479865;4491.461024677027;4571.885702857422;4612.930806037912;74435.62782186054;74454.62772686157;74473.25526744772;74473.62763186204;74629.25370746278;11387.601433949772;11388.886111138949;11421.001185863071;11879.940600296943;12179.086568507397;21740.239091631716;21941.341759747233;21953.341399758032;21993.560128797333;22016.11935522561;8342.791430214293;8365.163483651708;8423.410361274651;8474.703385381605;8764.123587641312;4536.546345365503;4563.771811409482;4744.952550474451;4852;4861.927071093936;3534.0988048047584;3626.492291079251;3651.3427583034777;3659.70722342212;3662.487251784752;599.8950183717832;654.8428377189535;677.4275737002324;689.7965100295324;699.5138378826769;668.3817468841303;736.7237286017744;738.1879932074675;743.7248218159316;773.6789232468462;9488.146066854017;9796.706098817047;9857.35927164732;9988.977611084187;9994.150497207818;742.3504433620582;744.556988591787;749.4828568287863;755.4787196834277;778.7079845058104;4275.486941567032;4497.797599108048;4589.449266088091;4662.650301227353;4718.200555963104;69765.65117174381;71901;71992.28007719961;73990.63004685049;74114.25885741181;65384;76836;107043.46478267558;107086.46456767667;108021.4598927016;60379.69810150921;93767;97157.51421242849;97299;98778.50610747044;8883.108889561812;9133.725988220363;9219.216366608913;9338;9367.718968430958;44168.779156104014;51732.48267517277;53888;54012.729936350064;55200.72399637976;71500;78779.2122078772;80517.19482805097;80671;81838.18161818544;11003.504842281956;11288.096952243637;11954.760904781682;12112.273263604213;13108.147970381895;14076.507322243673;14298.428062877369;14599.85400145985;14774.409023638935;15004.324805383565;13595.756560580792;13778.104423212459;14066.296685165906;14085.577432677037;14360.56918292453;13046.54337098197;13630.86369136296;13927.512537061155;14149.292535372984;14416.351264193347;13018.609441716764;13430.529931452353;13453.394597242952;13865.861341386457;13879;11383.146264030065;11417.80367798449;11764.882351176379;11784.410779461165;12025.579104731292;11006.394648294101;11006.669799906016;11152.330860148417;11294.322340659586;11360.091192704398 15:57:00 INFO - PROCESS | 11799 | __end_tp_report 15:57:00 INFO - PROCESS | 11799 | __start_cc_report 15:57:00 INFO - PROCESS | 11799 | _x_x_mozilla_cycle_collect,825 15:57:00 INFO - PROCESS | 11799 | __end_cc_report 15:57:00 INFO - PROCESS | 11799 | __startTimestamp1465340220147__endTimestamp 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | ------- Summary: start ------- 15:57:00 INFO - PROCESS | 11799 | Number of tests: 6 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | [#0] dojo.html Cycles:165 Average:5350.47 Median:5561.61 stddev:626.68 (11.3%) stddev-sans-first:604.45 15:57:00 INFO - PROCESS | 11799 | Values: 3153.8 3158.6 3174.8 3197.7 3230.4 5272.2 5310.5 5365.8 5402.9 5441.6 5340.5 5371.9 5376.8 5380.6 5423.0 4816.6 4897.3 4903.4 4919.4 5075.0 4973.0 4995.3 5017.9 5032.2 5047.2 4807.6 4808.8 4856.7 4883.8 4912.3 4879.0 4890.4 4898.2 4901.5 5001.8 4902.8 4924.7 4933.5 4937.1 4948.8 5222.1 5246.4 5312.9 5321.3 5354.9 5800.8 5814.6 5843.4 5850.0 5851.2 5877.8 5880.4 5887.1 5895.5 5899.5 5285.5 5346.0 5401.0 5405.4 5409.7 5862.7 5932.4 5947.3 5955.8 5956.8 5518.1 5720.5 5723.8 5723.9 5728.0 5705.9 5793.9 5805.7 5846.2 5846.7 5807.2 5814.4 5820.3 5821.7 5826.0 5655.9 5732.1 5776.8 5840.7 5843.4 4195.3 4224.3 4224.6 4263.9 4266.9 3772.0 3795.8 3796.9 3821.0 3836.2 5217.5 5335.9 5378.5 5476.3 5510.1 5776.8 5814.4 5834.4 5838.8 5900.1 5442.6 5450.1 5451.6 5452.5 5457.9 4745.6 4768.3 4812.2 4866.4 4924.1 5958.4 5962.0 5965.5 5965.9 6001.2 5780.2 5792.4 5792.8 5793.6 5798.1 5783.5 5828.3 5857.7 5912.0 5954.2 5666.6 5678.1 5680.3 5680.9 5684.8 5690.1 5778.8 5781.0 5782.9 5785.5 5453.0 5581.2 5597.7 5620.5 5681.5 5685.5 5685.6 5688.1 5689.4 5701.4 5364.1 5522.3 5591.0 5592.1 5598.6 5580.7 5603.9 5639.4 5727.9 5744.7 5555.8 5556.2 5557.0 5561.6 5565.0 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | [#1] ext.html Cycles:180 Average:56025.02 Median:41715.19 stddev:103063.01 (247.1%) stddev-sans-first:103268.15 15:57:00 INFO - PROCESS | 11799 | Values: 610.4 621.5 623.4 639.0 640.4 42508.1 43962.3 44168.0 48515.3 48608.5 46294.0 46760.5 46778.8 46781.0 46833.8 8908.0 9106.9 9110.8 9115.5 9120.3 8882.4 8975.6 8987.5 8995.6 8999.0 9013.4 9050.5 9055.0 9059.3 9065.3 8982.9 9036.0 9039.4 9040.3 9049.6 8925.6 9027.6 9030.1 9031.5 9033.2 43725.3 44212.6 44268.6 44309.3 44390.0 3553.9 3599.1 3637.3 3683.4 3849.3 33685.8 33873.8 33971.0 34289.8 35462.6 4311.4 4341.1 4363.7 4400.3 4407.5 336840.0 337446.0 337465.0 338589.0 339315.0 51001.4 59406.7 60203.4 60272.4 60349.0 59669.4 61073.4 61428.7 67169.3 67260.7 17421.6 17509.6 17589.7 17666.8 17668.6 12162.1 12438.9 13052.9 13125.5 13130.0 16915.3 16974.9 17360.0 17761.7 17833.4 5899.5 5963.1 5981.8 6031.7 6068.7 1019.7 1092.0 1126.1 1169.4 1182.7 31578.5 31685.8 31731.8 31886.8 32499.8 1121.7 1138.3 1148.3 1176.9 1234.2 16805.0 17001.9 17010.7 17053.3 17392.3 493233.0 494661.0 498397.0 499090.0 499434.0 58114.0 58222.0 58246.7 58300.1 58374.0 1276.9 1365.4 1420.8 1427.5 1429.6 31831.5 32707.8 33143.0 33210.5 33636.8 51038.7 51782.5 52033.5 52039.0 52074.2 308262.0 310395.0 311015.0 311084.0 311674.0 34908.0 40363.4 41065.2 41069.0 43914.6 48168.8 48292.5 48941.8 48943.5 49639.0 40055.6 40431.4 40991.0 41604.8 42039.4 40526.0 40712.0 40771.4 41236.8 41262.0 40212.2 40560.0 41509.4 41825.6 41841.8 34357.3 36148.3 36302.5 36786.6 36803.8 27590.9 27885.9 28014.3 28187.9 28198.0 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | [#2] jquery.html Cycles:180 Average:27236.20 Median:12568.65 stddev:75309.42 (599.2%) stddev-sans-first:75493.72 15:57:00 INFO - PROCESS | 11799 | Values: 395.1 397.8 400.1 401.5 413.5 17878.0 19066.5 19280.0 19300.9 20203.7 18375.6 18395.3 18988.2 19020.4 19216.5 5601.6 5706.8 5775.8 5781.5 5783.3 5587.2 5643.3 5670.9 5688.5 5690.1 5558.0 5612.5 5667.2 5682.6 5684.6 5653.7 5661.4 5664.5 5675.5 5680.6 4817.3 4976.1 4980.6 4982.4 4989.1 19562.2 19812.7 22387.6 22888.4 23026.9 2248.9 2355.8 2433.1 2595.5 2970.2 13620.0 13631.7 13753.5 14202.9 14558.7 1893.5 1958.4 1985.0 1991.9 2058.0 75525.6 75803.0 76068.6 76201.2 78760.2 9789.6 9995.9 10675.8 10746.5 10873.0 14769.2 14858.9 14898.7 14974.3 15003.8 8090.8 8114.0 8153.0 8246.8 8259.7 7689.5 7777.5 7804.3 7961.6 7999.0 6538.4 6593.0 6620.9 6622.7 6627.3 1894.5 1898.9 1917.3 1935.5 1936.8 941.7 1012.4 1046.3 1092.9 1131.2 18442.4 18519.9 19427.9 19587.8 19752.9 1059.8 1066.4 1115.6 1118.8 1151.3 10734.8 11219.4 11258.4 11271.4 11302.3 451499.0 456522.0 459612.0 462063.0 464780.0 72292.6 74384.0 74660.6 75463.6 76316.2 1033.7 1037.9 1056.5 1057.6 1067.9 16686.2 16841.4 17039.8 17136.9 17432.2 37211.4 37255.0 38289.4 38778.6 39155.4 49233.0 50911.2 51597.2 51665.0 51879.5 8239.2 8251.5 8290.7 8296.7 8406.7 12489.6 12706.9 12708.5 12764.0 12778.7 11156.8 11230.6 11385.2 11410.5 11440.2 10947.6 11051.1 11091.2 11445.7 11459.6 11105.1 11349.3 11491.7 11513.5 11842.7 8299.1 8321.6 8375.7 8383.3 8435.2 11449.2 11566.5 11615.8 11710.4 11783.5 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | [#3] mootools.html Cycles:180 Average:6011.17 Median:4431.15 stddev:3490.77 (78.8%) stddev-sans-first:3482.35 15:57:00 INFO - PROCESS | 11799 | Values: 1266.0 1320.8 1400.8 1416.5 1841.7 8553.5 8921.7 8976.6 9217.8 9234.6 8918.7 9163.9 9166.5 9167.2 9180.4 2072.2 2273.1 2328.5 2351.0 2358.1 2385.6 2397.3 2400.1 2400.3 2405.4 2293.6 2307.8 2325.0 2326.2 2326.8 1990.8 2069.9 2226.8 2308.7 2312.7 2298.1 2301.7 2324.2 2327.1 2329.5 9044.8 9129.9 9137.4 9138.2 9143.9 5216.7 5349.9 5360.7 5361.3 5364.5 3380.8 3401.0 3443.5 3449.2 3548.5 1055.7 1073.4 1078.3 1084.7 1096.2 13189.0 13217.9 13220.3 13224.0 13229.5 4653.0 4703.0 4773.5 4872.2 4908.7 10896.7 10898.7 10909.5 10919.5 10928.7 5445.1 5445.4 5452.8 5454.0 5454.1 4293.8 4768.7 4913.5 4918.6 4957.1 3853.1 3950.4 4048.4 4078.5 4124.3 1221.8 1222.7 1229.3 1238.1 1239.9 981.2 998.1 1023.5 1052.2 1065.9 6101.4 6341.7 6397.5 6473.3 6664.4 953.8 958.8 1008.1 1016.3 1030.5 4310.4 4398.4 4418.4 4420.4 4441.8 10171.1 10855.7 11050.3 11104.3 11195.6 10744.4 11174.1 11186.2 11269.7 11304.3 8365.9 8497.3 8532.5 8535.5 8541.6 5979.9 6363.2 6385.7 6410.0 6489.1 10450.9 10582.5 10626.8 10805.0 10821.1 10690.9 10819.5 10862.4 10865.3 10865.9 8364.2 8648.5 8680.0 8682.5 8752.8 8988.6 9009.1 9017.0 9086.4 9089.7 7743.8 7936.8 7978.0 8044.5 8172.3 7516.2 7758.8 7862.8 7873.1 8147.3 7212.5 7226.6 7293.1 7296.4 7302.6 5069.1 5098.7 5149.6 5194.5 5273.2 4851.0 4991.1 5023.3 5029.5 5144.2 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | [#4] prototype.html Cycles:180 Average:4579.49 Median:5236.68 stddev:1355.78 (25.9%) stddev-sans-first:1321.40 15:57:00 INFO - PROCESS | 11799 | Values: 323.0 323.6 327.6 327.9 329.4 5295.5 5350.5 5352.4 5362.9 5371.5 5262.5 5303.5 5322.8 5337.4 5340.6 3631.0 3936.2 4024.1 4040.8 4069.1 3976.1 4050.4 4083.9 4095.5 4111.2 4014.4 4050.1 4063.6 4067.4 4091.2 3915.2 4034.6 4047.5 4074.2 4095.0 3580.9 3585.0 3627.9 3637.2 3657.6 5006.4 5018.4 5021.8 5024.9 5029.6 4701.2 4705.6 4711.0 4712.4 4749.2 5210.9 5421.4 5443.8 5481.7 5481.9 3909.5 3930.3 3933.9 3939.0 3947.3 5331.5 5401.5 5666.5 5764.2 5791.2 3895.0 3912.7 3915.1 3922.0 3951.0 4046.1 4065.0 4065.7 4072.3 4073.8 4545.6 4573.9 4635.6 4638.4 4661.8 5318.8 5368.6 5431.3 5442.8 5471.2 1391.3 1394.7 1402.9 1409.5 1419.8 821.5 851.9 894.8 895.3 905.9 4690.3 4713.4 4716.8 4762.3 4763.4 4955.6 4978.6 4992.3 5015.8 5077.4 4663.0 4764.4 4827.2 4841.9 4859.8 3009.2 3029.5 3040.6 3042.9 3066.2 5877.9 5907.3 5911.2 5928.8 5933.0 5527.6 5741.9 5742.5 5797.1 5850.3 5796.2 5853.6 5878.7 5911.9 5918.2 5385.8 5421.7 5676.9 5725.7 5731.3 5541.3 5549.6 5603.6 5606.6 5632.5 5689.4 5834.8 5850.7 5853.2 5854.3 3973.9 3975.3 3990.1 4004.1 4012.2 5588.5 5607.6 5642.4 5712.5 5725.8 5309.6 5467.8 5536.6 5546.2 5576.2 5478.6 5513.3 5531.7 5539.9 5546.3 5449.3 5450.5 5466.0 5485.5 5488.6 5360.3 5575.6 5582.5 5606.4 5628.9 5410.4 5452.2 5482.3 5509.2 5517.2 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - PROCESS | 11799 | [#5] yui.html Cycles:180 Average:19704.98 Median:4731.58 stddev:27008.17 (570.8%) stddev-sans-first:27046.48 15:57:00 INFO - PROCESS | 11799 | Values: 761.2 770.6 782.4 785.3 825.7 15148.3 15765.0 15916.5 16052.8 16270.3 15060.3 15158.1 15212.9 15225.8 15226.2 818.2 823.2 831.3 836.2 837.2 678.3 798.6 823.4 828.5 828.8 872.3 873.2 874.4 875.1 875.2 703.3 863.2 863.7 864.1 864.4 610.7 624.0 625.0 626.1 626.7 15379.0 15403.7 15425.2 15442.4 15474.7 14001.6 14501.9 14575.6 14753.9 14922.6 12439.4 12628.0 12666.4 12775.7 13264.8 4438.6 4480.0 4491.5 4571.9 4612.9 74435.6 74454.6 74473.3 74473.6 74629.3 11387.6 11388.9 11421.0 11879.9 12179.1 21740.2 21941.3 21953.3 21993.6 22016.1 8342.8 8365.2 8423.4 8474.7 8764.1 4536.5 4563.8 4745.0 4852.0 4861.9 3534.1 3626.5 3651.3 3659.7 3662.5 599.9 654.8 677.4 689.8 699.5 668.4 736.7 738.2 743.7 773.7 9488.1 9796.7 9857.4 9989.0 9994.2 742.4 744.6 749.5 755.5 778.7 4275.5 4497.8 4589.4 4662.7 4718.2 69765.7 71901.0 71992.3 73990.6 74114.3 65384.0 76836.0 107043.5 107086.5 108021.5 60379.7 93767.0 97157.5 97299.0 98778.5 8883.1 9133.7 9219.2 9338.0 9367.7 44168.8 51732.5 53888.0 54012.7 55200.7 71500.0 78779.2 80517.2 80671.0 81838.2 11003.5 11288.1 11954.8 12112.3 13108.1 14076.5 14298.4 14599.9 14774.4 15004.3 13595.8 13778.1 14066.3 14085.6 14360.6 13046.5 13630.9 13927.5 14149.3 14416.4 13018.6 13430.5 13453.4 13865.9 13879.0 11383.1 11417.8 11764.9 11784.4 12025.6 11006.4 11006.7 11152.3 11294.3 11360.1 15:57:00 INFO - PROCESS | 11799 | -------- Summary: end -------- 15:57:00 INFO - PROCESS | 11799 | 15:57:00 INFO - TEST-INFO | 11799: exit 0 15:57:00 INFO - TEST-OK | dromaeo_css | took 1115686ms 15:57:00 INFO - TEST-START | kraken 15:57:00 INFO - operating with platform_type : linux_ 15:57:00 INFO - Initialising browser for kraken test... 15:57:00 INFO - TEST-INFO | started process 11982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVVLBvE/profile http://localhost:50104/getInfo.html) 15:57:05 INFO - PROCESS | 11982 | __metrics Screen width/height:1600/1200 15:57:05 INFO - PROCESS | 11982 | colorDepth:24 15:57:05 INFO - PROCESS | 11982 | Browser inner width/height: 1024/697 15:57:05 INFO - PROCESS | 11982 | __metrics 15:57:06 ERROR - PROCESS | 11982 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:57:06 INFO - PROCESS | 11982 | console.error: PushService: 15:57:06 INFO - PROCESS | 11982 | stateChangeProcessEnqueue: Error transitioning state 15:57:06 INFO - PROCESS | 11982 | UnknownError 15:57:07 INFO - TEST-INFO | 11982: exit 0 15:57:07 INFO - Browser initialized. 15:57:07 INFO - Running cycle 1/1 for kraken test... 15:57:07 INFO - TEST-INFO | started process 12066 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVVLBvE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:19 INFO - PROCESS | 12066 | RSS: Main: 159547392 15:57:19 INFO - PROCESS | 12066 | 15:57:22 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50104/tests/kraken/driver.html?testName=audio-beat-detection) 15:57:22 INFO - PROCESS | 12066 | RSS: Main: 217677824 15:57:22 INFO - PROCESS | 12066 | 15:57:25 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50104/tests/kraken/driver.html?testName=audio-dft) 15:57:26 INFO - PROCESS | 12066 | RSS: Main: 205078528 15:57:26 INFO - PROCESS | 12066 | 15:57:29 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50104/tests/kraken/driver.html?testName=audio-fft) 15:57:29 INFO - PROCESS | 12066 | RSS: Main: 271847424 15:57:29 INFO - PROCESS | 12066 | 15:57:32 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50104/tests/kraken/driver.html?testName=audio-oscillator) 15:57:32 INFO - PROCESS | 12066 | RSS: Main: 182349824 15:57:32 INFO - PROCESS | 12066 | 15:57:34 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50104/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:57:35 INFO - PROCESS | 12066 | RSS: Main: 186884096 15:57:35 INFO - PROCESS | 12066 | 15:57:38 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50104/tests/kraken/driver.html?testName=imaging-darkroom) 15:57:39 INFO - PROCESS | 12066 | RSS: Main: 221388800 15:57:39 INFO - PROCESS | 12066 | 15:57:42 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50104/tests/kraken/driver.html?testName=imaging-desaturate) 15:57:42 INFO - PROCESS | 12066 | RSS: Main: 222998528 15:57:42 INFO - PROCESS | 12066 | 15:57:45 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50104/tests/kraken/driver.html?testName=json-parse-financial) 15:57:46 INFO - PROCESS | 12066 | RSS: Main: 223408128 15:57:46 INFO - PROCESS | 12066 | 15:57:47 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50104/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:57:48 INFO - PROCESS | 12066 | RSS: Main: 209981440 15:57:48 INFO - PROCESS | 12066 | 15:57:50 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:57:50 INFO - PROCESS | 12066 | RSS: Main: 213557248 15:57:50 INFO - PROCESS | 12066 | 15:57:53 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:57:53 INFO - PROCESS | 12066 | RSS: Main: 216977408 15:57:53 INFO - PROCESS | 12066 | 15:57:56 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:57:56 INFO - PROCESS | 12066 | RSS: Main: 222347264 15:57:56 INFO - PROCESS | 12066 | 15:57:59 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:58:00 INFO - PROCESS | 12066 | RSS: Main: 222105600 15:58:00 INFO - PROCESS | 12066 | 15:58:01 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:50104/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50104/tests/kraken/driver.html?testName=ai-astar) 15:58:02 INFO - PROCESS | 12066 | RSS: Main: 263847936 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | __start_tp_report 15:58:02 INFO - PROCESS | 12066 | _x_x_mozilla_page_load 15:58:02 INFO - PROCESS | 12066 | _x_x_mozilla_page_load_details 15:58:02 INFO - PROCESS | 12066 | |i|pagename|runs| 15:58:02 INFO - PROCESS | 12066 | |0;ai-astar;110;106;109;111;111;109;111;110;110;111 15:58:02 INFO - PROCESS | 12066 | |1;audio-beat-detection;155;125;126;178;129;125;149;134;191;134 15:58:02 INFO - PROCESS | 12066 | |2;audio-dft;162;161;160;161;161;161;163;161;160;162 15:58:02 INFO - PROCESS | 12066 | |3;audio-fft;74;74;75;129;74;75;75;74;104;75 15:58:02 INFO - PROCESS | 12066 | |4;audio-oscillator;84;84;116;84;84;87;116;87;85;127 15:58:02 INFO - PROCESS | 12066 | |5;imaging-gaussian-blur;117;117;116;116;117;116;116;117;117;117 15:58:02 INFO - PROCESS | 12066 | |6;imaging-darkroom;145;146;145;145;146;145;146;146;146;145 15:58:02 INFO - PROCESS | 12066 | |7;imaging-desaturate;95;95;95;95;94;95;95;95;95;96 15:58:02 INFO - PROCESS | 12066 | |8;json-parse-financial;56;53;52;54;53;54;52;59;52;53 15:58:02 INFO - PROCESS | 12066 | |9;json-stringify-tinderbox;62;63;63;62;63;62;63;63;62;62 15:58:02 INFO - PROCESS | 12066 | |10;stanford-crypto-aes;83;86;80;80;79;81;81;79;113;86 15:58:02 INFO - PROCESS | 12066 | |11;stanford-crypto-ccm;131;135;127;135;119;130;121;125;124;158 15:58:02 INFO - PROCESS | 12066 | |12;stanford-crypto-pbkdf2;161;158;159;164;159;158;159;160;162;160 15:58:02 INFO - PROCESS | 12066 | |13;stanford-crypto-sha256-iterative;67;63;65;63;63;62;64;62;64;62 15:58:02 INFO - PROCESS | 12066 | __end_tp_report 15:58:02 INFO - PROCESS | 12066 | __start_cc_report 15:58:02 INFO - PROCESS | 12066 | _x_x_mozilla_cycle_collect,1593 15:58:02 INFO - PROCESS | 12066 | __end_cc_report 15:58:02 INFO - PROCESS | 12066 | __startTimestamp1465340282135__endTimestamp 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | ------- Summary: start ------- 15:58:02 INFO - PROCESS | 12066 | Number of tests: 14 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.50 stddev:1.55 (1.4%) stddev-sans-first:1.64 15:58:02 INFO - PROCESS | 12066 | Values: 110.0 106.0 109.0 111.0 111.0 109.0 111.0 110.0 110.0 111.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:141.50 stddev:23.52 (16.6%) stddev-sans-first:24.64 15:58:02 INFO - PROCESS | 12066 | Values: 155.0 125.0 126.0 178.0 129.0 125.0 149.0 134.0 191.0 134.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 15:58:02 INFO - PROCESS | 12066 | Values: 162.0 161.0 160.0 161.0 161.0 161.0 163.0 161.0 160.0 162.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#3] audio-fft Cycles:10 Average:82.90 Median:74.50 stddev:18.67 (25.1%) stddev-sans-first:19.52 15:58:02 INFO - PROCESS | 12066 | Values: 74.0 74.0 75.0 129.0 74.0 75.0 75.0 74.0 104.0 75.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:84.00 stddev:17.05 (20.3%) stddev-sans-first:17.58 15:58:02 INFO - PROCESS | 12066 | Values: 84.0 84.0 116.0 84.0 84.0 87.0 116.0 87.0 85.0 127.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:58:02 INFO - PROCESS | 12066 | Values: 117.0 117.0 116.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 15:58:02 INFO - PROCESS | 12066 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 146.0 146.0 146.0 145.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 15:58:02 INFO - PROCESS | 12066 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 96.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:2.20 (4.1%) stddev-sans-first:2.19 15:58:02 INFO - PROCESS | 12066 | Values: 56.0 53.0 52.0 54.0 53.0 54.0 52.0 59.0 52.0 53.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#9] json-stringify-tinderbox Cycles:10 Average:62.50 Median:63.00 stddev:0.53 (0.8%) stddev-sans-first:0.53 15:58:02 INFO - PROCESS | 12066 | Values: 62.0 63.0 63.0 62.0 63.0 62.0 63.0 63.0 62.0 62.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:81.00 stddev:10.24 (12.6%) stddev-sans-first:10.84 15:58:02 INFO - PROCESS | 12066 | Values: 83.0 86.0 80.0 80.0 79.0 81.0 81.0 79.0 113.0 86.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:130.50 stddev:11.08 (8.5%) stddev-sans-first:11.75 15:58:02 INFO - PROCESS | 12066 | Values: 131.0 135.0 127.0 135.0 119.0 130.0 121.0 125.0 124.0 158.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:160.00 stddev:1.89 (1.2%) stddev-sans-first:1.96 15:58:02 INFO - PROCESS | 12066 | Values: 161.0 158.0 159.0 164.0 159.0 158.0 159.0 160.0 162.0 160.0 15:58:02 INFO - PROCESS | 12066 | 15:58:02 INFO - PROCESS | 12066 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:63.50 stddev:1.58 (2.5%) stddev-sans-first:1.05 15:58:02 INFO - PROCESS | 12066 | Values: 67.0 63.0 65.0 63.0 63.0 62.0 64.0 62.0 64.0 62.0 15:58:02 INFO - PROCESS | 12066 | -------- Summary: end -------- 15:58:02 INFO - PROCESS | 12066 | 15:58:03 INFO - TEST-INFO | 12066: exit 0 15:58:03 INFO - TEST-OK | kraken | took 62269ms 15:58:03 INFO - SUITE-END | took 1177s 15:58:03 INFO - Completed test suite (00:19:38) 15:58:03 INFO - javascript benchmark 15:58:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3153.8107713537174, 3158.6367567729712, 3174.8253846038465, 3197.6642452542496, 3230.4346739320613, 5272.156454967206, 5310.548603368714, 5365.839024829252, 5402.918956215655, 5441.646292990953, 5340.54605358545, 5371.946280537203, 5376.758045887935, 5380.596455265861, 5422.969635769201, 4816.614670826334, 4897.2898929655175, 4903.362562866822, 4919.4096708395045, 5075.010372977271, 4973.030259099477, 4995.250712393134, 5017.9247311290355, 5032.194848824189, 5047.192449208127, 4807.639427042968, 4808.831690890819, 4856.660033797618, 4883.804647814083, 4912.263160525914, 4879.0, 4890.413150421937, 4898.2162853943555, 4901.534354236377, 5001.824936127255, 4902.828401005948, 4924.704517728947, 4933.506649335073, 4937.111319962405, 4948.752562371867, 5222.112240919053, 5246.396664383607, 5312.946870531323, 5321.281626980347, 5354.892902141974, 5800.8259752207505, 5814.622049566807, 5843.386444423317, 5849.970750146264, 5851.23933888596, 5877.823665290005, 5880.353161152269, 5887.116932460122, 5895.528357731371, 5899.528037756969, 5285.524302812727, 5346.03771321162, 5400.972995134999, 5405.351357837046, 5409.702466364309, 5862.677552734641, 5932.436418540232, 5947.256592925884, 5955.79154729591, 5956.76172953077, 5518.117101263779, 5720.513756330759, 5723.7996670116345, 5723.8569035774435, 5727.971360143172, 5705.857353566194, 5793.8841223175305, 5805.651660900359, 5846.239988801429, 5846.736896839652, 5807.157962095473, 5814.418558144236, 5820.301563812285, 5821.650700957956, 5825.970870145622, 5655.915161272585, 5732.140178973104, 5776.797812076557, 5840.707964601753, 5843.357230704619, 4195.307774217281, 4224.324108142682, 4224.577542245751, 4263.87208383749, 4266.914661706748, 3772.0, 3795.7532760370486, 3796.867109651149, 3820.9617903821163, 3836.175222327213, 5217.452167522356, 5335.866603334947, 5378.462153784669, 5476.288082549243, 5510.063289240758, 5776.797812076641, 5814.360420353758, 5834.387389324144, 5838.795642152504, 5900.085486749561, 5442.591805614516, 5450.073487507133, 5451.618386712971, 5452.536534394541, 5457.918131228034, 4745.596624286905, 4768.260919557405, 4812.205986012269, 4866.367372241586, 4924.064427758714, 5958.404159584094, 5961.970190149108, 5965.463108320272, 5965.880682386328, 6001.2498437695285, 5780.2485676863425, 5792.4497172768515, 5792.82621521355, 5793.623414478046, 5798.072308430799, 5783.450572195635, 5828.300603927556, 5857.736401862012, 5912.0, 5954.196183515343, 5666.575006874418, 5678.091505359288, 5680.261565996394, 5680.914786278292, 5684.8010319638615, 5690.146478028329, 5778.797742079175, 5780.971095144498, 5782.884342313214, 5785.508231800344, 5452.972735136299, 5581.162825576196, 5597.692126933058, 5620.4660557247, 5681.460261275172, 5685.545156387562, 5685.60200785941, 5688.146777983336, 5689.402612725604, 5701.40135285789, 5364.061289274439, 5522.337319521683, 5591.049521581423, 5592.077307244259, 5598.636088654224, 5580.720963951868, 5603.859903502445, 5639.436056394327, 5727.8568035799435, 5744.712764361681, 5555.833325000256, 5556.249906262765, 5557.0, 5561.610687251854, 5565.026120428828], "value": 5307.226212901957, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.4322979628945, 621.5462712220079, 623.3921926122032, 638.9904151437727, 640.3500447046251, 42508.14983700324, 43962.340564891514, 44168.0, 48515.272270915964, 48608.51391486084, 46294.0, 46760.53239467604, 46778.76610616942, 46781.0, 46833.76583117079, 8908.0, 9106.86339704905, 9110.772230694252, 9115.544222788867, 9120.270378369747, 8882.422642528216, 8975.596098175598, 8987.505687187202, 8995.55022248885, 8999.0, 9013.36906416551, 9050.456972581665, 9054.954725226366, 9059.320550958671, 9065.274778017743, 8982.86525702115, 9036.0, 9039.412438191563, 9040.321975851837, 9049.638014479413, 8925.598348074287, 9027.63889444428, 9030.142136497088, 9031.50326732036, 9033.23217526511, 43725.34411983791, 44212.55787442149, 44268.55731442677, 44309.33535996963, 44390.0, 3553.893383198508, 3599.1002249437643, 3637.2543628556346, 3683.37382644951, 3849.287881741887, 33685.83157084223, 33873.83063084693, 33971.0, 34289.82855085734, 35462.64537354619, 4311.439512863339, 4341.088371442001, 4363.672724545671, 4400.339949007643, 4407.471103467572, 336840.0, 337446.0, 337465.0, 338589.0, 339315.0, 51001.429899907394, 59406.70296648489, 60203.39796602065, 60272.39727602756, 60349.0, 59669.40330596725, 61073.38926610766, 61428.69285653543, 67169.32830671729, 67260.66369668121, 17421.564460888578, 17509.562260943578, 17589.7361539577, 17666.823331766776, 17668.558286042695, 12162.08784341178, 12438.875611243953, 13052.869471305356, 13125.54060607874, 13130.0, 16915.323387064378, 16974.915125424293, 17360.0, 17761.7335739964, 17833.375831845824, 5899.4985426239255, 5963.075723262901, 5981.820545383645, 6031.728572214261, 6068.666223357758, 1019.6838979916249, 1091.9672409827558, 1126.0541145437708, 1169.4386694386742, 1182.739797244605, 31578.526322105186, 31685.841570792, 31731.84134079361, 31886.84056579702, 32499.837500812817, 1121.7027487715761, 1138.2715062360096, 1148.3224897310627, 1176.9470373833199, 1234.154604096197, 16805.0, 17001.914990424968, 17010.744838827428, 17053.31786728542, 17392.304307827544, 493233.0, 494661.0, 498397.0, 499090.0, 499434.0, 58114.0, 58222.0, 58246.70876645589, 58300.12549811756, 58374.0, 1276.9106162568717, 1365.4128724648497, 1420.801087847702, 1427.5289154578757, 1429.5997120806192, 31831.522527161647, 32707.836460817543, 33143.0, 33210.50184247286, 33636.831815840764, 51038.74480627573, 51782.48217517776, 52033.47966520286, 52039.0, 52074.218886717485, 308262.0, 310395.0, 311015.0, 311084.0, 311674.0, 34908.0, 40363.394549082375, 41065.178696426505, 41069.0, 43914.56085439105, 48168.759156203996, 48292.5170748288, 48941.755291223315, 48943.5105648939, 49639.0, 40055.59944400635, 40431.3935290965, 40991.0, 41604.79197603992, 42039.36940945827, 40526.0, 40711.98220044404, 40771.38842917418, 41236.79381603073, 41262.0, 40212.1957560853, 40560.0, 41509.37735933903, 41825.58174418217, 41841.79079104707, 34357.312853743286, 36148.27703445969, 36302.455463168604, 36786.63213367832, 36803.815980921, 27590.862045689642, 27885.860570697016, 28014.2996425091, 28187.859060704566, 28198.0], "value": 20293.943627546756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.09720289139284, 397.82495701891185, 400.0798164222288, 401.45602708330205, 413.4996654048601, 17878.0, 19066.523336916584, 19280.0, 19300.903495482537, 20203.696944545805, 18375.632487350245, 18395.26418943244, 18988.240470381203, 19020.429387118373, 19216.519587010298, 5601.579881508883, 5706.80026199081, 5775.768969241225, 5781.537477001851, 5783.277090363705, 5587.217789509472, 5643.294588176478, 5670.886582268352, 5688.544916406678, 5690.06113991191, 5558.027345214591, 5612.494875461208, 5667.206591077253, 5682.630629009122, 5684.602077854552, 5653.745581448845, 5661.405552416999, 5664.546836253131, 5675.5459563234845, 5680.602357834953, 4817.349657796188, 4976.0545496355935, 4980.626453016038, 4982.377202849644, 4989.077020751172, 19562.217511299532, 19812.70280945787, 22387.55224895493, 22888.427789305235, 23026.884865575565, 2248.87631180285, 2355.799757020638, 2433.051110067075, 2595.545779488582, 2970.2425881400322, 13620.0, 13631.727365452734, 13753.518626848012, 14202.92898535511, 14558.70882582353, 1893.5455490682136, 1958.4026871804094, 1985.0, 1991.8705284156483, 2058.0, 75525.62237188779, 75803.0, 76068.61965690191, 76201.23798761997, 78760.21239787586, 9789.608415663437, 9995.900040999642, 10675.786484270271, 10746.462676866282, 10873.0, 14769.18769467668, 14858.851411485746, 14898.702025959421, 14974.251287435585, 15003.774943375858, 8090.797730056795, 8113.959430202929, 8152.959235203904, 8246.793830154294, 8259.710910118238, 7689.538627682245, 7777.496236454924, 7804.2585954334245, 7961.641726122339, 7998.960005199937, 6538.378854008862, 6592.967035164794, 6620.933790662128, 6622.735090596419, 6627.337266273431, 1894.488488108203, 1898.924043038263, 1917.338518211215, 1935.4967708395918, 1936.80886254955, 941.7410212191566, 1012.372329155928, 1046.3460337289193, 1092.9234953553334, 1131.2194585735815, 18442.35451759182, 18519.90740046291, 19427.902860485607, 19587.80412195888, 19752.90123549373, 1059.8410238464137, 1066.4347895615335, 1115.6206889657556, 1118.8433619293305, 1151.3092144713273, 10734.785304294026, 11219.439028048564, 11258.380789056517, 11271.38007409584, 11302.321860688386, 451499.0, 456522.0, 459612.0, 462063.0, 464780.0, 72292.63853680698, 74384.0, 74660.62669686726, 75463.62268188734, 76316.23683763202, 1033.6950599573145, 1037.8806437259661, 1056.4770438632927, 1057.603398725478, 1067.8611780468489, 16686.24911878944, 16841.41055063116, 17039.82960170432, 17136.914315428345, 17432.21555030052, 37211.44182837205, 37255.0, 38289.40726042215, 38778.61221387863, 39155.41266880942, 49233.0, 50911.23633145432, 51597.226041610156, 51665.0, 51879.48120518746, 8239.217274358927, 8251.504909705436, 8290.668373265002, 8296.70961516344, 8406.705765298186, 12489.562865299671, 12706.87293127057, 12708.491660333482, 12764.0, 12778.68053298675, 11156.776864462827, 11230.55077796879, 11385.203035787747, 11410.54357825711, 11440.199186057229, 10947.616833410792, 11051.086143805584, 11091.168162387692, 11445.713857153305, 11459.598914037968, 11105.111591072857, 11349.319040857576, 11491.655250342503, 11513.53945842157, 11842.703932401759, 8299.08710041907, 8321.625526851427, 8375.748727538183, 8383.329333653415, 8435.216378594927, 11449.198556100993, 11566.47950842231, 11615.825762613398, 11710.355930423908, 11783.469743861719], "value": 9185.383520962432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1266.0314859132766, 1320.8414990201177, 1400.7898815177723, 1416.490063577112, 1841.723741438784, 8553.529555874424, 8921.687740929068, 8976.596053177607, 9217.815643687121, 9234.630614775419, 8918.687845925393, 9163.862542061874, 9166.450012999225, 9167.220786233178, 9180.403273787217, 2072.2332736887374, 2273.147569661377, 2328.4877326988035, 2351.0, 2358.0921345282104, 2385.6063749481314, 2397.304781613339, 2400.051979468109, 2400.2559206645997, 2405.4226985523437, 2293.552757212344, 2307.8038366738847, 2324.9651255231183, 2326.220716060122, 2326.755690652474, 1990.8009199080047, 2069.9275525356616, 2226.832987525942, 2308.6883270758567, 2312.6531020346915, 2298.138198175684, 2301.746807851135, 2324.1981516376823, 2327.138958585317, 2329.4991576811067, 9044.773880653036, 9129.908700912973, 9137.40606860552, 9138.177564019205, 9143.862842057375, 5216.66091704038, 5349.866253343658, 5360.705161216131, 5361.329833770778, 5364.490373414519, 3380.7633465657414, 3401.0, 3443.517907492953, 3449.2066824630474, 3548.538689970313, 1055.7400887419303, 1073.420353009374, 1078.3260462211117, 1084.7342401111778, 1096.155959910872, 13189.0, 13217.867821321759, 13220.338983050906, 13224.0, 13229.536966206137, 4652.97673511637, 4702.976485117552, 4773.451053128934, 4872.196087645499, 4908.656394052382, 10896.67309980686, 10898.72753181161, 10909.509072091774, 10919.454027298603, 10928.726781830517, 5445.101558242845, 5445.37378201504, 5452.836414907559, 5454.018276710231, 5454.072807622634, 4293.806778694966, 4768.690035147704, 4913.459519452857, 4918.606511479073, 4957.107720610253, 3853.133045064894, 3950.3876899080688, 4048.39274108886, 4078.510578730512, 4124.298869192245, 1221.780079585683, 1222.7371115210271, 1229.3484453239798, 1238.0714464151886, 1239.8884100431005, 981.1513041219293, 998.0817647764075, 1023.5394072667181, 1052.163529993657, 1065.8827528971806, 6101.359357267423, 6341.651209183539, 6397.52018598607, 6473.320301368382, 6664.366885145903, 953.7615596100975, 958.8130314588727, 1008.0927165550917, 1016.2936758952457, 1030.4744580264119, 4310.374995625617, 4398.3842262083335, 4418.425604671372, 4420.447444069491, 4441.844535441309, 10171.135453486388, 10855.67432977012, 11050.33697978124, 11104.27822191571, 11195.608153714744, 10744.409057501756, 11174.10607151426, 11186.16103792219, 11269.71825704364, 11304.321740695585, 8365.916340836635, 8497.277731392776, 8532.488050716976, 8535.487870727777, 8541.572921353907, 5979.940200598112, 6363.204599425072, 6385.744570217139, 6410.0, 6489.091527186292, 10450.895491044992, 10582.523786429476, 10626.84059739089, 10805.0, 10821.134309255083, 10690.893091069302, 10819.459027048773, 10862.402567858528, 10865.348079115278, 10865.891341086804, 8364.163583641588, 8648.481091134552, 8680.0, 8682.479051256692, 8752.824943500967, 8988.595513202052, 9009.144131307516, 9016.954915225382, 9086.409383390059, 9089.727308180765, 7743.845123097619, 7936.761897142864, 7978.0, 8044.4771089881315, 8172.346212302882, 7516.248375162659, 7758.806029849299, 7862.803429914298, 7873.055233372032, 8147.307478864363, 7212.459065570209, 7226.566406015656, 7293.124825021031, 7296.379807716191, 7302.56184628924, 5069.138246498178, 5098.719570423662, 5149.588032957429, 5194.480551944926, 5273.209018647233, 4850.975745121393, 4991.101601711727, 5023.296738456546, 5029.497050295087, 5144.151215049475], "value": 4754.864649805924, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.0389590966874, 323.5793468490961, 327.59705562158535, 327.8590206211328, 329.44817430803414, 5295.523402893738, 5350.5184533391985, 5352.384475785286, 5362.865928351793, 5371.462853714632, 5262.500062494057, 5303.522682958533, 5322.840314790553, 5337.359516857973, 5340.62615616907, 3631.0151688481537, 3936.1734035852505, 4024.134811015631, 4040.7777572233517, 4069.1454794493193, 3976.0855003349247, 4050.3519436890106, 4083.8979025524304, 4095.5494895561465, 4111.218868414991, 4014.3777714454304, 4050.0887300357476, 4063.634272915437, 4067.4102255172984, 4091.161311931062, 3915.2365288768415, 4034.616711412411, 4047.4738284023183, 4074.164796216798, 4095.0, 3580.905170475275, 3584.964150358489, 3627.8730244441317, 3637.1816341323256, 3657.56109266887, 5006.4492905780335, 5018.422881368653, 5021.799128034911, 5024.899502009976, 5029.572486338666, 4701.224297990828, 4705.55297246761, 4710.952890471086, 4712.434507859079, 4749.240121580565, 5210.869728256786, 5421.430749771257, 5443.8094666686875, 5481.698506582136, 5481.890362192734, 3909.5113110861143, 3930.2532518821617, 3933.9213215735813, 3938.960610393888, 3947.328954077785, 5331.493508116684, 5401.4868587484125, 5666.5183459406, 5764.213093940892, 5791.160281759164, 3895.045713800137, 3912.68698504119, 3915.1190982028816, 3921.9607803922167, 3951.0, 4046.069404037088, 4064.9796751016056, 4065.7435123506148, 4072.289638351899, 4073.7778666399963, 4545.568171023748, 4573.91260898892, 4635.629149668019, 4638.420197475316, 4661.813527458864, 5318.7726369175925, 5368.597355198298, 5431.321084864392, 5442.836714898559, 5471.23402723619, 1391.3234552364377, 1394.741972735027, 1402.894782891267, 1409.485537778724, 1419.7577120019982, 821.5112008355019, 851.9275861551714, 894.8299823033602, 895.2703546609492, 905.8505346617866, 4690.334931099411, 4713.434387873477, 4716.834910778175, 4762.261849413347, 4763.380760501182, 4955.603551715854, 4978.5768209701855, 4992.301077849104, 5015.793051057523, 5077.365329333833, 4662.9766851166205, 4764.404449443819, 4827.2035114206465, 4841.942174575791, 4859.80560777572, 3009.2326456753826, 3029.4698427775493, 3040.559118927743, 3042.915602812081, 3066.1874603230162, 5877.942720047949, 5907.2911250650195, 5911.172435858984, 5928.822135335947, 5932.970335148383, 5527.585431092684, 5741.942580574141, 5742.511886489611, 5797.115937320388, 5850.3272123705465, 5796.246487956623, 5853.619514731614, 5878.735456904449, 5911.940880591139, 5918.171455996078, 5385.8114965976, 5421.701806400686, 5676.886462270649, 5725.713714314351, 5731.312242530923, 5541.2796336476, 5549.5837812165055, 5603.5797315202335, 5606.579506537108, 5632.5493960482245, 5689.402612725769, 5834.795782147605, 5850.707464626837, 5853.239078919713, 5854.326752423526, 3973.9403908941968, 3975.2844487991283, 3990.1421194443333, 4004.0790618157403, 4012.1574469360594, 5588.497035266845, 5607.579431542732, 5642.379338272869, 5712.457316554921, 5725.770969161187, 5309.628326017141, 5467.781288748405, 5536.584756143225, 5546.168074788814, 5576.163575463713, 5478.589105817159, 5513.255710479034, 5531.695756733418, 5539.944600553943, 5546.251256080377, 5449.3188351456065, 5450.509454148987, 5465.9726701366235, 5485.4788795066, 5488.615796894179, 5360.303160589098, 5575.609707320448, 5582.49757521825, 5606.411326810626, 5628.915566266591, 5410.3778065522965, 5452.236686863763, 5482.2873026506295, 5509.173623956439, 5517.2275881377445], "value": 4145.482817805943, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.2045412543891, 770.6300975531744, 782.4210084537444, 785.3206975965788, 825.6738588257637, 15148.318325675344, 15765.0, 15916.52250432486, 16052.83947160528, 16270.349186032545, 15060.322285497152, 15158.090514569134, 15212.923935380308, 15225.84774152261, 15226.162561059138, 818.2145140664954, 823.180934969706, 831.2518733140192, 836.2056046755577, 837.1753822484848, 678.297961609734, 798.5847359373151, 823.4071468542639, 828.5484410995991, 828.7886588919818, 872.2978002707817, 873.2010210657241, 874.3617159473588, 875.0724232313768, 875.1948207648946, 703.3212949503702, 863.1713554987219, 863.7020228021386, 864.1099667342647, 864.4337958637103, 610.6671863834176, 624.0296339192554, 625.0343219725535, 626.1077963901422, 626.7023163997111, 15379.0, 15403.691926161415, 15425.15161666108, 15442.382304707799, 15474.690506189925, 14001.649958751012, 14501.854981450157, 14575.635609109857, 14753.92623036878, 14922.552323430315, 12439.440225189888, 12628.0, 12666.36668166588, 12775.74448511034, 13264.801027984588, 4438.578335058164, 4479.9552004479865, 4491.461024677027, 4571.885702857422, 4612.930806037912, 74435.62782186054, 74454.62772686157, 74473.25526744772, 74473.62763186204, 74629.25370746278, 11387.601433949772, 11388.886111138949, 11421.001185863071, 11879.940600296943, 12179.086568507397, 21740.239091631716, 21941.341759747233, 21953.341399758032, 21993.560128797333, 22016.11935522561, 8342.791430214293, 8365.163483651708, 8423.410361274651, 8474.703385381605, 8764.123587641312, 4536.546345365503, 4563.771811409482, 4744.952550474451, 4852.0, 4861.927071093936, 3534.0988048047584, 3626.492291079251, 3651.3427583034777, 3659.70722342212, 3662.487251784752, 599.8950183717832, 654.8428377189535, 677.4275737002324, 689.7965100295324, 699.5138378826769, 668.3817468841303, 736.7237286017744, 738.1879932074675, 743.7248218159316, 773.6789232468462, 9488.146066854017, 9796.706098817047, 9857.35927164732, 9988.977611084187, 9994.150497207818, 742.3504433620582, 744.556988591787, 749.4828568287863, 755.4787196834277, 778.7079845058104, 4275.486941567032, 4497.797599108048, 4589.449266088091, 4662.650301227353, 4718.200555963104, 69765.65117174381, 71901.0, 71992.28007719961, 73990.63004685049, 74114.25885741181, 65384.0, 76836.0, 107043.46478267558, 107086.46456767667, 108021.4598927016, 60379.69810150921, 93767.0, 97157.51421242849, 97299.0, 98778.50610747044, 8883.108889561812, 9133.725988220363, 9219.216366608913, 9338.0, 9367.718968430958, 44168.779156104014, 51732.48267517277, 53888.0, 54012.729936350064, 55200.72399637976, 71500.0, 78779.2122078772, 80517.19482805097, 80671.0, 81838.18161818544, 11003.504842281956, 11288.096952243637, 11954.760904781682, 12112.273263604213, 13108.147970381895, 14076.507322243673, 14298.428062877369, 14599.85400145985, 14774.409023638935, 15004.324805383565, 13595.756560580792, 13778.104423212459, 14066.296685165906, 14085.577432677037, 14360.56918292453, 13046.54337098197, 13630.86369136296, 13927.512537061155, 14149.292535372984, 14416.351264193347, 13018.609441716764, 13430.529931452353, 13453.394597242952, 13865.861341386457, 13879.0, 11383.146264030065, 11417.80367798449, 11764.882351176379, 11784.410779461165, 12025.579104731292, 11006.394648294101, 11006.669799906016, 11152.330860148417, 11294.322340659586, 11360.091192704398], "value": 7567.633678762773, "unit": "score"}], "name": "dromaeo_css", "value": 7269.564212903597, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 109.0, 111.0, 111.0, 109.0, 111.0, 110.0, 110.0, 111.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 126.0, 178.0, 129.0, 125.0, 149.0, 134.0, 191.0, 134.0], "value": 144.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 161.0, 161.0, 163.0, 161.0, 160.0, 162.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 129.0, 74.0, 75.0, 75.0, 74.0, 104.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 84.0, 84.0, 87.0, 116.0, 87.0, 85.0, 127.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 52.0, 54.0, 53.0, 54.0, 52.0, 59.0, 52.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 63.0, 62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 80.0, 80.0, 79.0, 81.0, 81.0, 79.0, 113.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 135.0, 127.0, 135.0, 119.0, 130.0, 121.0, 125.0, 124.0, 158.0], "value": 130.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 158.0, 159.0, 164.0, 159.0, 158.0, 159.0, 160.0, 162.0, 160.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 65.0, 63.0, 63.0, 62.0, 64.0, 62.0, 64.0, 62.0], "value": 63.5, "unit": "score"}], "name": "kraken", "value": 1506.1, "alertThreshold": 2.0}]} 15:58:03 INFO - Return code: 0 15:58:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:58:03 ERROR - # TBPL SUCCESS # 15:58:03 INFO - Running post-action listener: _resource_record_post_action 15:58:03 INFO - ##### 15:58:03 INFO - ##### Finished run-tests step (success) 15:58:03 INFO - ##### 15:58:03 INFO - Running post-run listener: _resource_record_post_run 15:58:03 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 541638656; Read time: 0; Write time: 1117472 15:58:03 INFO - TinderboxPrint: CPU usage
13.2% 15:58:03 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 15:58:03 INFO - TinderboxPrint: I/O write bytes / time
541,638,656 / 1,117,472 15:58:03 INFO - TinderboxPrint: CPU idle
8,282.3 (86.8%) 15:58:03 INFO - TinderboxPrint: CPU user
1,209.7 (12.7%) 15:58:03 INFO - TinderboxPrint: Swap in / out
0 / 0 15:58:03 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 241487872; Read time: 0; Write time: 673432 15:58:03 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 293306368; Read time: 0; Write time: 434468 15:58:04 INFO - Running post-run listener: _upload_blobber_files 15:58:04 INFO - Blob upload gear active. 15:58:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:58:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:58:04 INFO - (blobuploader) - INFO - Open directory for files ... 15:58:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:58:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:58:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:09 INFO - (blobuploader) - INFO - Done attempting. 15:58:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:58:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:10 INFO - (blobuploader) - INFO - Done attempting. 15:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:58:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:11 INFO - (blobuploader) - INFO - Done attempting. 15:58:11 INFO - (blobuploader) - INFO - Iteration through files over. 15:58:11 INFO - Return code: 0 15:58:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:58:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:58:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c89b81cbccf7891897e2ca3533798353a5da276a0a58609be069680aa2b123205d3276107eb72253c972a59e2bbae2cf7dc38e6c67242c9bbee2304dbe6254b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/273c2804dd08df65b8867d3242310023523d2aa73740ff467a4d26a0fd007f0e391fe89ec24c7b5506cfd8e513f6c82876c3a44f6ff24274e9cac1c7dd1c9177", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cbddd5113169125a8199f7f81d66bd5a61c1cfd7d9c584942bb5717b5e9d176934ad2ecd6a0b91609713459a9908ae23eace855e8ea941ed0cd68aec5634c5c5"} 15:58:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:58:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:58:11 INFO - Contents: 15:58:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c89b81cbccf7891897e2ca3533798353a5da276a0a58609be069680aa2b123205d3276107eb72253c972a59e2bbae2cf7dc38e6c67242c9bbee2304dbe6254b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/273c2804dd08df65b8867d3242310023523d2aa73740ff467a4d26a0fd007f0e391fe89ec24c7b5506cfd8e513f6c82876c3a44f6ff24274e9cac1c7dd1c9177", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cbddd5113169125a8199f7f81d66bd5a61c1cfd7d9c584942bb5717b5e9d176934ad2ecd6a0b91609713459a9908ae23eace855e8ea941ed0cd68aec5634c5c5"} 15:58:11 INFO - Running post-run listener: copy_logs_to_upload_dir 15:58:11 INFO - Copying logs to upload dir... 15:58:11 INFO - mkdir: /builds/slave/test/build/upload/logs 15:58:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.224158 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-07 15:58:10.678143) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 15:58:10.681900) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1465339049.407221-1269813280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c89b81cbccf7891897e2ca3533798353a5da276a0a58609be069680aa2b123205d3276107eb72253c972a59e2bbae2cf7dc38e6c67242c9bbee2304dbe6254b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/273c2804dd08df65b8867d3242310023523d2aa73740ff467a4d26a0fd007f0e391fe89ec24c7b5506cfd8e513f6c82876c3a44f6ff24274e9cac1c7dd1c9177", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cbddd5113169125a8199f7f81d66bd5a61c1cfd7d9c584942bb5717b5e9d176934ad2ecd6a0b91609713459a9908ae23eace855e8ea941ed0cd68aec5634c5c5"} build_url:https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011492 build_url: 'https://queue.taskcluster.net/v1/task/BgsvknK2TWCDluns0M4S2w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c89b81cbccf7891897e2ca3533798353a5da276a0a58609be069680aa2b123205d3276107eb72253c972a59e2bbae2cf7dc38e6c67242c9bbee2304dbe6254b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/273c2804dd08df65b8867d3242310023523d2aa73740ff467a4d26a0fd007f0e391fe89ec24c7b5506cfd8e513f6c82876c3a44f6ff24274e9cac1c7dd1c9177", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/cbddd5113169125a8199f7f81d66bd5a61c1cfd7d9c584942bb5717b5e9d176934ad2ecd6a0b91609713459a9908ae23eace855e8ea941ed0cd68aec5634c5c5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 15:58:10.707549) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:58:10.707887) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1465339049.407221-1269813280 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 15:58:10.791483) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 15:58:10.791819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 15:58:10.792114) ========= ========= Total master_lag: 0.17 =========