builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-033 starttime: 1469877439.57 results: success (0) buildid: 20160729220924 builduid: 55f0f713cef1469489ac756a477dd212 revision: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.566358) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.566955) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.567640) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.584754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.585309) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 04:17:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 102M=0s 2016-07-30 04:17:19 (102 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.721163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.722003) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017829 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.776629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:19.777459) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 --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=edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 04:17:20,051 truncating revision to first 12 chars 2016-07-30 04:17:20,051 Setting DEBUG logging. 2016-07-30 04:17:20,051 attempt 1/10 2016-07-30 04:17:20,051 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cf46248f894f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 04:17:20,486 unpacking tar archive at: autoland-cf46248f894f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.799939 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:20.595818) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:20.596216) ========= script_repo_revision: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:20.596661) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:20.596999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 04:17:20.607702) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-30 04:17:20.608030) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 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', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:17:20 INFO - MultiFileLogger online at 20160730 04:17:20 in /builds/slave/test 04:17:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 04:17:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:17:20 INFO - {'append_to_log': False, 04:17:20 INFO - 'base_work_dir': '/builds/slave/test', 04:17:20 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 04:17:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:17:20 INFO - 'branch': 'Autoland-Non-PGO', 04:17:20 INFO - 'buildbot_json_path': 'buildprops.json', 04:17:20 INFO - 'config_files': ('talos/linux_config.py',), 04:17:20 INFO - 'default_actions': ('clobber', 04:17:20 INFO - 'read-buildbot-config', 04:17:20 INFO - 'download-and-extract', 04:17:20 INFO - 'populate-webroot', 04:17:20 INFO - 'create-virtualenv', 04:17:20 INFO - 'install', 04:17:20 INFO - 'run-tests'), 04:17:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:17:20 INFO - 'download_minidump_stackwalk': True, 04:17:20 INFO - 'download_symbols': 'ondemand', 04:17:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:17:20 INFO - 'tooltool.py': '/tools/tooltool.py', 04:17:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:17:20 INFO - '/tools/misc-python/virtualenv.py')}, 04:17:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:17:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:17:20 INFO - 'installer_path': 'installer.exe', 04:17:20 INFO - 'log_level': 'info', 04:17:20 INFO - 'log_name': 'talos', 04:17:20 INFO - 'log_to_console': True, 04:17:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:17:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:17:20 INFO - 'opt_config_files': (), 04:17:20 INFO - 'pip_index': False, 04:17:20 INFO - 'sps_profile': False, 04:17:20 INFO - 'sps_profile_interval': 0, 04:17:20 INFO - 'suite': 'dromaeojs', 04:17:20 INFO - 'system_bits': '32', 04:17:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:17:20 INFO - 'title': 'talos-linux64-ix-033', 04:17:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:17:20 INFO - 'use_talos_json': True, 04:17:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:17:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:17:20 INFO - 'work_dir': 'build'} 04:17:20 INFO - [mozharness: 2016-07-30 11:17:20.969375Z] Running clobber step. 04:17:20 INFO - Running pre-action listener: _resource_record_pre_action 04:17:20 INFO - Running main action method: clobber 04:17:20 INFO - rmtree: /builds/slave/test/build 04:17:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:17:21 INFO - Running post-action listener: _resource_record_post_action 04:17:21 INFO - [mozharness: 2016-07-30 11:17:21.451812Z] Finished clobber step (success) 04:17:21 INFO - [mozharness: 2016-07-30 11:17:21.451914Z] Running read-buildbot-config step. 04:17:21 INFO - Running pre-action listener: _resource_record_pre_action 04:17:21 INFO - Running main action method: read_buildbot_config 04:17:21 INFO - Using buildbot properties: 04:17:21 INFO - { 04:17:21 INFO - "project": "", 04:17:21 INFO - "product": "firefox", 04:17:21 INFO - "script_repo_revision": "production", 04:17:21 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 04:17:21 INFO - "repository": "", 04:17:21 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 04:17:21 INFO - "buildid": "20160729220924", 04:17:21 INFO - "pgo_build": "False", 04:17:21 INFO - "basedir": "/builds/slave/test", 04:17:21 INFO - "buildnumber": 272, 04:17:21 INFO - "slavename": "talos-linux64-ix-033", 04:17:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:17:21 INFO - "platform": "ubuntu64_hw", 04:17:21 INFO - "scheduler": "tests-autoland-linux64-talos", 04:17:21 INFO - "branch": "autoland", 04:17:21 INFO - "repo_path": "integration/autoland", 04:17:21 INFO - "revision": "cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8", 04:17:21 INFO - "stage_platform": "linux64", 04:17:21 INFO - "builduid": "55f0f713cef1469489ac756a477dd212", 04:17:21 INFO - "slavebuilddir": "test" 04:17:21 INFO - } 04:17:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:17:21 INFO - Running post-action listener: _resource_record_post_action 04:17:21 INFO - [mozharness: 2016-07-30 11:17:21.454358Z] Finished read-buildbot-config step (success) 04:17:21 INFO - [mozharness: 2016-07-30 11:17:21.454447Z] Running download-and-extract step. 04:17:21 INFO - Running pre-action listener: _resource_record_pre_action 04:17:21 INFO - Running main action method: download_and_extract 04:17:21 INFO - mkdir: /builds/slave/test/build/tests 04:17:21 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')]} 04:17:21 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:17:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:22 INFO - Downloaded 1361 bytes. 04:17:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:17:22 INFO - Using the following test package requirements: 04:17:22 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:17:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:17:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:17:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'jsshell-linux-x86_64.zip'], 04:17:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:17:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:17:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:17:22 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:17:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:17:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:17:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:17:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:17:22 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')]} 04:17:22 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:17:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:23 INFO - Downloaded 22760926 bytes. 04:17:23 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'] 04:17:23 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 04:17:24 INFO - Return code: 0 04:17:24 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 04:17: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')]} 04:17:24 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:17:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:17:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:25 INFO - Downloaded 22760926 bytes. 04:17:25 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'] 04:17:25 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 04:17:27 INFO - Return code: 0 04:17:27 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')]} 04:17:27 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:17:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:17:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:17:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:28 INFO - Downloaded 14413725 bytes. 04:17:28 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'] 04:17:28 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 04:17:28 INFO - Return code: 0 04:17:28 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')]} 04:17:28 INFO - https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:17:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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 04:17:29 INFO - Downloaded 57172742 bytes. 04:17:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:29 INFO - mkdir: /builds/slave/test/properties 04:17:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:17:29 INFO - Writing to file /builds/slave/test/properties/build_url 04:17:29 INFO - Contents: 04:17:29 INFO - build_url:https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:17:30 INFO - Running post-action listener: _resource_record_post_action 04:17:30 INFO - Running post-action listener: set_extra_try_arguments 04:17:30 INFO - [mozharness: 2016-07-30 11:17:30.169283Z] Finished download-and-extract step (success) 04:17:30 INFO - [mozharness: 2016-07-30 11:17:30.169517Z] Running populate-webroot step. 04:17:30 INFO - Running pre-action listener: _resource_record_pre_action 04:17:30 INFO - Running main action method: populate_webroot 04:17:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:17:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:17:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:17:30 INFO - '--tppagecycles', 04:17:30 INFO - '7'], 04:17:30 INFO - 'tests': ['tsvgm']}}, 04:17:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:17:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:17:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:17:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:17:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:17:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['damp', 'tps']}, 04:17:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'tests': ['damp', 'tps']}, 04:17:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['dromaeo_dom']}, 04:17:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:17:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['basic_compositor_video']}, 04:17:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:17:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['a11yr', 04:17:30 INFO - 'ts_paint', 04:17:30 INFO - 'tpaint', 04:17:30 INFO - 'sessionrestore', 04:17:30 INFO - 'sessionrestore_no_auto_restore', 04:17:30 INFO - 'tabpaint']}, 04:17:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:17:30 INFO - 'ts_paint', 04:17:30 INFO - 'tpaint', 04:17:30 INFO - 'sessionrestore', 04:17:30 INFO - 'sessionrestore_no_auto_restore', 04:17:30 INFO - 'tabpaint']}, 04:17:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:17:30 INFO - 'ts_paint', 04:17:30 INFO - 'tpaint', 04:17:30 INFO - 'sessionrestore', 04:17:30 INFO - 'sessionrestore_no_auto_restore', 04:17:30 INFO - 'tabpaint']}, 04:17:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:17:30 INFO - 'ts_paint', 04:17:30 INFO - 'tpaint', 04:17:30 INFO - 'sessionrestore', 04:17:30 INFO - 'sessionrestore_no_auto_restore', 04:17:30 INFO - 'tabpaint']}, 04:17:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['a11yr', 04:17:30 INFO - 'ts_paint', 04:17:30 INFO - 'tpaint', 04:17:30 INFO - 'sessionrestore', 04:17:30 INFO - 'sessionrestore_no_auto_restore', 04:17:30 INFO - 'tabpaint']}, 04:17:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['a11yr', 04:17:30 INFO - 'ts_paint', 04:17:30 INFO - 'tpaint', 04:17:30 INFO - 'sessionrestore', 04:17:30 INFO - 'sessionrestore_no_auto_restore', 04:17:30 INFO - 'tabpaint']}, 04:17:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['tsvgx', 04:17:30 INFO - 'tsvgr_opacity', 04:17:30 INFO - 'tart', 04:17:30 INFO - 'tscrollx', 04:17:30 INFO - 'cart']}, 04:17:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:17:30 INFO - 'tsvgr_opacity', 04:17:30 INFO - 'tart', 04:17:30 INFO - 'tscrollx', 04:17:30 INFO - 'cart']}, 04:17:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'talos_options': ['--disable-e10s'], 04:17:30 INFO - 'tests': ['tp5o']}, 04:17:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'tests': ['tp5o']}, 04:17:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'talos_options': ['--disable-e10s', 04:17:30 INFO - '--xperf_path', 04:17:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:30 INFO - 'tests': ['tp5n']}, 04:17:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:17:30 INFO - 'talos_options': ['--xperf_path', 04:17:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:17:30 INFO - 'tests': ['tp5n']}}, 04:17:30 INFO - 'talos.zip': {'path': '', 04:17:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:17:30 INFO - Running post-action listener: _resource_record_post_action 04:17:30 INFO - [mozharness: 2016-07-30 11:17:30.190015Z] Finished populate-webroot step (success) 04:17:30 INFO - [mozharness: 2016-07-30 11:17:30.190104Z] Running create-virtualenv step. 04:17:30 INFO - Running pre-action listener: _resource_record_pre_action 04:17:30 INFO - Running main action method: create_virtualenv 04:17:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:17:30 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 04:17:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:17:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:17:30 INFO - Using real prefix '/usr' 04:17:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:17:30 INFO - Installing distribute.............................................................................................................................................................................................done. 04:17:32 INFO - Installing pip.................done. 04:17:32 INFO - Return code: 0 04:17:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:32 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:32 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 04:17:32 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 04:17:32 INFO - Using env: {'DISPLAY': ':0', 04:17:32 INFO - 'HOME': '/home/cltbld', 04:17:32 INFO - 'LANG': 'en_US.UTF-8', 04:17:32 INFO - 'LANGUAGE': 'en_US:en', 04:17:32 INFO - 'LOGNAME': 'cltbld', 04:17:32 INFO - 'MAIL': '/var/mail/cltbld', 04:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:32 INFO - 'MOZ_NO_REMOTE': '1', 04:17:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:32 INFO - 'NO_EM_RESTART': '1', 04:17:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:32 INFO - 'PWD': '/builds/slave/test', 04:17:32 INFO - 'SHELL': '/bin/bash', 04:17:32 INFO - 'SHLVL': '1', 04:17:32 INFO - 'TERM': 'linux', 04:17:32 INFO - 'TMOUT': '86400', 04:17:32 INFO - 'USER': 'cltbld', 04:17:32 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:32 INFO - '_': '/tools/buildbot/bin/python'} 04:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:33 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 04:17:33 INFO - Cleaning up... 04:17:33 INFO - Return code: 0 04:17:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:33 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:33 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 04:17:33 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 04:17:33 INFO - Using env: {'DISPLAY': ':0', 04:17:33 INFO - 'HOME': '/home/cltbld', 04:17:33 INFO - 'LANG': 'en_US.UTF-8', 04:17:33 INFO - 'LANGUAGE': 'en_US:en', 04:17:33 INFO - 'LOGNAME': 'cltbld', 04:17:33 INFO - 'MAIL': '/var/mail/cltbld', 04:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:33 INFO - 'MOZ_NO_REMOTE': '1', 04:17:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:33 INFO - 'NO_EM_RESTART': '1', 04:17:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:33 INFO - 'PWD': '/builds/slave/test', 04:17:33 INFO - 'SHELL': '/bin/bash', 04:17:33 INFO - 'SHLVL': '1', 04:17:33 INFO - 'TERM': 'linux', 04:17:33 INFO - 'TMOUT': '86400', 04:17:33 INFO - 'USER': 'cltbld', 04:17:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:33 INFO - '_': '/tools/buildbot/bin/python'} 04:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:33 INFO - Downloading/unpacking psutil>=3.1.1 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 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 04:17:33 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 04:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:17:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:35 INFO - Installing collected packages: psutil 04:17:35 INFO - Running setup.py install for psutil 04:17:35 INFO - building 'psutil._psutil_linux' extension 04:17:35 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 04:17:35 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 04:17:35 INFO - building 'psutil._psutil_posix' extension 04:17:35 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 04:17:35 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 04:17:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:17:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:17:35 INFO - Successfully installed psutil 04:17:35 INFO - Cleaning up... 04:17:35 INFO - Return code: 0 04:17:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:17:35 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')]} 04:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:35 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')]} 04:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:35 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:35 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 04:17:35 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 04:17:35 INFO - Using env: {'DISPLAY': ':0', 04:17:35 INFO - 'HOME': '/home/cltbld', 04:17:35 INFO - 'LANG': 'en_US.UTF-8', 04:17:35 INFO - 'LANGUAGE': 'en_US:en', 04:17:35 INFO - 'LOGNAME': 'cltbld', 04:17:35 INFO - 'MAIL': '/var/mail/cltbld', 04:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:35 INFO - 'MOZ_NO_REMOTE': '1', 04:17:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:35 INFO - 'NO_EM_RESTART': '1', 04:17:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:35 INFO - 'PWD': '/builds/slave/test', 04:17:35 INFO - 'SHELL': '/bin/bash', 04:17:35 INFO - 'SHLVL': '1', 04:17:35 INFO - 'TERM': 'linux', 04:17:35 INFO - 'TMOUT': '86400', 04:17:35 INFO - 'USER': 'cltbld', 04:17:35 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:35 INFO - '_': '/tools/buildbot/bin/python'} 04:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:36 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 04:17:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:17:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:17:38 INFO - Installing collected packages: mozsystemmonitor 04:17:38 INFO - Running setup.py install for mozsystemmonitor 04:17:38 INFO - Successfully installed mozsystemmonitor 04:17:38 INFO - Cleaning up... 04:17:38 INFO - Return code: 0 04:17:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:38 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:38 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 04:17:38 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 04:17:38 INFO - Using env: {'DISPLAY': ':0', 04:17:38 INFO - 'HOME': '/home/cltbld', 04:17:38 INFO - 'LANG': 'en_US.UTF-8', 04:17:38 INFO - 'LANGUAGE': 'en_US:en', 04:17:38 INFO - 'LOGNAME': 'cltbld', 04:17:38 INFO - 'MAIL': '/var/mail/cltbld', 04:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:38 INFO - 'MOZ_NO_REMOTE': '1', 04:17:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:38 INFO - 'NO_EM_RESTART': '1', 04:17:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:38 INFO - 'PWD': '/builds/slave/test', 04:17:38 INFO - 'SHELL': '/bin/bash', 04:17:38 INFO - 'SHLVL': '1', 04:17:38 INFO - 'TERM': 'linux', 04:17:38 INFO - 'TMOUT': '86400', 04:17:38 INFO - 'USER': 'cltbld', 04:17:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:38 INFO - '_': '/tools/buildbot/bin/python'} 04:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:38 INFO - Downloading/unpacking jsonschema==2.5.1 04:17:38 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 04:17:38 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 04:17:38 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 04:17:38 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 04:17:38 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 04:17:38 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 04:17:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:17:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:17:40 INFO - warning: no files found matching '*.txt' 04:17:40 INFO - no previously-included directories found matching 'build' 04:17:40 INFO - no previously-included directories found matching 'dist' 04:17:40 INFO - no previously-included directories found matching '.git*' 04:17:40 INFO - Installing collected packages: jsonschema, functools32 04:17:40 INFO - Running setup.py install for functools32 04:17:40 INFO - warning: no files found matching '*.txt' 04:17:40 INFO - no previously-included directories found matching 'build' 04:17:40 INFO - no previously-included directories found matching 'dist' 04:17:40 INFO - no previously-included directories found matching '.git*' 04:17:40 INFO - Successfully installed jsonschema functools32 04:17:40 INFO - Cleaning up... 04:17:40 INFO - Return code: 0 04:17:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:40 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:40 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:17:40 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 functools32==3.2.3-2 04:17:40 INFO - Using env: {'DISPLAY': ':0', 04:17:40 INFO - 'HOME': '/home/cltbld', 04:17:40 INFO - 'LANG': 'en_US.UTF-8', 04:17:40 INFO - 'LANGUAGE': 'en_US:en', 04:17:40 INFO - 'LOGNAME': 'cltbld', 04:17:40 INFO - 'MAIL': '/var/mail/cltbld', 04:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:40 INFO - 'MOZ_NO_REMOTE': '1', 04:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:40 INFO - 'NO_EM_RESTART': '1', 04:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:40 INFO - 'PWD': '/builds/slave/test', 04:17:40 INFO - 'SHELL': '/bin/bash', 04:17:40 INFO - 'SHLVL': '1', 04:17:40 INFO - 'TERM': 'linux', 04:17:40 INFO - 'TMOUT': '86400', 04:17:40 INFO - 'USER': 'cltbld', 04:17:40 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:40 INFO - '_': '/tools/buildbot/bin/python'} 04:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:17:40 INFO - Cleaning up... 04:17:40 INFO - Return code: 0 04:17:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:40 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:40 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 04:17:40 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 04:17:40 INFO - Using env: {'DISPLAY': ':0', 04:17:40 INFO - 'HOME': '/home/cltbld', 04:17:40 INFO - 'LANG': 'en_US.UTF-8', 04:17:40 INFO - 'LANGUAGE': 'en_US:en', 04:17:40 INFO - 'LOGNAME': 'cltbld', 04:17:40 INFO - 'MAIL': '/var/mail/cltbld', 04:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:40 INFO - 'MOZ_NO_REMOTE': '1', 04:17:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:40 INFO - 'NO_EM_RESTART': '1', 04:17:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:40 INFO - 'PWD': '/builds/slave/test', 04:17:40 INFO - 'SHELL': '/bin/bash', 04:17:40 INFO - 'SHLVL': '1', 04:17:40 INFO - 'TERM': 'linux', 04:17:40 INFO - 'TMOUT': '86400', 04:17:40 INFO - 'USER': 'cltbld', 04:17:40 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:40 INFO - '_': '/tools/buildbot/bin/python'} 04:17:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:41 INFO - Downloading/unpacking blobuploader==1.2.4 04:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:42 INFO - Downloading blobuploader-1.2.4.tar.gz 04:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:17:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:17:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:17:43 INFO - Downloading docopt-0.6.1.tar.gz 04:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:17:43 INFO - Installing collected packages: blobuploader, requests, docopt 04:17:43 INFO - Running setup.py install for blobuploader 04:17:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:17:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:17:43 INFO - Running setup.py install for requests 04:17:44 INFO - Running setup.py install for docopt 04:17:44 INFO - Successfully installed blobuploader requests docopt 04:17:44 INFO - Cleaning up... 04:17:44 INFO - Return code: 0 04:17:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:44 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:44 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 04:17:44 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 04:17:44 INFO - Using env: {'DISPLAY': ':0', 04:17:44 INFO - 'HOME': '/home/cltbld', 04:17:44 INFO - 'LANG': 'en_US.UTF-8', 04:17:44 INFO - 'LANGUAGE': 'en_US:en', 04:17:44 INFO - 'LOGNAME': 'cltbld', 04:17:44 INFO - 'MAIL': '/var/mail/cltbld', 04:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:44 INFO - 'MOZ_NO_REMOTE': '1', 04:17:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:44 INFO - 'NO_EM_RESTART': '1', 04:17:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:44 INFO - 'PWD': '/builds/slave/test', 04:17:44 INFO - 'SHELL': '/bin/bash', 04:17:44 INFO - 'SHLVL': '1', 04:17:44 INFO - 'TERM': 'linux', 04:17:44 INFO - 'TMOUT': '86400', 04:17:44 INFO - 'USER': 'cltbld', 04:17:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:44 INFO - '_': '/tools/buildbot/bin/python'} 04:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:44 INFO - Running setup.py (path:/tmp/pip-0_ixqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:44 INFO - Running setup.py (path:/tmp/pip-YiCMhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:44 INFO - Running setup.py (path:/tmp/pip-0AXeAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:44 INFO - Running setup.py (path:/tmp/pip-zfrpuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:44 INFO - Running setup.py (path:/tmp/pip-fQT9n0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:44 INFO - Running setup.py (path:/tmp/pip-F1OLuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:45 INFO - Running setup.py (path:/tmp/pip-5c6qbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:45 INFO - Running setup.py (path:/tmp/pip-mErky8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:45 INFO - Running setup.py (path:/tmp/pip-j6bfHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:45 INFO - Running setup.py (path:/tmp/pip-4xLATS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:45 INFO - Running setup.py (path:/tmp/pip-efhpwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:45 INFO - Running setup.py (path:/tmp/pip-2x3t9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:45 INFO - Running setup.py (path:/tmp/pip-C06dlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:45 INFO - Running setup.py (path:/tmp/pip-daveoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:45 INFO - Running setup.py (path:/tmp/pip-gnY3jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:45 INFO - Running setup.py (path:/tmp/pip-bWpU6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:45 INFO - Running setup.py (path:/tmp/pip-BoGHKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:17:45 INFO - Running setup.py install for manifestparser 04:17:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:17:45 INFO - Running setup.py install for mozcrash 04:17:46 INFO - Running setup.py install for mozdebug 04:17:46 INFO - Running setup.py install for mozdevice 04:17:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozfile 04:17:46 INFO - Running setup.py install for mozhttpd 04:17:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozinfo 04:17:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozInstall 04:17:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozleak 04:17:46 INFO - Running setup.py install for mozlog 04:17:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for moznetwork 04:17:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:17:46 INFO - Running setup.py install for mozprocess 04:17:47 INFO - Running setup.py install for mozprofile 04:17:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:17:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:17:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:17:47 INFO - Running setup.py install for mozrunner 04:17:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:17:47 INFO - Running setup.py install for mozscreenshot 04:17:47 INFO - Running setup.py install for moztest 04:17:47 INFO - Running setup.py install for mozversion 04:17:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:17:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:17:47 INFO - Cleaning up... 04:17:47 INFO - Return code: 0 04:17:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17: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')]} 04:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17: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')]} 04:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:47 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:47 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 04:17:47 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 04:17:47 INFO - Using env: {'DISPLAY': ':0', 04:17:47 INFO - 'HOME': '/home/cltbld', 04:17:47 INFO - 'LANG': 'en_US.UTF-8', 04:17:47 INFO - 'LANGUAGE': 'en_US:en', 04:17:47 INFO - 'LOGNAME': 'cltbld', 04:17:47 INFO - 'MAIL': '/var/mail/cltbld', 04:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:47 INFO - 'MOZ_NO_REMOTE': '1', 04:17:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:47 INFO - 'NO_EM_RESTART': '1', 04:17:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:47 INFO - 'PWD': '/builds/slave/test', 04:17:47 INFO - 'SHELL': '/bin/bash', 04:17:47 INFO - 'SHLVL': '1', 04:17:47 INFO - 'TERM': 'linux', 04:17:47 INFO - 'TMOUT': '86400', 04:17:47 INFO - 'USER': 'cltbld', 04:17:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:47 INFO - '_': '/tools/buildbot/bin/python'} 04:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:17:47 INFO - Running setup.py (path:/tmp/pip-mQo37L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:17:47 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)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:17:47 INFO - Running setup.py (path:/tmp/pip-r9kvL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:17:47 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)) 04:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:17:47 INFO - Running setup.py (path:/tmp/pip-qAyVT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:17:48 INFO - Running setup.py (path:/tmp/pip-ynd0wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:17:48 INFO - Running setup.py (path:/tmp/pip-G_qrbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:17:48 INFO - Running setup.py (path:/tmp/pip-J6abqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:17:48 INFO - Running setup.py (path:/tmp/pip-ko0CwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:17:48 INFO - Running setup.py (path:/tmp/pip-eCl2S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:17:48 INFO - Running setup.py (path:/tmp/pip-X166_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:17:48 INFO - Running setup.py (path:/tmp/pip-usyk1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:17:48 INFO - Running setup.py (path:/tmp/pip-yaz3P2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:17:48 INFO - Running setup.py (path:/tmp/pip-aOe5qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:17:48 INFO - Running setup.py (path:/tmp/pip-0Zi5GL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:17:48 INFO - Running setup.py (path:/tmp/pip-Ju1Vb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:48 INFO - Running setup.py (path:/tmp/pip-ka_Jrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:17:48 INFO - Running setup.py (path:/tmp/pip-q7IC0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:17:48 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)) 04:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:17:48 INFO - Running setup.py (path:/tmp/pip-jm9vPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:17:49 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)) 04:17:49 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)) 04:17:49 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)) 04:17:49 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)) 04:17:49 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)) 04:17:49 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)) 04:17:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:17:49 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 04:17:49 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 04:17:49 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 04:17:49 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 04:17:49 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 04:17:49 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 04:17:50 INFO - Downloading blessings-1.6.tar.gz 04:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:17:50 INFO - Installing collected packages: blessings 04:17:50 INFO - Running setup.py install for blessings 04:17:51 INFO - Successfully installed blessings 04:17:51 INFO - Cleaning up... 04:17:51 INFO - Return code: 0 04:17:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:17:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:51 INFO - Reading from file tmpfile_stdout 04:17:51 INFO - Current package versions: 04:17:51 INFO - argparse == 1.2.1 04:17:51 INFO - blessings == 1.6 04:17:51 INFO - blobuploader == 1.2.4 04:17:51 INFO - docopt == 0.6.1 04:17:51 INFO - functools32 == 3.2.3-2 04:17:51 INFO - jsonschema == 2.5.1 04:17:51 INFO - manifestparser == 1.1 04:17:51 INFO - mozInstall == 1.12 04:17:51 INFO - mozcrash == 0.17 04:17:51 INFO - mozdebug == 0.1 04:17:51 INFO - mozdevice == 0.48 04:17:51 INFO - mozfile == 1.2 04:17:51 INFO - mozhttpd == 0.7 04:17:51 INFO - mozinfo == 0.9 04:17:51 INFO - mozleak == 0.1 04:17:51 INFO - mozlog == 3.3 04:17:51 INFO - moznetwork == 0.27 04:17:51 INFO - mozprocess == 0.23 04:17:51 INFO - mozprofile == 0.28 04:17:51 INFO - mozrunner == 6.12 04:17:51 INFO - mozscreenshot == 0.1 04:17:51 INFO - mozsystemmonitor == 0.3 04:17:51 INFO - moztest == 0.7 04:17:51 INFO - mozversion == 1.4 04:17:51 INFO - psutil == 3.1.1 04:17:51 INFO - requests == 1.2.3 04:17:51 INFO - wsgiref == 0.1.2 04:17:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:17:51 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')]} 04:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:51 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')]} 04:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:51 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:51 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 04:17:51 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 04:17:51 INFO - Using env: {'DISPLAY': ':0', 04:17:51 INFO - 'HOME': '/home/cltbld', 04:17:51 INFO - 'LANG': 'en_US.UTF-8', 04:17:51 INFO - 'LANGUAGE': 'en_US:en', 04:17:51 INFO - 'LOGNAME': 'cltbld', 04:17:51 INFO - 'MAIL': '/var/mail/cltbld', 04:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:51 INFO - 'MOZ_NO_REMOTE': '1', 04:17:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:51 INFO - 'NO_EM_RESTART': '1', 04:17:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:51 INFO - 'PWD': '/builds/slave/test', 04:17:51 INFO - 'SHELL': '/bin/bash', 04:17:51 INFO - 'SHLVL': '1', 04:17:51 INFO - 'TERM': 'linux', 04:17:51 INFO - 'TMOUT': '86400', 04:17:51 INFO - 'USER': 'cltbld', 04:17:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:51 INFO - '_': '/tools/buildbot/bin/python'} 04:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 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)) 04:17:51 INFO - Cleaning up... 04:17:51 INFO - Return code: 0 04:17:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:17:51 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')]} 04:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:51 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')]} 04:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:17:51 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 0x177c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18df8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a3d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', '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 04:17:51 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 04:17:51 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 04:17:51 INFO - Using env: {'DISPLAY': ':0', 04:17:51 INFO - 'HOME': '/home/cltbld', 04:17:51 INFO - 'LANG': 'en_US.UTF-8', 04:17:51 INFO - 'LANGUAGE': 'en_US:en', 04:17:51 INFO - 'LOGNAME': 'cltbld', 04:17:51 INFO - 'MAIL': '/var/mail/cltbld', 04:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:17:51 INFO - 'MOZ_NO_REMOTE': '1', 04:17:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:17:51 INFO - 'NO_EM_RESTART': '1', 04:17:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:17:51 INFO - 'PWD': '/builds/slave/test', 04:17:51 INFO - 'SHELL': '/bin/bash', 04:17:51 INFO - 'SHLVL': '1', 04:17:51 INFO - 'TERM': 'linux', 04:17:51 INFO - 'TMOUT': '86400', 04:17:51 INFO - 'USER': 'cltbld', 04:17:51 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:17:51 INFO - '_': '/tools/buildbot/bin/python'} 04:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:17:51 INFO - Cleaning up... 04:17:51 INFO - Return code: 0 04:17:51 INFO - Running post-action listener: _resource_record_post_action 04:17:51 INFO - Running post-action listener: _start_resource_monitoring 04:17:51 INFO - Starting resource monitoring. 04:17:51 INFO - [mozharness: 2016-07-30 11:17:51.843340Z] Finished create-virtualenv step (success) 04:17:51 INFO - [mozharness: 2016-07-30 11:17:51.843818Z] Running install step. 04:17:51 INFO - Running pre-action listener: _resource_record_pre_action 04:17:51 INFO - Running main action method: install 04:17:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:17:52 INFO - Reading from file tmpfile_stdout 04:17:52 INFO - Detecting whether we're running mozinstall >=1.0... 04:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:17:52 INFO - Reading from file tmpfile_stdout 04:17:52 INFO - Output received: 04:17:52 INFO - Usage: mozinstall [options] installer 04:17:52 INFO - Options: 04:17:52 INFO - -h, --help show this help message and exit 04:17:52 INFO - -d DEST, --destination=DEST 04:17:52 INFO - Directory to install application into. [default: 04:17:52 INFO - "/builds/slave/test"] 04:17:52 INFO - --app=APP Application being installed. [default: firefox] 04:17:52 INFO - mkdir: /builds/slave/test/build/application 04:17:52 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'] 04:17:52 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 04:18:09 INFO - Reading from file tmpfile_stdout 04:18:09 INFO - Output received: 04:18:09 INFO - /builds/slave/test/build/application/firefox/firefox 04:18:09 INFO - Running post-action listener: _resource_record_post_action 04:18:09 INFO - [mozharness: 2016-07-30 11:18:09.375470Z] Finished install step (success) 04:18:09 INFO - [mozharness: 2016-07-30 11:18:09.375747Z] Running run-tests step. 04:18:09 INFO - Running pre-action listener: _resource_record_pre_action 04:18:09 INFO - Running main action method: run_tests 04:18:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:09 INFO - Python 2.7.3 04:18:09 INFO - Return code: 0 04:18:09 INFO - grabbing minidump binary from tooltool 04:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:18:09 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 0x1a3b3b0>, '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 0x1a3c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a3c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:09 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 04:18:09 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 04:18:09 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 04:18:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:09 INFO - Return code: 0 04:18:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:18:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:18:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 04:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 04:18:09 INFO - Using env: {'DISPLAY': ':0', 04:18:09 INFO - 'HOME': '/home/cltbld', 04:18:09 INFO - 'LANG': 'en_US.UTF-8', 04:18:09 INFO - 'LANGUAGE': 'en_US:en', 04:18:09 INFO - 'LOGNAME': 'cltbld', 04:18:09 INFO - 'MAIL': '/var/mail/cltbld', 04:18:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:09 INFO - 'MOZ_NO_REMOTE': '1', 04:18:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:09 INFO - 'NO_EM_RESTART': '1', 04:18:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:09 INFO - 'PWD': '/builds/slave/test', 04:18:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:09 INFO - 'SHELL': '/bin/bash', 04:18:09 INFO - 'SHLVL': '1', 04:18:09 INFO - 'TERM': 'linux', 04:18:09 INFO - 'TMOUT': '86400', 04:18:09 INFO - 'USER': 'cltbld', 04:18:09 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071', 04:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:09 INFO - '_': '/tools/buildbot/bin/python'} 04:18:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 04:18:09 INFO - mozversion application_buildid: 20160729220924 04:18:09 INFO - mozversion application_changeset: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 04:18:09 INFO - mozversion application_display_name: Nightly 04:18:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:09 INFO - mozversion application_name: Firefox 04:18:09 INFO - mozversion application_remotingname: firefox 04:18:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:18:09 INFO - mozversion application_vendor: Mozilla 04:18:09 INFO - mozversion application_version: 50.0a1 04:18:09 INFO - mozversion platform_buildid: 20160729220924 04:18:09 INFO - mozversion platform_changeset: cf46248f894fb1e6785f8e8e8d9c3d67bd6612c8 04:18:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:18:09 INFO - mozversion platform_version: 50.0a1 04:18:09 INFO - using testdate: 1469877489 04:18:09 INFO - actual date: 1469877489 04:18:09 INFO - starting webserver on 'localhost:43259' 04:18:09 INFO - SUITE-START | Running 2 tests 04:18:09 INFO - TEST-START | dromaeo_css 04:18:09 INFO - operating with platform_type : linux_ 04:18:09 INFO - Initialising browser for dromaeo_css test... 04:18:09 INFO - TEST-INFO | started process 9757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvlQvFw/profile http://localhost:43259/getInfo.html) 04:18:13 INFO - PROCESS | 9757 | JavaScript error: , line 0: uncaught exception: undefined 04:18:14 INFO - PROCESS | 9757 | __metrics Screen width/height:1600/1200 04:18:14 INFO - PROCESS | 9757 | colorDepth:24 04:18:14 INFO - PROCESS | 9757 | Browser inner width/height: 1024/697 04:18:14 INFO - PROCESS | 9757 | __metrics 04:18:15 INFO - PROCESS | 9757 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:18:15 INFO - PROCESS | 9757 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:16 INFO - PROCESS | 9757 | console.error: PushService: 04:18:16 INFO - PROCESS | 9757 | stateChangeProcessEnqueue: Error transitioning state 04:18:16 INFO - PROCESS | 9757 | UnknownError 04:18:16 INFO - TEST-INFO | 9757: exit 0 04:18:16 INFO - Browser initialized. 04:18:16 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:16 INFO - TEST-INFO | started process 9842 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvlQvFw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:28 INFO - PROCESS | 9842 | RSS: Main: 158867456 04:18:28 INFO - PROCESS | 9842 | 04:19:20 INFO - PROCESS | 9842 | 1469877560941 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] 04:21:32 INFO - PROCESS | 9842 | Cycle 1(1): loaded http://localhost:43259/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43259/tests/dromaeo/cssquery-ext.html) 04:21:33 INFO - PROCESS | 9842 | RSS: Main: 250384384 04:21:33 INFO - PROCESS | 9842 | 04:24:36 INFO - PROCESS | 9842 | Cycle 1(1): loaded http://localhost:43259/tests/dromaeo/cssquery-ext.html (next: http://localhost:43259/tests/dromaeo/cssquery-jquery.html) 04:24:37 INFO - PROCESS | 9842 | RSS: Main: 248213504 04:24:37 INFO - PROCESS | 9842 | 04:27:39 INFO - PROCESS | 9842 | Cycle 1(1): loaded http://localhost:43259/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43259/tests/dromaeo/cssquery-mootools.html) 04:27:40 INFO - PROCESS | 9842 | RSS: Main: 189743104 04:27:40 INFO - PROCESS | 9842 | 04:30:42 INFO - PROCESS | 9842 | Cycle 1(1): loaded http://localhost:43259/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43259/tests/dromaeo/cssquery-prototype.html) 04:30:42 INFO - PROCESS | 9842 | RSS: Main: 201080832 04:30:42 INFO - PROCESS | 9842 | 04:33:46 INFO - PROCESS | 9842 | Cycle 1(1): loaded http://localhost:43259/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43259/tests/dromaeo/cssquery-yui.html) 04:33:46 INFO - PROCESS | 9842 | RSS: Main: 192155648 04:33:46 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | Cycle 1(1): loaded http://localhost:43259/tests/dromaeo/cssquery-yui.html (next: http://localhost:43259/tests/dromaeo/cssquery-dojo.html) 04:36:49 INFO - PROCESS | 9842 | RSS: Main: 285257728 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | __start_tp_report 04:36:49 INFO - PROCESS | 9842 | _x_x_mozilla_page_load 04:36:49 INFO - PROCESS | 9842 | _x_x_mozilla_page_load_details 04:36:49 INFO - PROCESS | 9842 | |i|pagename|runs| 04:36:49 INFO - PROCESS | 9842 | |0;dojo.html;2588.818782685212;2628.645132907059;2634.841909485431;2654.8274362166458;2658.574628059511;4607.28587069004;4687.882802929928;4729.290606409036;4730.810767569297;4737.336772851803;4720.433547974248;4762.547557981995;4770.594499467549;4780.737059461728;4822.565969062784;4345.174416860794;4352.738835669854;4416.072624748806;4472.709273897202;4527.298268768346;4445.088756804864;4477.216487114758;4486.618637415823;4498.50516443191;4503.099380123972;4283.485981682187;4328.199283132631;4333.2200203963275;4340.65274778017;4415.249407600683;4334.068175342317;4343.066240758252;4370.628496577795;4439.089986552781;4443.355713421536;4366.8253269869165;4376;4378.233809083429;4407.427034485529;4416.514183439819;4568.360429539867;4752;4806.182948898696;4806.4712881583;4823.758812059418;5055.469175736532;5075.187969924831;5080.872978175538;5100.311457953166;5143.382794064699;5142.7685754140775;5158.432572417031;5171.844844654666;5197.402298735618;5204.635675502716;4666.976665116653;4762.166620841339;4819.638527110481;4834.154023045953;4840.564349208588;5259.921716048201;5286.022085914118;5310.309659744208;5328.866778330495;5331.573474122061;5062.265971434122;5120.974395128001;5121.692698438031;5122.282880396748;5123.103456895028;4948.777305021283;4993.375828021497;5156.200788877655;5221.869453263623;5223.52988231061;4997.450280469146;5009.198528235464;5015.573676237561;5060.924086138711;5072.289879416885;4989.501049894981;4994.375703037121;4996.825111121093;4998.2002879539095;5029.748512574357;4126.546079931205;4166.979090122939;4202.327627579633;4209.936850947238;4254.7659878706345;3719.386301260262;3730.384486559687;3790.8673196437994;3796.1838204786104;3854.672352849983;4385.451818522684;4389.692721509464;4390.275604525282;4402.449693788277;4432.556744325541;4955.40535135786;5155.53600175986;5164.483551644806;5169.327987361619;5198.454162312978;4264.658827293809;4355.281378572563;4358.934615980763;4379.846705365297;4421.314696222091;4632.073585282957;4669.252919532859;4670.859874203779;4671.602913752301;4709.81160753566;4788.4253889533475;4809.254565542346;4821.445533763662;4825.638077144158;4833.419989601271;4983.601311895112;5081.110805609078;5122.79508819658;5128.358955130609;5186;4761.16679581065;4779.617630589614;4785.808567657255;4800;4843.951560484491;5063.291139240583;5070.062038522885;5070.290159377616;5088.618353623567;5138.768755405941;4917.631177661761;5033.9428719967755;5068.366454193226;5084.6440749147205;5122.820701275437;4971.279164521052;4978.452370239198;4979.551840334388;4980.526849949249;5022.648414611088;4939.456659767351;4960.479149689375;4969.45336013031;4974.527419895104;5018.949810501995;4789.473157952552;4819.132556139928;4861.562459378673;4866.6350023747655;4926.310316555613;4992.725400102884;4999.725015124058;5011.874703132305;5019.171836646912;5039.84880453587;4924;4926.975365123151;4936.531029552187;4940.851774446773;4989.12690279207 04:36:49 INFO - PROCESS | 9842 | |1;ext.html;568.9971550142249;576.9701083565835;584.9444302791234;588.1031427073714;588.7027051339073;27479.45041099177;40465.3930191047;41951;42046.36930446046;45116.774416127955;40511.18977620446;40987.38518922219;41143.382849257214;41159.79420102903;41176.58823411765;7317.378022868036;7681.615919204045;7861.371090312789;7869.055713314382;7872.724454644089;7439;7512.248775122499;7517.511361761495;9447.338686291929;9775.755606109833;10108.595656173744;10133.391996480235;10162.136218421443;10168.13570846479;10169.644062457815;10053.145482633985;10081.44552049637;10095.697129086138;10109;10118.038786315288;9912.702618921443;10004.19966402686;10009.749756256078;10021.749456263578;10022.098011178956;36942.445863312074;37172;37220.44169337462;37275.440868387;37297.440538391944;2977.4194032163737;2993.1020693791775;3284.8193349366015;3774.226283611853;3868.535775706905;26848.59727104095;27044.729552704615;28138.85930570334;29733.851330743208;31456.842715786504;3045.4365942300747;3114.408262430131;3129.4679904416303;3130.4521708701113;3194.0896844399235;270948;273510;273674;274173;274473;57165.42834571684;57513.712431437576;57564.8487030257;57573;57638;33360.49959250613;34202.31595368128;34316.14209644779;35851.4622280666;35903;15536.06783592988;15676.68646627061;15806.762898556532;15807.288672009787;15819.367225310858;11902;12049.518019279307;12049.879501204876;12062.155649104478;12063.819042714365;16179;18314.725279120823;18759.155837987324;18941.33705320307;19715.338429311858;6759.324067593299;6899.413549848319;6984.225301457578;7127.287271272935;7138.107736532934;741.4809633256742;814.7515007922641;849.515776007682;874.8031692869054;910.9114608043457;27527.587086193722;28765.137045888656;28768.71231287702;29579.852100739357;29668.258293542833;907.3013779389833;940.2431043010442;958.5351104714208;989.085096285947;1007.5717819926502;14969.625759355886;15209.315580798891;15328.156951367559;15591.532254032398;15601.68796624084;424505;428578;429368;430317;430485;50762.23856642153;51203;51306.48693513017;51348.22977655338;51366.48633513617;1043.3583346242006;1071.7374243310283;1089.8910108989037;1126.7239526316105;1132.9376884271444;27593.034243802173;29537.26156846096;29579.556306654988;30473.085807425814;30624.387512249185;36581.08547286339;37022.62977370192;37053.629463705016;37067;37093.443598345504;142520;143659;143751.5624843738;143867.280663596;144169;25600.487990240465;25836.870815646555;26597.33506662349;26638.600420994087;27783.30541736473;42004.36993445162;42192.36711449269;42364.576354237295;42784.5721542793;43479.78260108806;35945.64054359423;36022.819885900404;36112.81943590265;36584.634153658124;37000.81499592485;36908.446373304956;37001.814990924875;37479.25041499209;38053.809730951165;38419.42370864495;35297;35427;35807.28385432329;35874.820625896704;36001.63998360088;31239.375212495168;33402;33596;33624.327513449105;33908.49137262993;25072;25307.873460632578;25526.872365638053;25588.616170757083;25999.220023399328 04:36:49 INFO - PROCESS | 9842 | |2;jquery.html;355.8665500437336;362.2013460319995;371.92747414254217;374.39722047503517;381.4430930840972;13303.600891973245;16197.676046479064;16342.59143521411;17402.82597174028;17572.912135439306;16943.322267109303;17033.91483042583;17067.231974561142;17294.654106917886;17404.65190696185;5129.461406552315;5209.192575150839;5413.756380962846;5449.64577302476;5534.225208470817;3508.9649103508896;5082.339295891547;5475.479829416219;5505.476979686943;5508.366537848158;5348.545373643245;5403.432639572848;5425.240466334716;5429.945700542983;5430;5239.423663397023;5362.758675859557;5398.78404863805;5403.243545903577;5420.1327787554;5246.3704355477585;5251.238570407269;5259.763310650992;5261.15821468567;5262.92105618416;12044.578439754567;12256.938715306367;12350.320732359716;12365.38173091342;12463;1846.510674671213;1848.3530764232546;1856.2760523395887;1865.8880467172014;2002.2691717523144;8910.554472276424;8963.46219226842;9184.448933064037;9421.199198068172;9464.148226659565;1705.224123024021;1710.5381546982246;1728.8271172882737;1733.8612910967092;1743.5466778637644;48792.756036219595;66091.66954165246;67611.66194169047;70453.64773176152;72937.63531182363;10029;10152.949235253776;10214.387136771817;10338.534765935401;10508.684739457829;14155.575332740034;14171.149731015967;14192.787108193384;14215.50245741394;14217.92891035559;7327.816804579821;7350.471773548727;7430.851382972311;7541.321281084729;7656.851472279202;7077.435054389993;7182.3535881770895;7272.63636818157;7404.37062849653;7573.772786816405;4373.278409062469;4451.3323001550025;4477.686561940633;4567.269236922077;4571.497135315112;1748.519157231746;1843.5851933314898;1877.11775465531;1938.2731475696614;1938.4766113149653;693.5977133262695;745.0909889934271;759.9810004749926;789.8657228271207;834.9206825351582;16354.754678679828;16699.982009714862;17044.744328835077;17441.7383739244;17453.301867925395;760.9201033891472;762.3748526208567;777.6889244302324;785.807477168086;816.3999460396603;8962.507062111516;10287.03833170196;10319.226058045677;10399.584016639401;10493.213009024354;431497;438536;445823;445992;448981;82005;86569.56715216384;88928;89430.55284723535;90337;873.8208667223204;907.5643691028212;915.624593916491;916.2303664921498;919.3977944446265;15367.769483457534;15494.612634684223;15966.201689915226;16215.270312835715;16234.18829058566;37737.433938490394;37925.069740933504;39812.80093599513;40334.79832600818;40353.39469907895;47642.78338231811;49879.25181122214;51169.744151279;52698.736506317226;53752.19371709349;7589.886151707839;7598.506097103671;7728.227177282227;7728.7681369559;7927.167647397171;9864.605415783288;11911.106666999836;12253.877461225273;12546.749065018466;12629.557965471164;9767.169790567597;9833.459159746282;10008.349457285252;10049.346792458467;10482.895171048496;9993.150582200595;10427.530761115617;10454.32046916948;10515.842262365917;10521.894781052091;9758.365706229073;10447.164226861682;10584.312019718695;10728.51721672511;10824.891751082703;6590.637514936724;6987.860242795014;7037.788866334018;7400.5189662671755;7746;8839.734807955772;10039.799204016024;10460.26778125524;10598.735031624272;10906.61826836057 04:36:49 INFO - PROCESS | 9842 | |3;mootools.html;1359.2456186816316;1428.3072709735777;1482.8220613526378;1676.0697812713954;1723.5087999920017;7632.618369081544;8142.715004974827;8163.10205877353;8163.714270000552;8165.34677225822;6886.586804791704;7591.5065520741255;7804.960975195116;7848.293653571178;8025.959870200655;2101.831853451724;2119.9894000529976;2122.660374340106;2123.893805309736;2127.2448280860303;2132.7973842485017;2156.1159924430985;2220.6335954567476;2223.8776867272295;2226.1763147635397;2171.9565608687817;2180.3023032629567;2192.3970908000347;2192.5395666909967;2228.765979572146;2123.1507397041228;2125.3623912826247;2129.169623846701;2129.58473097746;2130.0201907122664;2080.8855512946784;2116.9682454763038;2119.7774233705545;2120.3956872291324;2121.087932189158;8057.315128214109;8059.717909873185;8114.431989760719;8120.59397030152;8122.796930076736;4666.766661666937;4811.302361157613;4820.517948205186;4821.831235906761;4826.420829500447;2884.365439603284;2944.514155164395;3006.969930300691;3016.502277124272;3020.879164833404;864.1315477944688;930.6742640075987;947.8341290274175;949.8812648418948;960.5437417226831;10991.835122473169;11010.724731881686;11036.172287078422;11061.889381106166;11064.889351106467;4086.1214838809797;4317.676174286941;4378.2119218540965;4494.5730155635165;4504.25679762842;9439.300925833415;9450.763730906783;9452.8582071269;9454;9455.14903658674;4764.142454358249;4764.618830493552;4772.5943294819635;4782.067496838084;4790.137775200497;3771.17034252464;4254.3831144483875;4347.217500849814;4358.259095953696;4417.072414792865;3684.6499582539614;3686.189038411545;3807.8476860925807;3820.770753754728;3828.7128465364653;1171.1158075652997;1173.7065733566608;1177.3112729053428;1194.7252132009687;1198.6823491774687;634.2864277687602;776.8679324514846;798.5368486277945;798.9041315042232;799.9000124984377;5288.391834938954;5473.233747275384;5495.972520137374;5564.10974244119;5642.04085305499;381.8987968188432;396.3440505962579;398.32682766125157;400.9255196074548;407.10437038515386;3775.8489660413275;3785.2997195518365;3858.6527212551005;3863.16941857502;3932.7640341579595;9389.29580281468;9754.122129008281;9901.75245618851;9947.05502977216;9972.700818975442;9909.108180263811;9931.50342482873;9937.552810123561;9942.105210531086;9945.502724863873;7368.37368823656;7517.812054698677;7535.698572057166;7540.17058123617;7540.773776786705;5333.333333333333;5454.972725136349;5476.863078423071;5486.725663716718;5728.742206600629;9083.18251357381;9132;9133.223675987616;9183.954080229556;9204.079592040742;9238.445693258425;9296.6281348748;9317.627294908129;9332.206762424998;9381.296402769683;5954.583179177416;6694.196696396463;6845.623490708059;6950.93049069523;6957.895631565631;7405.925940740739;7718.961405192938;7719.189485104208;7721.5753133575945;7727.11138219112;6692.39768420825;6762.594244345355;6911.619860907698;6912.965435172993;6922.826929326807;6904.3095690430555;6942.965285173542;7019.719211231493;7101.644917753988;7104.715811367488;5892.499137573354;5915.142304365929;5955.076963070731;5955.821325360246;5963.821085367446;4763.190257656138;4774.856754297377;4805;4824.252240902666;4888.706677599355;4678.92981605283;4733.9053218936115;4847.5394837490385;4892.975535122301;4909.239067944474 04:36:49 INFO - PROCESS | 9842 | |4;prototype.html;280.5931399470768;290.92435966648674;294.3832670555186;294.49347122948535;298.0744787435014;4706.3411122442885;4867.1969125094365;4878.341423907771;4913.53321434464;4916.803327866885;4666.343448075935;4786.401699787527;4793.57924248255;4800.207965685667;4853.272009198622;3789.2610940866543;3792.8103594820286;3812.9809350953205;3821.426785982097;3842.7502212356108;3588.282343531291;3642.3261696586214;3852.8266228019806;3855.2289542091694;3855.63371479709;3776.339140650389;3788.7537310074895;3793.924121517568;3816.8740221634544;3827.4641550182996;3781.735278530504;3801.9619803801884;3809.7237425462363;3810.371288737355;3820.5930263933683;3325.1687078230443;3329.983350083234;3337.0823023668445;3348.2299071213515;3348.7823291485975;4563;4671.789769460383;4703.882402939919;4720.716756994591;4720.929186062212;4295.91408171838;4297.613214810682;4330.17726631939;4346.978460061883;4356.607905288539;5000.1999680051385;5036.6726162799305;5061.115164356318;5067.290579318898;5071.644984851062;3546.6098729139776;3663.065918190844;3676.963230367689;3679.7792132472136;3705.4441833724886;5255.448177941337;5264.236685680555;5272.947270527322;5286.682799032032;5288.180332048539;3768.773873567595;3780.8676696315497;3795.3927371620407;3801.1827457096306;3809.75236609625;3743.794091325003;3808.7714737115307;3826.732128750961;3860.1121741999495;3866.555346135174;4293.3989241506215;4304.720193187495;4308.784560771949;4348.152110338454;4356.564343565618;5014.749262536859;5019.899602007939;5028;5039.244113383022;5064.139096353629;1257.5158563952943;1273.178799674205;1309.7642424363705;1314.2180402660401;1320.531211419932;889.4974339498162;905.44767691708;919.131420807344;919.6045700348781;925.5187302602609;4423.137488189836;4426.136903303889;4428.933565996513;4442.333649952533;4460.286354183315;5123.308353372322;5136.255242989746;5154.871128221749;5156.278121063054;5176.602317374288;4412.69111162222;4419.270820314677;4423.380726698265;4440.245158323029;4447.710164052389;2313.5372925414986;2418.322869596516;2433.270018994295;2443.7067551893533;2457.557639624849;4301.376300436419;4305.935410968838;4309.168603343566;4328.220920234388;4342.5874541918465;4577.977110114428;4634.629229661619;4638.83764068256;4660.976695116503;4704.764761761967;4299.441072660534;4524.5701658342405;4873.147199240118;4894.119058569349;4897.706137631825;5224.7126408047925;5225.216217567396;5240.947590524046;5242.554382877498;5253.921191182212;5111.769970351269;5128.512611424268;5155.53600175986;5159.303494028408;5181.792728290826;4643.303504474356;4832.34763306949;4912.238603016538;4919.425783749166;4922.458529561817;3657.634236576321;3665.4318580620047;3667.8716244932366;3668.926621467609;3673.5959044505616;5017.147084995487;5205.6616319939085;5232.0438951992;5238.161894097078;5254.842354729364;4706.576408123286;5061.595072394273;5063.974680126723;5070.873228169326;5099.719515426687;5067.290579318972;5067.797288108434;5068.949310506995;5070.8225212117395;5076.6446348755235;5002.649814512949;5007.37407824022;5008.298838162587;5011.173156429294;5032.471590483093;4876.414830220397;5052.317937078447;5063.3417655704525;5074.898502029865;5085.593152547861;4940.505949405031;4954.752262386794;4967.975160124176;4974.477679843564;4981.10340138764 04:36:49 INFO - PROCESS | 9842 | |5;yui.html;644.3524258120589;659.3143131143611;688.4010910507859;710.2684235237704;742.046470285683;12603.936980315097;13601.251931143808;13661.453541858315;13671.384787684554;13689.589312320646;12925.35373231337;13005.934970325136;13018.674533136676;13025.73948521029;13068.73862522749;622.3869488553771;755.3805879179089;759.2407592407592;774.508187301065;781.4217479065479;778.1246098139593;789.8696715042012;790.0716657926941;790.1861083084403;790.1979490816825;839.2195258409677;841.2512863551444;841.3143288220111;841.5455653946862;842.1199846160746;657.8750037492861;800.6037011679198;816.465215283984;834.2491757418342;835.0980940584154;599.4125756758358;599.5443462968132;599.9820005399845;600.0279547133628;600.1507866369083;12459.252444853248;12499.687507812287;12507.437165327583;12515.56195533152;12612.621621351374;12404.56584019555;12678;12979.221246725227;13093.541726039639;13446.462141514328;11342.206045576813;11408.94295528517;11486.540538378455;11652.941735291353;11832.645020649308;3789.77261364319;3898.161895192547;3918.3730603103368;4023.3562629979356;4105.281575724266;62795.6860215696;63338.04992925156;64003.35996640067;64723;64882.351176488104;10355.63755268577;10396.688099357032;10474.895251047392;10479.738006549745;10490.741111066722;19438.805611943983;19552.804471955384;19602.607947840963;19635.509112272022;19732.802671973383;6410.134631824644;6557.967210164014;6585.835354116185;6653.234877989094;6758.290379510179;3601.819909004539;3676.1544844685873;3678.558572971207;3763.2097259575794;3776.4712940188397;2764.986175069152;2851.7861160413054;2861.3561948561824;2861.642294713161;2863.957040644392;474.53732610704975;474.7578734845185;477.11971412743435;489.8089744999453;496.5282981167905;433.3802662193031;539.8731298144933;545.1767830575859;549.4368272520699;573.0973716396692;7173.46199035064;7562.810929726801;7563.205863384375;7668.616569171519;8041.8793718094275;415.39974736506065;440.50003246315663;475.8911735655913;514.282575806755;524.5593701290934;3501.3697534444045;3700.777953322809;3755.361588529902;3797.7911214883447;3836.731428800013;54674;97323.51338243263;97346.51326743462;98097;98926;49303.753481233085;59286.703566482756;76077.61961190158;79036.60481697555;86815;74676.62661686656;75989.62005190049;78772.60613696894;79450;79820.60089699514;6817.079694241214;6821.249662537018;6846.965765171142;6984.476164287597;6991.7902462926195;45462.54537454715;46079;46289;46647.533524664315;47036.7648161757;61705.691471542355;63780.3621963793;64303;64569.354306456335;67043;9627.614895404386;9817.607295708378;9941.403515789076;10088.243381746252;10112.544935478069;10283.43441110746;10514.158867290751;10665.093467055387;10985.176111791488;11041.447927603427;9518.904810951803;9535.523223838642;9598.96635690509;9604.75988100303;9838.950805246215;9187.908120918974;9539.481885955181;9626.95186524063;9668.129868311886;9718.659846905324;9129.178373946377;9315.813683726423;9331.86002209981;9417.717468475958;9477.763055923657;8384.706535271236;8532.957335213285;8537.428328468068;8825.602847871989;9347.205487533363;7624.370934696619;7899.526028438312;8045.195480452142;8074.434789564674;8110.797230069295 04:36:49 INFO - PROCESS | 9842 | __end_tp_report 04:36:49 INFO - PROCESS | 9842 | __start_cc_report 04:36:49 INFO - PROCESS | 9842 | _x_x_mozilla_cycle_collect,1002 04:36:49 INFO - PROCESS | 9842 | __end_cc_report 04:36:49 INFO - PROCESS | 9842 | __startTimestamp1469878609542__endTimestamp 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | ------- Summary: start ------- 04:36:49 INFO - PROCESS | 9842 | Number of tests: 6 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | [#0] dojo.html Cycles:165 Average:4715.63 Median:4825.64 stddev:505.14 (10.5%) stddev-sans-first:478.34 04:36:49 INFO - PROCESS | 9842 | Values: 2588.8 2628.6 2634.8 2654.8 2658.6 4607.3 4687.9 4729.3 4730.8 4737.3 4720.4 4762.5 4770.6 4780.7 4822.6 4345.2 4352.7 4416.1 4472.7 4527.3 4445.1 4477.2 4486.6 4498.5 4503.1 4283.5 4328.2 4333.2 4340.7 4415.2 4334.1 4343.1 4370.6 4439.1 4443.4 4366.8 4376.0 4378.2 4407.4 4416.5 4568.4 4752.0 4806.2 4806.5 4823.8 5055.5 5075.2 5080.9 5100.3 5143.4 5142.8 5158.4 5171.8 5197.4 5204.6 4667.0 4762.2 4819.6 4834.2 4840.6 5259.9 5286.0 5310.3 5328.9 5331.6 5062.3 5121.0 5121.7 5122.3 5123.1 4948.8 4993.4 5156.2 5221.9 5223.5 4997.5 5009.2 5015.6 5060.9 5072.3 4989.5 4994.4 4996.8 4998.2 5029.7 4126.5 4167.0 4202.3 4209.9 4254.8 3719.4 3730.4 3790.9 3796.2 3854.7 4385.5 4389.7 4390.3 4402.4 4432.6 4955.4 5155.5 5164.5 5169.3 5198.5 4264.7 4355.3 4358.9 4379.8 4421.3 4632.1 4669.3 4670.9 4671.6 4709.8 4788.4 4809.3 4821.4 4825.6 4833.4 4983.6 5081.1 5122.8 5128.4 5186.0 4761.2 4779.6 4785.8 4800.0 4844.0 5063.3 5070.1 5070.3 5088.6 5138.8 4917.6 5033.9 5068.4 5084.6 5122.8 4971.3 4978.5 4979.6 4980.5 5022.6 4939.5 4960.5 4969.5 4974.5 5018.9 4789.5 4819.1 4861.6 4866.6 4926.3 4992.7 4999.7 5011.9 5019.2 5039.8 4924.0 4927.0 4936.5 4940.9 4989.1 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | [#1] ext.html Cycles:180 Average:44240.52 Median:34259.23 stddev:80858.17 (236.0%) stddev-sans-first:81018.51 04:36:49 INFO - PROCESS | 9842 | Values: 569.0 577.0 584.9 588.1 588.7 27479.5 40465.4 41951.0 42046.4 45116.8 40511.2 40987.4 41143.4 41159.8 41176.6 7317.4 7681.6 7861.4 7869.1 7872.7 7439.0 7512.2 7517.5 9447.3 9775.8 10108.6 10133.4 10162.1 10168.1 10169.6 10053.1 10081.4 10095.7 10109.0 10118.0 9912.7 10004.2 10009.7 10021.7 10022.1 36942.4 37172.0 37220.4 37275.4 37297.4 2977.4 2993.1 3284.8 3774.2 3868.5 26848.6 27044.7 28138.9 29733.9 31456.8 3045.4 3114.4 3129.5 3130.5 3194.1 270948.0 273510.0 273674.0 274173.0 274473.0 57165.4 57513.7 57564.8 57573.0 57638.0 33360.5 34202.3 34316.1 35851.5 35903.0 15536.1 15676.7 15806.8 15807.3 15819.4 11902.0 12049.5 12049.9 12062.2 12063.8 16179.0 18314.7 18759.2 18941.3 19715.3 6759.3 6899.4 6984.2 7127.3 7138.1 741.5 814.8 849.5 874.8 910.9 27527.6 28765.1 28768.7 29579.9 29668.3 907.3 940.2 958.5 989.1 1007.6 14969.6 15209.3 15328.2 15591.5 15601.7 424505.0 428578.0 429368.0 430317.0 430485.0 50762.2 51203.0 51306.5 51348.2 51366.5 1043.4 1071.7 1089.9 1126.7 1132.9 27593.0 29537.3 29579.6 30473.1 30624.4 36581.1 37022.6 37053.6 37067.0 37093.4 142520.0 143659.0 143751.6 143867.3 144169.0 25600.5 25836.9 26597.3 26638.6 27783.3 42004.4 42192.4 42364.6 42784.6 43479.8 35945.6 36022.8 36112.8 36584.6 37000.8 36908.4 37001.8 37479.3 38053.8 38419.4 35297.0 35427.0 35807.3 35874.8 36001.6 31239.4 33402.0 33596.0 33624.3 33908.5 25072.0 25307.9 25526.9 25588.6 25999.2 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | [#2] jquery.html Cycles:180 Average:25803.92 Median:226729.34 stddev:72928.74 (32.2%) stddev-sans-first:73108.29 04:36:49 INFO - PROCESS | 9842 | Values: 355.9 362.2 371.9 374.4 381.4 13303.6 16197.7 16342.6 17402.8 17572.9 16943.3 17033.9 17067.2 17294.7 17404.7 5129.5 5209.2 5413.8 5449.6 5534.2 3509.0 5082.3 5475.5 5505.5 5508.4 5348.5 5403.4 5425.2 5429.9 5430.0 5239.4 5362.8 5398.8 5403.2 5420.1 5246.4 5251.2 5259.8 5261.2 5262.9 12044.6 12256.9 12350.3 12365.4 12463.0 1846.5 1848.4 1856.3 1865.9 2002.3 8910.6 8963.5 9184.4 9421.2 9464.1 1705.2 1710.5 1728.8 1733.9 1743.5 48792.8 66091.7 67611.7 70453.6 72937.6 10029.0 10152.9 10214.4 10338.5 10508.7 14155.6 14171.1 14192.8 14215.5 14217.9 7327.8 7350.5 7430.9 7541.3 7656.9 7077.4 7182.4 7272.6 7404.4 7573.8 4373.3 4451.3 4477.7 4567.3 4571.5 1748.5 1843.6 1877.1 1938.3 1938.5 693.6 745.1 760.0 789.9 834.9 16354.8 16700.0 17044.7 17441.7 17453.3 760.9 762.4 777.7 785.8 816.4 8962.5 10287.0 10319.2 10399.6 10493.2 431497.0 438536.0 445823.0 445992.0 448981.0 82005.0 86569.6 88928.0 89430.6 90337.0 873.8 907.6 915.6 916.2 919.4 15367.8 15494.6 15966.2 16215.3 16234.2 37737.4 37925.1 39812.8 40334.8 40353.4 47642.8 49879.3 51169.7 52698.7 53752.2 7589.9 7598.5 7728.2 7728.8 7927.2 9864.6 11911.1 12253.9 12546.7 12629.6 9767.2 9833.5 10008.3 10049.3 10482.9 9993.2 10427.5 10454.3 10515.8 10521.9 9758.4 10447.2 10584.3 10728.5 10824.9 6590.6 6987.9 7037.8 7400.5 7746.0 8839.7 10039.8 10460.3 10598.7 10906.6 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | [#3] mootools.html Cycles:180 Average:5249.37 Median:5310.86 stddev:2987.61 (56.3%) stddev-sans-first:2981.69 04:36:49 INFO - PROCESS | 9842 | Values: 1359.2 1428.3 1482.8 1676.1 1723.5 7632.6 8142.7 8163.1 8163.7 8165.3 6886.6 7591.5 7805.0 7848.3 8026.0 2101.8 2120.0 2122.7 2123.9 2127.2 2132.8 2156.1 2220.6 2223.9 2226.2 2172.0 2180.3 2192.4 2192.5 2228.8 2123.2 2125.4 2129.2 2129.6 2130.0 2080.9 2117.0 2119.8 2120.4 2121.1 8057.3 8059.7 8114.4 8120.6 8122.8 4666.8 4811.3 4820.5 4821.8 4826.4 2884.4 2944.5 3007.0 3016.5 3020.9 864.1 930.7 947.8 949.9 960.5 10991.8 11010.7 11036.2 11061.9 11064.9 4086.1 4317.7 4378.2 4494.6 4504.3 9439.3 9450.8 9452.9 9454.0 9455.1 4764.1 4764.6 4772.6 4782.1 4790.1 3771.2 4254.4 4347.2 4358.3 4417.1 3684.6 3686.2 3807.8 3820.8 3828.7 1171.1 1173.7 1177.3 1194.7 1198.7 634.3 776.9 798.5 798.9 799.9 5288.4 5473.2 5496.0 5564.1 5642.0 381.9 396.3 398.3 400.9 407.1 3775.8 3785.3 3858.7 3863.2 3932.8 9389.3 9754.1 9901.8 9947.1 9972.7 9909.1 9931.5 9937.6 9942.1 9945.5 7368.4 7517.8 7535.7 7540.2 7540.8 5333.3 5455.0 5476.9 5486.7 5728.7 9083.2 9132.0 9133.2 9184.0 9204.1 9238.4 9296.6 9317.6 9332.2 9381.3 5954.6 6694.2 6845.6 6950.9 6957.9 7405.9 7719.0 7719.2 7721.6 7727.1 6692.4 6762.6 6911.6 6913.0 6922.8 6904.3 6943.0 7019.7 7101.6 7104.7 5892.5 5915.1 5955.1 5955.8 5963.8 4763.2 4774.9 4805.0 4824.3 4888.7 4678.9 4733.9 4847.5 4893.0 4909.2 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | [#4] prototype.html Cycles:180 Average:4162.35 Median:4641.07 stddev:1203.72 (25.9%) stddev-sans-first:1171.31 04:36:49 INFO - PROCESS | 9842 | Values: 280.6 290.9 294.4 294.5 298.1 4706.3 4867.2 4878.3 4913.5 4916.8 4666.3 4786.4 4793.6 4800.2 4853.3 3789.3 3792.8 3813.0 3821.4 3842.8 3588.3 3642.3 3852.8 3855.2 3855.6 3776.3 3788.8 3793.9 3816.9 3827.5 3781.7 3802.0 3809.7 3810.4 3820.6 3325.2 3330.0 3337.1 3348.2 3348.8 4563.0 4671.8 4703.9 4720.7 4720.9 4295.9 4297.6 4330.2 4347.0 4356.6 5000.2 5036.7 5061.1 5067.3 5071.6 3546.6 3663.1 3677.0 3679.8 3705.4 5255.4 5264.2 5272.9 5286.7 5288.2 3768.8 3780.9 3795.4 3801.2 3809.8 3743.8 3808.8 3826.7 3860.1 3866.6 4293.4 4304.7 4308.8 4348.2 4356.6 5014.7 5019.9 5028.0 5039.2 5064.1 1257.5 1273.2 1309.8 1314.2 1320.5 889.5 905.4 919.1 919.6 925.5 4423.1 4426.1 4428.9 4442.3 4460.3 5123.3 5136.3 5154.9 5156.3 5176.6 4412.7 4419.3 4423.4 4440.2 4447.7 2313.5 2418.3 2433.3 2443.7 2457.6 4301.4 4305.9 4309.2 4328.2 4342.6 4578.0 4634.6 4638.8 4661.0 4704.8 4299.4 4524.6 4873.1 4894.1 4897.7 5224.7 5225.2 5240.9 5242.6 5253.9 5111.8 5128.5 5155.5 5159.3 5181.8 4643.3 4832.3 4912.2 4919.4 4922.5 3657.6 3665.4 3667.9 3668.9 3673.6 5017.1 5205.7 5232.0 5238.2 5254.8 4706.6 5061.6 5064.0 5070.9 5099.7 5067.3 5067.8 5068.9 5070.8 5076.6 5002.6 5007.4 5008.3 5011.2 5032.5 4876.4 5052.3 5063.3 5074.9 5085.6 4940.5 4954.8 4968.0 4974.5 4981.1 04:36:49 INFO - PROCESS | 9842 | 04:36:49 INFO - PROCESS | 9842 | [#5] yui.html Cycles:180 Average:17003.83 Median:31164.04 stddev:24511.34 (78.7%) stddev-sans-first:24549.32 04:36:49 INFO - PROCESS | 9842 | Values: 644.4 659.3 688.4 710.3 742.0 12603.9 13601.3 13661.5 13671.4 13689.6 12925.4 13005.9 13018.7 13025.7 13068.7 622.4 755.4 759.2 774.5 781.4 778.1 789.9 790.1 790.2 790.2 839.2 841.3 841.3 841.5 842.1 657.9 800.6 816.5 834.2 835.1 599.4 599.5 600.0 600.0 600.2 12459.3 12499.7 12507.4 12515.6 12612.6 12404.6 12678.0 12979.2 13093.5 13446.5 11342.2 11408.9 11486.5 11652.9 11832.6 3789.8 3898.2 3918.4 4023.4 4105.3 62795.7 63338.0 64003.4 64723.0 64882.4 10355.6 10396.7 10474.9 10479.7 10490.7 19438.8 19552.8 19602.6 19635.5 19732.8 6410.1 6558.0 6585.8 6653.2 6758.3 3601.8 3676.2 3678.6 3763.2 3776.5 2765.0 2851.8 2861.4 2861.6 2864.0 474.5 474.8 477.1 489.8 496.5 433.4 539.9 545.2 549.4 573.1 7173.5 7562.8 7563.2 7668.6 8041.9 415.4 440.5 475.9 514.3 524.6 3501.4 3700.8 3755.4 3797.8 3836.7 54674.0 97323.5 97346.5 98097.0 98926.0 49303.8 59286.7 76077.6 79036.6 86815.0 74676.6 75989.6 78772.6 79450.0 79820.6 6817.1 6821.2 6847.0 6984.5 6991.8 45462.5 46079.0 46289.0 46647.5 47036.8 61705.7 63780.4 64303.0 64569.4 67043.0 9627.6 9817.6 9941.4 10088.2 10112.5 10283.4 10514.2 10665.1 10985.2 11041.4 9518.9 9535.5 9599.0 9604.8 9839.0 9187.9 9539.5 9627.0 9668.1 9718.7 9129.2 9315.8 9331.9 9417.7 9477.8 8384.7 8533.0 8537.4 8825.6 9347.2 7624.4 7899.5 8045.2 8074.4 8110.8 04:36:49 INFO - PROCESS | 9842 | -------- Summary: end -------- 04:36:49 INFO - PROCESS | 9842 | 04:36:50 INFO - TEST-INFO | 9842: exit 0 04:36:50 INFO - TEST-OK | dromaeo_css | took 1120791ms 04:36:50 INFO - TEST-START | kraken 04:36:50 INFO - operating with platform_type : linux_ 04:36:50 INFO - Initialising browser for kraken test... 04:36:50 INFO - TEST-INFO | started process 10032 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_mrsrN/profile http://localhost:43259/getInfo.html) 04:36:54 INFO - PROCESS | 10032 | JavaScript error: , line 0: uncaught exception: undefined 04:36:55 INFO - PROCESS | 10032 | __metrics Screen width/height:1600/1200 04:36:55 INFO - PROCESS | 10032 | colorDepth:24 04:36:55 INFO - PROCESS | 10032 | Browser inner width/height: 1024/697 04:36:55 INFO - PROCESS | 10032 | __metrics 04:36:56 INFO - PROCESS | 10032 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:36:56 INFO - PROCESS | 10032 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:36:57 INFO - TEST-INFO | 10032: exit 0 04:36:57 INFO - Browser initialized. 04:36:57 INFO - Running cycle 1/1 for kraken test... 04:36:57 INFO - TEST-INFO | started process 10114 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_mrsrN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:09 INFO - PROCESS | 10114 | RSS: Main: 159518720 04:37:09 INFO - PROCESS | 10114 | 04:37:11 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43259/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:12 INFO - PROCESS | 10114 | RSS: Main: 215584768 04:37:12 INFO - PROCESS | 10114 | 04:37:15 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43259/tests/kraken/driver.html?testName=audio-dft) 04:37:16 INFO - PROCESS | 10114 | RSS: Main: 194007040 04:37:16 INFO - PROCESS | 10114 | 04:37:19 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43259/tests/kraken/driver.html?testName=audio-fft) 04:37:19 INFO - PROCESS | 10114 | RSS: Main: 273350656 04:37:19 INFO - PROCESS | 10114 | 04:37:22 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43259/tests/kraken/driver.html?testName=audio-oscillator) 04:37:22 INFO - PROCESS | 10114 | RSS: Main: 182087680 04:37:22 INFO - PROCESS | 10114 | 04:37:25 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43259/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:25 INFO - PROCESS | 10114 | RSS: Main: 186077184 04:37:25 INFO - PROCESS | 10114 | 04:37:28 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43259/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:29 INFO - PROCESS | 10114 | RSS: Main: 222027776 04:37:29 INFO - PROCESS | 10114 | 04:37:32 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43259/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:33 INFO - PROCESS | 10114 | RSS: Main: 222429184 04:37:33 INFO - PROCESS | 10114 | 04:37:36 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43259/tests/kraken/driver.html?testName=json-parse-financial) 04:37:36 INFO - PROCESS | 10114 | RSS: Main: 221769728 04:37:36 INFO - PROCESS | 10114 | 04:37:38 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43259/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:39 INFO - PROCESS | 10114 | RSS: Main: 186245120 04:37:39 INFO - PROCESS | 10114 | 04:37:41 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:37:41 INFO - PROCESS | 10114 | RSS: Main: 191418368 04:37:41 INFO - PROCESS | 10114 | 04:37:43 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:37:44 INFO - PROCESS | 10114 | RSS: Main: 196689920 04:37:44 INFO - PROCESS | 10114 | 04:37:47 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:37:47 INFO - PROCESS | 10114 | RSS: Main: 191369216 04:37:47 INFO - PROCESS | 10114 | 04:37:50 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:37:51 INFO - PROCESS | 10114 | RSS: Main: 194924544 04:37:51 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | Cycle 1(1): loaded http://localhost:43259/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43259/tests/kraken/driver.html?testName=ai-astar) 04:37:53 INFO - PROCESS | 10114 | RSS: Main: 245022720 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | __start_tp_report 04:37:53 INFO - PROCESS | 10114 | _x_x_mozilla_page_load 04:37:53 INFO - PROCESS | 10114 | _x_x_mozilla_page_load_details 04:37:53 INFO - PROCESS | 10114 | |i|pagename|runs| 04:37:53 INFO - PROCESS | 10114 | |0;ai-astar;104;116;115;113;114;113;115;114;113;113 04:37:53 INFO - PROCESS | 10114 | |1;audio-beat-detection;156;125;124;188;120;123;148;123;162;126 04:37:53 INFO - PROCESS | 10114 | |2;audio-dft;161;161;160;160;162;162;161;162;159;162 04:37:53 INFO - PROCESS | 10114 | |3;audio-fft;75;75;75;125;75;76;75;76;111;75 04:37:53 INFO - PROCESS | 10114 | |4;audio-oscillator;84;84;127;85;85;85;119;85;85;129 04:37:53 INFO - PROCESS | 10114 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;116;117 04:37:53 INFO - PROCESS | 10114 | |6;imaging-darkroom;146;146;146;146;146;145;146;146;146;146 04:37:53 INFO - PROCESS | 10114 | |7;imaging-desaturate;95;95;95;95;95;94;94;94;95;95 04:37:53 INFO - PROCESS | 10114 | |8;json-parse-financial;62;63;64;63;64;63;64;63;62;63 04:37:53 INFO - PROCESS | 10114 | |9;json-stringify-tinderbox;67;67;67;67;67;67;66;66;66;67 04:37:53 INFO - PROCESS | 10114 | |10;stanford-crypto-aes;82;78;79;81;85;80;80;79;78;81 04:37:53 INFO - PROCESS | 10114 | |11;stanford-crypto-ccm;129;135;130;130;127;131;128;126;205;146 04:37:53 INFO - PROCESS | 10114 | |12;stanford-crypto-pbkdf2;168;166;167;170;166;170;170;166;164;166 04:37:53 INFO - PROCESS | 10114 | |13;stanford-crypto-sha256-iterative;69;67;68;72;66;67;67;67;65;66 04:37:53 INFO - PROCESS | 10114 | __end_tp_report 04:37:53 INFO - PROCESS | 10114 | __start_cc_report 04:37:53 INFO - PROCESS | 10114 | _x_x_mozilla_cycle_collect,1945 04:37:53 INFO - PROCESS | 10114 | __end_cc_report 04:37:53 INFO - PROCESS | 10114 | __startTimestamp1469878673448__endTimestamp 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | ------- Summary: start ------- 04:37:53 INFO - PROCESS | 10114 | Number of tests: 14 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.33 (2.9%) stddev-sans-first:1.12 04:37:53 INFO - PROCESS | 10114 | Values: 104.0 116.0 115.0 113.0 114.0 113.0 115.0 114.0 113.0 113.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:137.00 stddev:23.00 (16.8%) stddev-sans-first:23.61 04:37:53 INFO - PROCESS | 10114 | Values: 156.0 125.0 124.0 188.0 120.0 123.0 148.0 123.0 162.0 126.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.05 (0.7%) stddev-sans-first:1.12 04:37:53 INFO - PROCESS | 10114 | Values: 161.0 161.0 160.0 160.0 162.0 162.0 161.0 162.0 159.0 162.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#3] audio-fft Cycles:10 Average:83.80 Median:75.00 stddev:18.33 (24.4%) stddev-sans-first:19.16 04:37:53 INFO - PROCESS | 10114 | Values: 75.0 75.0 75.0 125.0 75.0 76.0 75.0 76.0 111.0 75.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:85.00 stddev:19.62 (23.1%) stddev-sans-first:20.26 04:37:53 INFO - PROCESS | 10114 | Values: 84.0 84.0 127.0 85.0 85.0 85.0 119.0 85.0 85.0 129.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 04:37:53 INFO - PROCESS | 10114 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 04:37:53 INFO - PROCESS | 10114 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:37:53 INFO - PROCESS | 10114 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:0.74 (1.2%) stddev-sans-first:0.67 04:37:53 INFO - PROCESS | 10114 | Values: 62.0 63.0 64.0 63.0 64.0 63.0 64.0 63.0 62.0 63.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 04:37:53 INFO - PROCESS | 10114 | Values: 67.0 67.0 67.0 67.0 67.0 67.0 66.0 66.0 66.0 67.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.50 stddev:2.11 (2.6%) stddev-sans-first:2.15 04:37:53 INFO - PROCESS | 10114 | Values: 82.0 78.0 79.0 81.0 85.0 80.0 80.0 79.0 78.0 81.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:130.50 stddev:23.99 (18.4%) stddev-sans-first:25.19 04:37:53 INFO - PROCESS | 10114 | Values: 129.0 135.0 130.0 130.0 127.0 131.0 128.0 126.0 205.0 146.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:2.11 (1.3%) stddev-sans-first:2.22 04:37:53 INFO - PROCESS | 10114 | Values: 168.0 166.0 167.0 170.0 166.0 170.0 170.0 166.0 164.0 166.0 04:37:53 INFO - PROCESS | 10114 | 04:37:53 INFO - PROCESS | 10114 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.96 (2.9%) stddev-sans-first:1.99 04:37:53 INFO - PROCESS | 10114 | Values: 69.0 67.0 68.0 72.0 66.0 67.0 67.0 67.0 65.0 66.0 04:37:53 INFO - PROCESS | 10114 | -------- Summary: end -------- 04:37:53 INFO - PROCESS | 10114 | 04:37:54 INFO - TEST-INFO | 10114: exit 0 04:37:54 INFO - TEST-OK | kraken | took 64128ms 04:37:54 INFO - SUITE-END | took 1184s 04:37:54 INFO - Completed test suite (00:19:44) 04:37:54 INFO - javascript benchmark 04:37:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2588.818782685212, 2628.645132907059, 2634.841909485431, 2654.8274362166458, 2658.574628059511, 4607.28587069004, 4687.882802929928, 4729.290606409036, 4730.810767569297, 4737.336772851803, 4720.433547974248, 4762.547557981995, 4770.594499467549, 4780.737059461728, 4822.565969062784, 4345.174416860794, 4352.738835669854, 4416.072624748806, 4472.709273897202, 4527.298268768346, 4445.088756804864, 4477.216487114758, 4486.618637415823, 4498.50516443191, 4503.099380123972, 4283.485981682187, 4328.199283132631, 4333.2200203963275, 4340.65274778017, 4415.249407600683, 4334.068175342317, 4343.066240758252, 4370.628496577795, 4439.089986552781, 4443.355713421536, 4366.8253269869165, 4376.0, 4378.233809083429, 4407.427034485529, 4416.514183439819, 4568.360429539867, 4752.0, 4806.182948898696, 4806.4712881583, 4823.758812059418, 5055.469175736532, 5075.187969924831, 5080.872978175538, 5100.311457953166, 5143.382794064699, 5142.7685754140775, 5158.432572417031, 5171.844844654666, 5197.402298735618, 5204.635675502716, 4666.976665116653, 4762.166620841339, 4819.638527110481, 4834.154023045953, 4840.564349208588, 5259.921716048201, 5286.022085914118, 5310.309659744208, 5328.866778330495, 5331.573474122061, 5062.265971434122, 5120.974395128001, 5121.692698438031, 5122.282880396748, 5123.103456895028, 4948.777305021283, 4993.375828021497, 5156.200788877655, 5221.869453263623, 5223.52988231061, 4997.450280469146, 5009.198528235464, 5015.573676237561, 5060.924086138711, 5072.289879416885, 4989.501049894981, 4994.375703037121, 4996.825111121093, 4998.2002879539095, 5029.748512574357, 4126.546079931205, 4166.979090122939, 4202.327627579633, 4209.936850947238, 4254.7659878706345, 3719.386301260262, 3730.384486559687, 3790.8673196437994, 3796.1838204786104, 3854.672352849983, 4385.451818522684, 4389.692721509464, 4390.275604525282, 4402.449693788277, 4432.556744325541, 4955.40535135786, 5155.53600175986, 5164.483551644806, 5169.327987361619, 5198.454162312978, 4264.658827293809, 4355.281378572563, 4358.934615980763, 4379.846705365297, 4421.314696222091, 4632.073585282957, 4669.252919532859, 4670.859874203779, 4671.602913752301, 4709.81160753566, 4788.4253889533475, 4809.254565542346, 4821.445533763662, 4825.638077144158, 4833.419989601271, 4983.601311895112, 5081.110805609078, 5122.79508819658, 5128.358955130609, 5186.0, 4761.16679581065, 4779.617630589614, 4785.808567657255, 4800.0, 4843.951560484491, 5063.291139240583, 5070.062038522885, 5070.290159377616, 5088.618353623567, 5138.768755405941, 4917.631177661761, 5033.9428719967755, 5068.366454193226, 5084.6440749147205, 5122.820701275437, 4971.279164521052, 4978.452370239198, 4979.551840334388, 4980.526849949249, 5022.648414611088, 4939.456659767351, 4960.479149689375, 4969.45336013031, 4974.527419895104, 5018.949810501995, 4789.473157952552, 4819.132556139928, 4861.562459378673, 4866.6350023747655, 4926.310316555613, 4992.725400102884, 4999.725015124058, 5011.874703132305, 5019.171836646912, 5039.84880453587, 4924.0, 4926.975365123151, 4936.531029552187, 4940.851774446773, 4989.12690279207], "value": 4682.600594443989, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.9971550142249, 576.9701083565835, 584.9444302791234, 588.1031427073714, 588.7027051339073, 27479.45041099177, 40465.3930191047, 41951.0, 42046.36930446046, 45116.774416127955, 40511.18977620446, 40987.38518922219, 41143.382849257214, 41159.79420102903, 41176.58823411765, 7317.378022868036, 7681.615919204045, 7861.371090312789, 7869.055713314382, 7872.724454644089, 7439.0, 7512.248775122499, 7517.511361761495, 9447.338686291929, 9775.755606109833, 10108.595656173744, 10133.391996480235, 10162.136218421443, 10168.13570846479, 10169.644062457815, 10053.145482633985, 10081.44552049637, 10095.697129086138, 10109.0, 10118.038786315288, 9912.702618921443, 10004.19966402686, 10009.749756256078, 10021.749456263578, 10022.098011178956, 36942.445863312074, 37172.0, 37220.44169337462, 37275.440868387, 37297.440538391944, 2977.4194032163737, 2993.1020693791775, 3284.8193349366015, 3774.226283611853, 3868.535775706905, 26848.59727104095, 27044.729552704615, 28138.85930570334, 29733.851330743208, 31456.842715786504, 3045.4365942300747, 3114.408262430131, 3129.4679904416303, 3130.4521708701113, 3194.0896844399235, 270948.0, 273510.0, 273674.0, 274173.0, 274473.0, 57165.42834571684, 57513.712431437576, 57564.8487030257, 57573.0, 57638.0, 33360.49959250613, 34202.31595368128, 34316.14209644779, 35851.4622280666, 35903.0, 15536.06783592988, 15676.68646627061, 15806.762898556532, 15807.288672009787, 15819.367225310858, 11902.0, 12049.518019279307, 12049.879501204876, 12062.155649104478, 12063.819042714365, 16179.0, 18314.725279120823, 18759.155837987324, 18941.33705320307, 19715.338429311858, 6759.324067593299, 6899.413549848319, 6984.225301457578, 7127.287271272935, 7138.107736532934, 741.4809633256742, 814.7515007922641, 849.515776007682, 874.8031692869054, 910.9114608043457, 27527.587086193722, 28765.137045888656, 28768.71231287702, 29579.852100739357, 29668.258293542833, 907.3013779389833, 940.2431043010442, 958.5351104714208, 989.085096285947, 1007.5717819926502, 14969.625759355886, 15209.315580798891, 15328.156951367559, 15591.532254032398, 15601.68796624084, 424505.0, 428578.0, 429368.0, 430317.0, 430485.0, 50762.23856642153, 51203.0, 51306.48693513017, 51348.22977655338, 51366.48633513617, 1043.3583346242006, 1071.7374243310283, 1089.8910108989037, 1126.7239526316105, 1132.9376884271444, 27593.034243802173, 29537.26156846096, 29579.556306654988, 30473.085807425814, 30624.387512249185, 36581.08547286339, 37022.62977370192, 37053.629463705016, 37067.0, 37093.443598345504, 142520.0, 143659.0, 143751.5624843738, 143867.280663596, 144169.0, 25600.487990240465, 25836.870815646555, 26597.33506662349, 26638.600420994087, 27783.30541736473, 42004.36993445162, 42192.36711449269, 42364.576354237295, 42784.5721542793, 43479.78260108806, 35945.64054359423, 36022.819885900404, 36112.81943590265, 36584.634153658124, 37000.81499592485, 36908.446373304956, 37001.814990924875, 37479.25041499209, 38053.809730951165, 38419.42370864495, 35297.0, 35427.0, 35807.28385432329, 35874.820625896704, 36001.63998360088, 31239.375212495168, 33402.0, 33596.0, 33624.327513449105, 33908.49137262993, 25072.0, 25307.873460632578, 25526.872365638053, 25588.616170757083, 25999.220023399328], "value": 17513.34916670466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.8665500437336, 362.2013460319995, 371.92747414254217, 374.39722047503517, 381.4430930840972, 13303.600891973245, 16197.676046479064, 16342.59143521411, 17402.82597174028, 17572.912135439306, 16943.322267109303, 17033.91483042583, 17067.231974561142, 17294.654106917886, 17404.65190696185, 5129.461406552315, 5209.192575150839, 5413.756380962846, 5449.64577302476, 5534.225208470817, 3508.9649103508896, 5082.339295891547, 5475.479829416219, 5505.476979686943, 5508.366537848158, 5348.545373643245, 5403.432639572848, 5425.240466334716, 5429.945700542983, 5430.0, 5239.423663397023, 5362.758675859557, 5398.78404863805, 5403.243545903577, 5420.1327787554, 5246.3704355477585, 5251.238570407269, 5259.763310650992, 5261.15821468567, 5262.92105618416, 12044.578439754567, 12256.938715306367, 12350.320732359716, 12365.38173091342, 12463.0, 1846.510674671213, 1848.3530764232546, 1856.2760523395887, 1865.8880467172014, 2002.2691717523144, 8910.554472276424, 8963.46219226842, 9184.448933064037, 9421.199198068172, 9464.148226659565, 1705.224123024021, 1710.5381546982246, 1728.8271172882737, 1733.8612910967092, 1743.5466778637644, 48792.756036219595, 66091.66954165246, 67611.66194169047, 70453.64773176152, 72937.63531182363, 10029.0, 10152.949235253776, 10214.387136771817, 10338.534765935401, 10508.684739457829, 14155.575332740034, 14171.149731015967, 14192.787108193384, 14215.50245741394, 14217.92891035559, 7327.816804579821, 7350.471773548727, 7430.851382972311, 7541.321281084729, 7656.851472279202, 7077.435054389993, 7182.3535881770895, 7272.63636818157, 7404.37062849653, 7573.772786816405, 4373.278409062469, 4451.3323001550025, 4477.686561940633, 4567.269236922077, 4571.497135315112, 1748.519157231746, 1843.5851933314898, 1877.11775465531, 1938.2731475696614, 1938.4766113149653, 693.5977133262695, 745.0909889934271, 759.9810004749926, 789.8657228271207, 834.9206825351582, 16354.754678679828, 16699.982009714862, 17044.744328835077, 17441.7383739244, 17453.301867925395, 760.9201033891472, 762.3748526208567, 777.6889244302324, 785.807477168086, 816.3999460396603, 8962.507062111516, 10287.03833170196, 10319.226058045677, 10399.584016639401, 10493.213009024354, 431497.0, 438536.0, 445823.0, 445992.0, 448981.0, 82005.0, 86569.56715216384, 88928.0, 89430.55284723535, 90337.0, 873.8208667223204, 907.5643691028212, 915.624593916491, 916.2303664921498, 919.3977944446265, 15367.769483457534, 15494.612634684223, 15966.201689915226, 16215.270312835715, 16234.18829058566, 37737.433938490394, 37925.069740933504, 39812.80093599513, 40334.79832600818, 40353.39469907895, 47642.78338231811, 49879.25181122214, 51169.744151279, 52698.736506317226, 53752.19371709349, 7589.886151707839, 7598.506097103671, 7728.227177282227, 7728.7681369559, 7927.167647397171, 9864.605415783288, 11911.106666999836, 12253.877461225273, 12546.749065018466, 12629.557965471164, 9767.169790567597, 9833.459159746282, 10008.349457285252, 10049.346792458467, 10482.895171048496, 9993.150582200595, 10427.530761115617, 10454.32046916948, 10515.842262365917, 10521.894781052091, 9758.365706229073, 10447.164226861682, 10584.312019718695, 10728.51721672511, 10824.891751082703, 6590.637514936724, 6987.860242795014, 7037.788866334018, 7400.5189662671755, 7746.0, 8839.734807955772, 10039.799204016024, 10460.26778125524, 10598.735031624272, 10906.61826836057], "value": 8119.29566977739, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.2456186816316, 1428.3072709735777, 1482.8220613526378, 1676.0697812713954, 1723.5087999920017, 7632.618369081544, 8142.715004974827, 8163.10205877353, 8163.714270000552, 8165.34677225822, 6886.586804791704, 7591.5065520741255, 7804.960975195116, 7848.293653571178, 8025.959870200655, 2101.831853451724, 2119.9894000529976, 2122.660374340106, 2123.893805309736, 2127.2448280860303, 2132.7973842485017, 2156.1159924430985, 2220.6335954567476, 2223.8776867272295, 2226.1763147635397, 2171.9565608687817, 2180.3023032629567, 2192.3970908000347, 2192.5395666909967, 2228.765979572146, 2123.1507397041228, 2125.3623912826247, 2129.169623846701, 2129.58473097746, 2130.0201907122664, 2080.8855512946784, 2116.9682454763038, 2119.7774233705545, 2120.3956872291324, 2121.087932189158, 8057.315128214109, 8059.717909873185, 8114.431989760719, 8120.59397030152, 8122.796930076736, 4666.766661666937, 4811.302361157613, 4820.517948205186, 4821.831235906761, 4826.420829500447, 2884.365439603284, 2944.514155164395, 3006.969930300691, 3016.502277124272, 3020.879164833404, 864.1315477944688, 930.6742640075987, 947.8341290274175, 949.8812648418948, 960.5437417226831, 10991.835122473169, 11010.724731881686, 11036.172287078422, 11061.889381106166, 11064.889351106467, 4086.1214838809797, 4317.676174286941, 4378.2119218540965, 4494.5730155635165, 4504.25679762842, 9439.300925833415, 9450.763730906783, 9452.8582071269, 9454.0, 9455.14903658674, 4764.142454358249, 4764.618830493552, 4772.5943294819635, 4782.067496838084, 4790.137775200497, 3771.17034252464, 4254.3831144483875, 4347.217500849814, 4358.259095953696, 4417.072414792865, 3684.6499582539614, 3686.189038411545, 3807.8476860925807, 3820.770753754728, 3828.7128465364653, 1171.1158075652997, 1173.7065733566608, 1177.3112729053428, 1194.7252132009687, 1198.6823491774687, 634.2864277687602, 776.8679324514846, 798.5368486277945, 798.9041315042232, 799.9000124984377, 5288.391834938954, 5473.233747275384, 5495.972520137374, 5564.10974244119, 5642.04085305499, 381.8987968188432, 396.3440505962579, 398.32682766125157, 400.9255196074548, 407.10437038515386, 3775.8489660413275, 3785.2997195518365, 3858.6527212551005, 3863.16941857502, 3932.7640341579595, 9389.29580281468, 9754.122129008281, 9901.75245618851, 9947.05502977216, 9972.700818975442, 9909.108180263811, 9931.50342482873, 9937.552810123561, 9942.105210531086, 9945.502724863873, 7368.37368823656, 7517.812054698677, 7535.698572057166, 7540.17058123617, 7540.773776786705, 5333.333333333333, 5454.972725136349, 5476.863078423071, 5486.725663716718, 5728.742206600629, 9083.18251357381, 9132.0, 9133.223675987616, 9183.954080229556, 9204.079592040742, 9238.445693258425, 9296.6281348748, 9317.627294908129, 9332.206762424998, 9381.296402769683, 5954.583179177416, 6694.196696396463, 6845.623490708059, 6950.93049069523, 6957.895631565631, 7405.925940740739, 7718.961405192938, 7719.189485104208, 7721.5753133575945, 7727.11138219112, 6692.39768420825, 6762.594244345355, 6911.619860907698, 6912.965435172993, 6922.826929326807, 6904.3095690430555, 6942.965285173542, 7019.719211231493, 7101.644917753988, 7104.715811367488, 5892.499137573354, 5915.142304365929, 5955.076963070731, 5955.821325360246, 5963.821085367446, 4763.190257656138, 4774.856754297377, 4805.0, 4824.252240902666, 4888.706677599355, 4678.92981605283, 4733.9053218936115, 4847.5394837490385, 4892.975535122301, 4909.239067944474], "value": 4122.472805582129, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.5931399470768, 290.92435966648674, 294.3832670555186, 294.49347122948535, 298.0744787435014, 4706.3411122442885, 4867.1969125094365, 4878.341423907771, 4913.53321434464, 4916.803327866885, 4666.343448075935, 4786.401699787527, 4793.57924248255, 4800.207965685667, 4853.272009198622, 3789.2610940866543, 3792.8103594820286, 3812.9809350953205, 3821.426785982097, 3842.7502212356108, 3588.282343531291, 3642.3261696586214, 3852.8266228019806, 3855.2289542091694, 3855.63371479709, 3776.339140650389, 3788.7537310074895, 3793.924121517568, 3816.8740221634544, 3827.4641550182996, 3781.735278530504, 3801.9619803801884, 3809.7237425462363, 3810.371288737355, 3820.5930263933683, 3325.1687078230443, 3329.983350083234, 3337.0823023668445, 3348.2299071213515, 3348.7823291485975, 4563.0, 4671.789769460383, 4703.882402939919, 4720.716756994591, 4720.929186062212, 4295.91408171838, 4297.613214810682, 4330.17726631939, 4346.978460061883, 4356.607905288539, 5000.1999680051385, 5036.6726162799305, 5061.115164356318, 5067.290579318898, 5071.644984851062, 3546.6098729139776, 3663.065918190844, 3676.963230367689, 3679.7792132472136, 3705.4441833724886, 5255.448177941337, 5264.236685680555, 5272.947270527322, 5286.682799032032, 5288.180332048539, 3768.773873567595, 3780.8676696315497, 3795.3927371620407, 3801.1827457096306, 3809.75236609625, 3743.794091325003, 3808.7714737115307, 3826.732128750961, 3860.1121741999495, 3866.555346135174, 4293.3989241506215, 4304.720193187495, 4308.784560771949, 4348.152110338454, 4356.564343565618, 5014.749262536859, 5019.899602007939, 5028.0, 5039.244113383022, 5064.139096353629, 1257.5158563952943, 1273.178799674205, 1309.7642424363705, 1314.2180402660401, 1320.531211419932, 889.4974339498162, 905.44767691708, 919.131420807344, 919.6045700348781, 925.5187302602609, 4423.137488189836, 4426.136903303889, 4428.933565996513, 4442.333649952533, 4460.286354183315, 5123.308353372322, 5136.255242989746, 5154.871128221749, 5156.278121063054, 5176.602317374288, 4412.69111162222, 4419.270820314677, 4423.380726698265, 4440.245158323029, 4447.710164052389, 2313.5372925414986, 2418.322869596516, 2433.270018994295, 2443.7067551893533, 2457.557639624849, 4301.376300436419, 4305.935410968838, 4309.168603343566, 4328.220920234388, 4342.5874541918465, 4577.977110114428, 4634.629229661619, 4638.83764068256, 4660.976695116503, 4704.764761761967, 4299.441072660534, 4524.5701658342405, 4873.147199240118, 4894.119058569349, 4897.706137631825, 5224.7126408047925, 5225.216217567396, 5240.947590524046, 5242.554382877498, 5253.921191182212, 5111.769970351269, 5128.512611424268, 5155.53600175986, 5159.303494028408, 5181.792728290826, 4643.303504474356, 4832.34763306949, 4912.238603016538, 4919.425783749166, 4922.458529561817, 3657.634236576321, 3665.4318580620047, 3667.8716244932366, 3668.926621467609, 3673.5959044505616, 5017.147084995487, 5205.6616319939085, 5232.0438951992, 5238.161894097078, 5254.842354729364, 4706.576408123286, 5061.595072394273, 5063.974680126723, 5070.873228169326, 5099.719515426687, 5067.290579318972, 5067.797288108434, 5068.949310506995, 5070.8225212117395, 5076.6446348755235, 5002.649814512949, 5007.37407824022, 5008.298838162587, 5011.173156429294, 5032.471590483093, 4876.414830220397, 5052.317937078447, 5063.3417655704525, 5074.898502029865, 5085.593152547861, 4940.505949405031, 4954.752262386794, 4967.975160124176, 4974.477679843564, 4981.10340138764], "value": 3781.3406784681724, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.3524258120589, 659.3143131143611, 688.4010910507859, 710.2684235237704, 742.046470285683, 12603.936980315097, 13601.251931143808, 13661.453541858315, 13671.384787684554, 13689.589312320646, 12925.35373231337, 13005.934970325136, 13018.674533136676, 13025.73948521029, 13068.73862522749, 622.3869488553771, 755.3805879179089, 759.2407592407592, 774.508187301065, 781.4217479065479, 778.1246098139593, 789.8696715042012, 790.0716657926941, 790.1861083084403, 790.1979490816825, 839.2195258409677, 841.2512863551444, 841.3143288220111, 841.5455653946862, 842.1199846160746, 657.8750037492861, 800.6037011679198, 816.465215283984, 834.2491757418342, 835.0980940584154, 599.4125756758358, 599.5443462968132, 599.9820005399845, 600.0279547133628, 600.1507866369083, 12459.252444853248, 12499.687507812287, 12507.437165327583, 12515.56195533152, 12612.621621351374, 12404.56584019555, 12678.0, 12979.221246725227, 13093.541726039639, 13446.462141514328, 11342.206045576813, 11408.94295528517, 11486.540538378455, 11652.941735291353, 11832.645020649308, 3789.77261364319, 3898.161895192547, 3918.3730603103368, 4023.3562629979356, 4105.281575724266, 62795.6860215696, 63338.04992925156, 64003.35996640067, 64723.0, 64882.351176488104, 10355.63755268577, 10396.688099357032, 10474.895251047392, 10479.738006549745, 10490.741111066722, 19438.805611943983, 19552.804471955384, 19602.607947840963, 19635.509112272022, 19732.802671973383, 6410.134631824644, 6557.967210164014, 6585.835354116185, 6653.234877989094, 6758.290379510179, 3601.819909004539, 3676.1544844685873, 3678.558572971207, 3763.2097259575794, 3776.4712940188397, 2764.986175069152, 2851.7861160413054, 2861.3561948561824, 2861.642294713161, 2863.957040644392, 474.53732610704975, 474.7578734845185, 477.11971412743435, 489.8089744999453, 496.5282981167905, 433.3802662193031, 539.8731298144933, 545.1767830575859, 549.4368272520699, 573.0973716396692, 7173.46199035064, 7562.810929726801, 7563.205863384375, 7668.616569171519, 8041.8793718094275, 415.39974736506065, 440.50003246315663, 475.8911735655913, 514.282575806755, 524.5593701290934, 3501.3697534444045, 3700.777953322809, 3755.361588529902, 3797.7911214883447, 3836.731428800013, 54674.0, 97323.51338243263, 97346.51326743462, 98097.0, 98926.0, 49303.753481233085, 59286.703566482756, 76077.61961190158, 79036.60481697555, 86815.0, 74676.62661686656, 75989.62005190049, 78772.60613696894, 79450.0, 79820.60089699514, 6817.079694241214, 6821.249662537018, 6846.965765171142, 6984.476164287597, 6991.7902462926195, 45462.54537454715, 46079.0, 46289.0, 46647.533524664315, 47036.7648161757, 61705.691471542355, 63780.3621963793, 64303.0, 64569.354306456335, 67043.0, 9627.614895404386, 9817.607295708378, 9941.403515789076, 10088.243381746252, 10112.544935478069, 10283.43441110746, 10514.158867290751, 10665.093467055387, 10985.176111791488, 11041.447927603427, 9518.904810951803, 9535.523223838642, 9598.96635690509, 9604.75988100303, 9838.950805246215, 9187.908120918974, 9539.481885955181, 9626.95186524063, 9668.129868311886, 9718.659846905324, 9129.178373946377, 9315.813683726423, 9331.86002209981, 9417.717468475958, 9477.763055923657, 8384.706535271236, 8532.957335213285, 8537.428328468068, 8825.602847871989, 9347.205487533363, 7624.370934696619, 7899.526028438312, 8045.195480452142, 8074.434789564674, 8110.797230069295], "value": 6232.574199923614, "unit": "score"}], "name": "dromaeo_css", "value": 6336.010621636358, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 115.0, 113.0, 114.0, 113.0, 115.0, 114.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 124.0, 188.0, 120.0, 123.0, 148.0, 123.0, 162.0, 126.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 162.0, 162.0, 161.0, 162.0, 159.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 125.0, 75.0, 76.0, 75.0, 76.0, 111.0, 75.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 127.0, 85.0, 85.0, 85.0, 119.0, 85.0, 85.0, 129.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 64.0, 63.0, 64.0, 63.0, 64.0, 63.0, 62.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 79.0, 81.0, 85.0, 80.0, 80.0, 79.0, 78.0, 81.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 135.0, 130.0, 130.0, 127.0, 131.0, 128.0, 126.0, 205.0, 146.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 167.0, 170.0, 166.0, 170.0, 170.0, 166.0, 164.0, 166.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 72.0, 66.0, 67.0, 67.0, 67.0, 65.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1535.2, "alertThreshold": 2.0}]} 04:37:54 INFO - Return code: 0 04:37:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:37:54 INFO - # TBPL SUCCESS # 04:37:54 INFO - Running post-action listener: _resource_record_post_action 04:37:54 INFO - [mozharness: 2016-07-30 11:37:54.720977Z] Finished run-tests step (success) 04:37:54 INFO - Running post-run listener: _resource_record_post_run 04:37:54 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 0; Write bytes: 541683712; Read time: 0; Write time: 1332336 04:37:54 INFO - TinderboxPrint: CPU usage
13.1% 04:37:54 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:37:54 INFO - TinderboxPrint: I/O write bytes / time
541,683,712 / 1,332,336 04:37:54 INFO - TinderboxPrint: CPU idle
8,336.9 (86.9%) 04:37:54 INFO - TinderboxPrint: CPU user
1,200.9 (12.5%) 04:37:54 INFO - TinderboxPrint: Swap in / out
0 / 0 04:37:54 INFO - install - Wall time: 18s; CPU: 17.0%; Read bytes: 0; Write bytes: 253399040; Read time: 0; Write time: 793656 04:37:54 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 287600640; Read time: 0; Write time: 537700 04:37:55 INFO - Running post-run listener: _upload_blobber_files 04:37:55 INFO - Blob upload gear active. 04:37:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:37:55 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 04:37:55 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:37:55 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:37:55 INFO - (blobuploader) - INFO - Open directory for files ... 04:37:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:37:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:37:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:59 INFO - (blobuploader) - INFO - Done attempting. 04:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:37:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:37:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:37:59 INFO - (blobuploader) - INFO - Done attempting. 04:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:38:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:00 INFO - (blobuploader) - INFO - Done attempting. 04:38:00 INFO - (blobuploader) - INFO - Iteration through files over. 04:38:00 INFO - Return code: 0 04:38:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:38:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:38:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/704042600c0ca397e8c70c349a277ee087dcc958f4d2a8f4cd65b7f7928d7d87fd72b75d848a82706969c76484b89d020c2865be4ebf6675e4d02ee4e23bad2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/23fc254cae365ffcdea1f22c06c5a562dc10ba87f6c50a7000b991240488919c2f9381ec4db48b5bba0d6f38261c1f81d8878a93f6995830d15ce6f327320052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2348c68002c8125f1f1d35cc68f1cccb04ee1d4076120efab326edbf5c8beb1c9cff7c3a1ac26645d74d1b6d18f8b5e73e760d3d3669b9b0917c04000cba996"} 04:38:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:38:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:38:00 INFO - Contents: 04:38:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/704042600c0ca397e8c70c349a277ee087dcc958f4d2a8f4cd65b7f7928d7d87fd72b75d848a82706969c76484b89d020c2865be4ebf6675e4d02ee4e23bad2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/23fc254cae365ffcdea1f22c06c5a562dc10ba87f6c50a7000b991240488919c2f9381ec4db48b5bba0d6f38261c1f81d8878a93f6995830d15ce6f327320052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2348c68002c8125f1f1d35cc68f1cccb04ee1d4076120efab326edbf5c8beb1c9cff7c3a1ac26645d74d1b6d18f8b5e73e760d3d3669b9b0917c04000cba996"} 04:38:00 INFO - Running post-run listener: copy_logs_to_upload_dir 04:38:00 INFO - Copying logs to upload dir... 04:38:00 INFO - mkdir: /builds/slave/test/build/upload/logs 04:38:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.883601 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-30 04:38:00.520709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 04:38:00.524346) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/704042600c0ca397e8c70c349a277ee087dcc958f4d2a8f4cd65b7f7928d7d87fd72b75d848a82706969c76484b89d020c2865be4ebf6675e4d02ee4e23bad2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/23fc254cae365ffcdea1f22c06c5a562dc10ba87f6c50a7000b991240488919c2f9381ec4db48b5bba0d6f38261c1f81d8878a93f6995830d15ce6f327320052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2348c68002c8125f1f1d35cc68f1cccb04ee1d4076120efab326edbf5c8beb1c9cff7c3a1ac26645d74d1b6d18f8b5e73e760d3d3669b9b0917c04000cba996"} build_url:https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011528 build_url: 'https://queue.taskcluster.net/v1/task/YGvcGw7UToyPXiWD2BgvrQ/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/Autoland-Non-PGO/sha512/704042600c0ca397e8c70c349a277ee087dcc958f4d2a8f4cd65b7f7928d7d87fd72b75d848a82706969c76484b89d020c2865be4ebf6675e4d02ee4e23bad2d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/23fc254cae365ffcdea1f22c06c5a562dc10ba87f6c50a7000b991240488919c2f9381ec4db48b5bba0d6f38261c1f81d8878a93f6995830d15ce6f327320052", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/d2348c68002c8125f1f1d35cc68f1cccb04ee1d4076120efab326edbf5c8beb1c9cff7c3a1ac26645d74d1b6d18f8b5e73e760d3d3669b9b0917c04000cba996"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 04:38:00.557642) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:38:00.558384) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1469877439.77573-1820389071 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004543 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 04:38:00.640730) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 04:38:00.641457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 04:38:00.642139) ========= ========= Total master_lag: 0.20 =========