builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-046 starttime: 1500224309.86 results: success (0) revision: 5f44d10bacca2d693413b529e0caadc73e634e1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:29.858850) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:29.859306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:29.859650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:29.891754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:29.892081) ========= 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=652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-16 09:58:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2017-07-16 09:58:30 (217 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180164 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.096999) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.097323) ========= 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=652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014931 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.148313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.148734) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f44d10bacca2d693413b529e0caadc73e634e1e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 5f44d10bacca2d693413b529e0caadc73e634e1e --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=652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-16 09:58:30,591 truncating revision to first 12 chars 2017-07-16 09:58:30,591 Setting DEBUG logging. 2017-07-16 09:58:30,591 attempt 1/10 2017-07-16 09:58:30,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f44d10bacca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-16 09:58:31,025 unpacking tar archive at: mozilla-central-5f44d10bacca/testing/mozharness/ program finished with exit code 0 elapsedTime=0.745789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.909902) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.910559) ========= script_repo_revision: 5f44d10bacca2d693413b529e0caadc73e634e1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.911339) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.911952) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 09:58:30.927974) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-07-16 09:58:30.928294) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:58:31 INFO - MultiFileLogger online at 20170716 09:58:31 in /builds/slave/test 09:58:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:58:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:31 INFO - {'append_to_log': False, 09:58:31 INFO - 'base_work_dir': '/builds/slave/test', 09:58:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:58:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:31 INFO - 'branch': 'Firefox-Non-PGO', 09:58:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:31 INFO - 'code_coverage': False, 09:58:31 INFO - 'config_files': ('talos/linux_config.py',), 09:58:31 INFO - 'default_actions': ('clobber', 09:58:31 INFO - 'read-buildbot-config', 09:58:31 INFO - 'download-and-extract', 09:58:31 INFO - 'populate-webroot', 09:58:31 INFO - 'create-virtualenv', 09:58:31 INFO - 'install', 09:58:31 INFO - 'setup-mitmproxy', 09:58:31 INFO - 'run-tests'), 09:58:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:31 INFO - 'disable_ccov_upload': False, 09:58:31 INFO - 'download_minidump_stackwalk': True, 09:58:31 INFO - 'download_symbols': 'ondemand', 09:58:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:31 INFO - 'gecko_profile': False, 09:58:31 INFO - 'gecko_profile_interval': 0, 09:58:31 INFO - 'installer_path': 'installer.exe', 09:58:31 INFO - 'log_level': 'info', 09:58:31 INFO - 'log_name': 'talos', 09:58:31 INFO - 'log_to_console': True, 09:58:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:58:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:58:31 INFO - 'opt_config_files': (), 09:58:31 INFO - 'pip_index': False, 09:58:31 INFO - 'suite': 'dromaeojs-e10s', 09:58:31 INFO - 'system_bits': '32', 09:58:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:31 INFO - 'title': 'talos-linux64-ix-046', 09:58:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:31 INFO - 'use_talos_json': True, 09:58:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:31 INFO - 'work_dir': 'build'} 09:58:31 INFO - [mozharness: 2017-07-16 16:58:31.471866Z] Running clobber step. 09:58:31 INFO - Running pre-action listener: _resource_record_pre_action 09:58:31 INFO - Running main action method: clobber 09:58:31 INFO - rmtree: /builds/slave/test/build 09:58:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:32 INFO - Running post-action listener: _resource_record_post_action 09:58:32 INFO - [mozharness: 2017-07-16 16:58:32.511020Z] Finished clobber step (success) 09:58:32 INFO - [mozharness: 2017-07-16 16:58:32.511121Z] Running read-buildbot-config step. 09:58:32 INFO - Running pre-action listener: _resource_record_pre_action 09:58:32 INFO - Running main action method: read_buildbot_config 09:58:32 INFO - Using buildbot properties: 09:58:32 INFO - { 09:58:32 INFO - "project": "", 09:58:32 INFO - "product": "firefox", 09:58:32 INFO - "who": "ffxbld@noreply.mozilla.org", 09:58:32 INFO - "installer_path": "public/build/target.tar.bz2", 09:58:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:58:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:58:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:58:32 INFO - "stage_platform": "linux64", 09:58:32 INFO - "basedir": "/builds/slave/test", 09:58:32 INFO - "buildnumber": 1012, 09:58:32 INFO - "platform": "ubuntu64_hw", 09:58:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:58:32 INFO - "slavebuilddir": "test", 09:58:32 INFO - "taskId": "ILAqQwahSeudS3MqMl0WCQ", 09:58:32 INFO - "branch": "mozilla-central", 09:58:32 INFO - "script_repo_revision": "production", 09:58:32 INFO - "revision": "5f44d10bacca2d693413b529e0caadc73e634e1e", 09:58:32 INFO - "slavename": "talos-linux64-ix-046", 09:58:32 INFO - "repo_path": "mozilla-central" 09:58:32 INFO - } 09:58:32 INFO - Finding installer, test and symbols from parent task. 09:58:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ILAqQwahSeudS3MqMl0WCQ'}, attempt #1 09:58:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ILAqQwahSeudS3MqMl0WCQ'}, attempt #1 09:58:33 INFO - Task dependencies: UQpJi8ppT0atoWxfZMc-4A 09:58:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A'}, attempt #1 09:58:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2 09:58:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/test_packages.json 09:58:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.crashreporter-symbols.zip 09:58:33 INFO - Running post-action listener: _resource_record_post_action 09:58:33 INFO - [mozharness: 2017-07-16 16:58:33.861870Z] Finished read-buildbot-config step (success) 09:58:33 INFO - [mozharness: 2017-07-16 16:58:33.862090Z] Running download-and-extract step. 09:58:33 INFO - Running pre-action listener: _resource_record_pre_action 09:58:33 INFO - Running main action method: download_and_extract 09:58:33 INFO - mkdir: /builds/slave/test/build/tests 09:58:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:33 INFO - trying https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.test_packages.json 09:58:33 INFO - Downloading https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:58:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:58:34 INFO - Downloaded 989 bytes. 09:58:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:58:34 INFO - Using the following test package requirements: 09:58:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:58:34 INFO - u'common': [u'target.common.tests.zip'], 09:58:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:58:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:58:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:58:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:58:34 INFO - u'mozbase': [u'target.common.tests.zip'], 09:58:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:58:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:58:34 INFO - u'web-platform': [u'target.common.tests.zip', 09:58:34 INFO - u'target.web-platform.tests.tar.gz'], 09:58:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:58:34 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:58:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.common.tests.zip 09:58:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:58:34 INFO - Fetch https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.common.tests.zip into memory 09:58:37 INFO - Content-Length response header: 72753029 09:58:37 INFO - Bytes received: 72753029 09:58:48 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:58:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.common.tests.zip 09:58:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:58:48 INFO - Fetch https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.common.tests.zip into memory 09:58:49 INFO - Content-Length response header: 72753029 09:58:49 INFO - Bytes received: 72753029 09:59:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.talos.tests.zip 09:59:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:59:01 INFO - Fetch https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.talos.tests.zip into memory 09:59:02 INFO - Content-Length response header: 14495033 09:59:02 INFO - Bytes received: 14495033 09:59:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:02 INFO - trying https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2 09:59:02 INFO - Downloading https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:59:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:59:04 INFO - Downloaded 61890374 bytes. 09:59:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2 09:59:04 INFO - mkdir: /builds/slave/test/properties 09:59:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:04 INFO - Contents: 09:59:04 INFO - build_url:https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2 09:59:04 INFO - Running post-action listener: _resource_record_post_action 09:59:04 INFO - Running post-action listener: set_extra_try_arguments 09:59:04 INFO - [mozharness: 2017-07-16 16:59:04.637030Z] Finished download-and-extract step (success) 09:59:04 INFO - [mozharness: 2017-07-16 16:59:04.637236Z] Running populate-webroot step. 09:59:04 INFO - Running pre-action listener: _resource_record_pre_action 09:59:04 INFO - Running main action method: populate_webroot 09:59:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:04 INFO - '--tppagecycles', 09:59:04 INFO - '7'], 09:59:04 INFO - 'tests': ['tsvgm']}}, 09:59:04 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['damp', 'tps']}, 09:59:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'tests': ['damp', 'tps']}, 09:59:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['dromaeo_dom']}, 09:59:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:59:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:59:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:59:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['a11yr', 09:59:04 INFO - 'ts_paint', 09:59:04 INFO - 'tpaint', 09:59:04 INFO - 'sessionrestore', 09:59:04 INFO - 'sessionrestore_no_auto_restore', 09:59:04 INFO - 'tabpaint']}, 09:59:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:04 INFO - 'ts_paint', 09:59:04 INFO - 'tpaint', 09:59:04 INFO - 'sessionrestore', 09:59:04 INFO - 'sessionrestore_many_windows', 09:59:04 INFO - 'sessionrestore_no_auto_restore', 09:59:04 INFO - 'tabpaint']}, 09:59:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:04 INFO - 'ts_paint', 09:59:04 INFO - 'tpaint', 09:59:04 INFO - 'sessionrestore', 09:59:04 INFO - 'sessionrestore_many_windows', 09:59:04 INFO - 'sessionrestore_no_auto_restore', 09:59:04 INFO - 'tabpaint']}, 09:59:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:04 INFO - 'ts_paint', 09:59:04 INFO - 'tpaint', 09:59:04 INFO - 'sessionrestore', 09:59:04 INFO - 'sessionrestore_many_windows', 09:59:04 INFO - 'sessionrestore_no_auto_restore', 09:59:04 INFO - 'tabpaint']}, 09:59:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['a11yr', 09:59:04 INFO - 'ts_paint', 09:59:04 INFO - 'tpaint', 09:59:04 INFO - 'sessionrestore', 09:59:04 INFO - 'sessionrestore_no_auto_restore', 09:59:04 INFO - 'tabpaint']}, 09:59:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['a11yr', 09:59:04 INFO - 'ts_paint', 09:59:04 INFO - 'tpaint', 09:59:04 INFO - 'sessionrestore', 09:59:04 INFO - 'sessionrestore_no_auto_restore', 09:59:04 INFO - 'tabpaint']}, 09:59:04 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 09:59:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:59:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:59:04 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:59:04 INFO - 'talos_options': ['--mitmproxy', 09:59:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:59:04 INFO - '--firstNonBlankPaint'], 09:59:04 INFO - 'tests': ['quantum_pageload_google', 09:59:04 INFO - 'quantum_pageload_youtube', 09:59:04 INFO - 'quantum_pageload_amazon', 09:59:04 INFO - 'quantum_pageload_facebook']}, 09:59:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['tsvgx', 09:59:04 INFO - 'tsvgr_opacity', 09:59:04 INFO - 'tart', 09:59:04 INFO - 'tscrollx', 09:59:04 INFO - 'cart', 09:59:04 INFO - 'tsvg_static']}, 09:59:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:04 INFO - 'tsvgr_opacity', 09:59:04 INFO - 'tart', 09:59:04 INFO - 'tscrollx', 09:59:04 INFO - 'cart', 09:59:04 INFO - 'tsvg_static']}, 09:59:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'talos_options': ['--disable-e10s'], 09:59:04 INFO - 'tests': ['tp5o']}, 09:59:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:59:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'talos_options': ['--disable-e10s', 09:59:04 INFO - '--xperf_path', 09:59:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:04 INFO - 'tests': ['tp5n']}, 09:59:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:59:04 INFO - 'talos_options': ['--xperf_path', 09:59:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:04 INFO - 'tests': ['tp5n']}}, 09:59:04 INFO - 'talos.zip': {'path': '', 09:59:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:04 INFO - Running post-action listener: _resource_record_post_action 09:59:04 INFO - [mozharness: 2017-07-16 16:59:04.659446Z] Finished populate-webroot step (success) 09:59:04 INFO - [mozharness: 2017-07-16 16:59:04.659536Z] Running create-virtualenv step. 09:59:04 INFO - Running pre-action listener: _resource_record_pre_action 09:59:04 INFO - Running main action method: create_virtualenv 09:59:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:04 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 09:59:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:59:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:04 INFO - Using real prefix '/usr' 09:59:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:07 INFO - Installing pip.................done. 09:59:07 INFO - Return code: 0 09:59:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:59:07 INFO - Reading from file tmpfile_stdout 09:59:07 INFO - Output received: 09:59:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:59:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:07 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:59:07 INFO - Using env: {'DISPLAY': ':0', 09:59:07 INFO - 'HOME': '/home/cltbld', 09:59:07 INFO - 'LANG': 'en_US.UTF-8', 09:59:07 INFO - 'LANGUAGE': 'en_US:en', 09:59:07 INFO - 'LOGNAME': 'cltbld', 09:59:07 INFO - 'MAIL': '/var/mail/cltbld', 09:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:07 INFO - 'MOZ_NO_REMOTE': '1', 09:59:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:07 INFO - 'NO_EM_RESTART': '1', 09:59:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:07 INFO - 'PWD': '/builds/slave/test', 09:59:07 INFO - 'SHELL': '/bin/bash', 09:59:07 INFO - 'SHLVL': '1', 09:59:07 INFO - 'TERM': 'linux', 09:59:07 INFO - 'TMOUT': '86400', 09:59:07 INFO - 'USER': 'cltbld', 09:59:07 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:07 INFO - '_': '/tools/buildbot/bin/python'} 09:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:07 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 09:59:07 INFO - Cleaning up... 09:59:07 INFO - Return code: 0 09:59:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:07 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:59:07 INFO - Using env: {'DISPLAY': ':0', 09:59:07 INFO - 'HOME': '/home/cltbld', 09:59:07 INFO - 'LANG': 'en_US.UTF-8', 09:59:07 INFO - 'LANGUAGE': 'en_US:en', 09:59:07 INFO - 'LOGNAME': 'cltbld', 09:59:07 INFO - 'MAIL': '/var/mail/cltbld', 09:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:07 INFO - 'MOZ_NO_REMOTE': '1', 09:59:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:07 INFO - 'NO_EM_RESTART': '1', 09:59:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:07 INFO - 'PWD': '/builds/slave/test', 09:59:07 INFO - 'SHELL': '/bin/bash', 09:59:07 INFO - 'SHLVL': '1', 09:59:07 INFO - 'TERM': 'linux', 09:59:07 INFO - 'TMOUT': '86400', 09:59:07 INFO - 'USER': 'cltbld', 09:59:07 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:07 INFO - '_': '/tools/buildbot/bin/python'} 09:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:07 INFO - Downloading/unpacking psutil>=3.1.1 09:59:07 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 09:59:07 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 09:59:07 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 09:59:07 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 09:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:09 INFO - Installing collected packages: psutil 09:59:09 INFO - Running setup.py install for psutil 09:59:09 INFO - building 'psutil._psutil_linux' extension 09:59:09 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 09:59:09 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 09:59:09 INFO - building 'psutil._psutil_posix' extension 09:59:09 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 09:59:09 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 09:59:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:09 INFO - Successfully installed psutil 09:59:09 INFO - Cleaning up... 09:59:09 INFO - Return code: 0 09:59:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:09 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:59:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:59:09 INFO - Using env: {'DISPLAY': ':0', 09:59:09 INFO - 'HOME': '/home/cltbld', 09:59:09 INFO - 'LANG': 'en_US.UTF-8', 09:59:09 INFO - 'LANGUAGE': 'en_US:en', 09:59:09 INFO - 'LOGNAME': 'cltbld', 09:59:09 INFO - 'MAIL': '/var/mail/cltbld', 09:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:09 INFO - 'MOZ_NO_REMOTE': '1', 09:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:09 INFO - 'NO_EM_RESTART': '1', 09:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:09 INFO - 'PWD': '/builds/slave/test', 09:59:09 INFO - 'SHELL': '/bin/bash', 09:59:09 INFO - 'SHLVL': '1', 09:59:09 INFO - 'TERM': 'linux', 09:59:09 INFO - 'TMOUT': '86400', 09:59:09 INFO - 'USER': 'cltbld', 09:59:09 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:09 INFO - '_': '/tools/buildbot/bin/python'} 09:59:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59:09 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 09:59:09 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 09:59:09 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 09:59:09 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 09:59:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:59:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:59:11 INFO - Installing collected packages: mozsystemmonitor 09:59:11 INFO - Running setup.py install for mozsystemmonitor 09:59:11 INFO - Successfully installed mozsystemmonitor 09:59:11 INFO - Cleaning up... 09:59:11 INFO - Return code: 0 09:59:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:11 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:59:11 INFO - Using env: {'DISPLAY': ':0', 09:59:11 INFO - 'HOME': '/home/cltbld', 09:59:11 INFO - 'LANG': 'en_US.UTF-8', 09:59:11 INFO - 'LANGUAGE': 'en_US:en', 09:59:11 INFO - 'LOGNAME': 'cltbld', 09:59:11 INFO - 'MAIL': '/var/mail/cltbld', 09:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:11 INFO - 'MOZ_NO_REMOTE': '1', 09:59:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:11 INFO - 'NO_EM_RESTART': '1', 09:59:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:11 INFO - 'PWD': '/builds/slave/test', 09:59:11 INFO - 'SHELL': '/bin/bash', 09:59:11 INFO - 'SHLVL': '1', 09:59:11 INFO - 'TERM': 'linux', 09:59:11 INFO - 'TMOUT': '86400', 09:59:11 INFO - 'USER': 'cltbld', 09:59:11 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:11 INFO - '_': '/tools/buildbot/bin/python'} 09:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:59:11 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 09:59:11 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 09:59:11 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 09:59:11 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 09:59:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:59:12 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 09:59:12 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 09:59:12 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 09:59:12 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 09:59:12 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:12 INFO - warning: no files found matching '*.txt' 09:59:12 INFO - no previously-included directories found matching 'build' 09:59:12 INFO - no previously-included directories found matching 'dist' 09:59:12 INFO - no previously-included directories found matching '.git*' 09:59:12 INFO - Installing collected packages: jsonschema, functools32 09:59:12 INFO - Running setup.py install for functools32 09:59:12 INFO - warning: no files found matching '*.txt' 09:59:12 INFO - no previously-included directories found matching 'build' 09:59:12 INFO - no previously-included directories found matching 'dist' 09:59:12 INFO - no previously-included directories found matching '.git*' 09:59:12 INFO - Successfully installed jsonschema functools32 09:59:12 INFO - Cleaning up... 09:59:12 INFO - Return code: 0 09:59:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:12 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:59:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:59:12 INFO - Using env: {'DISPLAY': ':0', 09:59:12 INFO - 'HOME': '/home/cltbld', 09:59:12 INFO - 'LANG': 'en_US.UTF-8', 09:59:12 INFO - 'LANGUAGE': 'en_US:en', 09:59:12 INFO - 'LOGNAME': 'cltbld', 09:59:12 INFO - 'MAIL': '/var/mail/cltbld', 09:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:12 INFO - 'MOZ_NO_REMOTE': '1', 09:59:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:12 INFO - 'NO_EM_RESTART': '1', 09:59:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:12 INFO - 'PWD': '/builds/slave/test', 09:59:12 INFO - 'SHELL': '/bin/bash', 09:59:12 INFO - 'SHLVL': '1', 09:59:12 INFO - 'TERM': 'linux', 09:59:12 INFO - 'TMOUT': '86400', 09:59:12 INFO - 'USER': 'cltbld', 09:59:12 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:12 INFO - '_': '/tools/buildbot/bin/python'} 09:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:59:13 INFO - Cleaning up... 09:59:13 INFO - Return code: 0 09:59:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:13 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 09:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:59:13 INFO - Using env: {'DISPLAY': ':0', 09:59:13 INFO - 'HOME': '/home/cltbld', 09:59:13 INFO - 'LANG': 'en_US.UTF-8', 09:59:13 INFO - 'LANGUAGE': 'en_US:en', 09:59:13 INFO - 'LOGNAME': 'cltbld', 09:59:13 INFO - 'MAIL': '/var/mail/cltbld', 09:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:13 INFO - 'MOZ_NO_REMOTE': '1', 09:59:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:13 INFO - 'NO_EM_RESTART': '1', 09:59:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:13 INFO - 'PWD': '/builds/slave/test', 09:59:13 INFO - 'SHELL': '/bin/bash', 09:59:13 INFO - 'SHLVL': '1', 09:59:13 INFO - 'TERM': 'linux', 09:59:13 INFO - 'TMOUT': '86400', 09:59:13 INFO - 'USER': 'cltbld', 09:59:13 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:13 INFO - '_': '/tools/buildbot/bin/python'} 09:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:13 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 09:59:13 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 09:59:13 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 09:59:13 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 09:59:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:14 INFO - Downloading docopt-0.6.1.tar.gz 09:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:15 INFO - Running setup.py install for blobuploader 09:59:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:15 INFO - Running setup.py install for requests 09:59:15 INFO - Running setup.py install for docopt 09:59:15 INFO - Successfully installed blobuploader requests docopt 09:59:15 INFO - Cleaning up... 09:59:15 INFO - Return code: 0 09:59:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:15 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:15 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:59:15 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:15 INFO - Using env: {'DISPLAY': ':0', 09:59:15 INFO - 'HOME': '/home/cltbld', 09:59:15 INFO - 'LANG': 'en_US.UTF-8', 09:59:15 INFO - 'LANGUAGE': 'en_US:en', 09:59:15 INFO - 'LOGNAME': 'cltbld', 09:59:15 INFO - 'MAIL': '/var/mail/cltbld', 09:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:15 INFO - 'MOZ_NO_REMOTE': '1', 09:59:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:15 INFO - 'NO_EM_RESTART': '1', 09:59:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:15 INFO - 'PWD': '/builds/slave/test', 09:59:15 INFO - 'SHELL': '/bin/bash', 09:59:15 INFO - 'SHLVL': '1', 09:59:15 INFO - 'TERM': 'linux', 09:59:15 INFO - 'TMOUT': '86400', 09:59:15 INFO - 'USER': 'cltbld', 09:59:15 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:15 INFO - '_': '/tools/buildbot/bin/python'} 09:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:15 INFO - Running setup.py (path:/tmp/pip-PvxZNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:15 INFO - Running setup.py (path:/tmp/pip-jcnqdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:16 INFO - Running setup.py (path:/tmp/pip-w0LLyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:16 INFO - Running setup.py (path:/tmp/pip-8XnwHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:16 INFO - Running setup.py (path:/tmp/pip-yxmMNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:16 INFO - Running setup.py (path:/tmp/pip-2L2Bfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:16 INFO - Running setup.py (path:/tmp/pip-Zaa4Oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:16 INFO - Running setup.py (path:/tmp/pip-Hbj33B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:16 INFO - Running setup.py (path:/tmp/pip-szMvc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:16 INFO - Running setup.py (path:/tmp/pip-iNuHL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:16 INFO - Running setup.py (path:/tmp/pip-GrN_uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:16 INFO - Running setup.py (path:/tmp/pip-sKUmfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:16 INFO - Running setup.py (path:/tmp/pip-iR9Qc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:16 INFO - Running setup.py (path:/tmp/pip-gxvdYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:16 INFO - Running setup.py (path:/tmp/pip-s5JdxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:16 INFO - Running setup.py (path:/tmp/pip-lfy24P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:17 INFO - Running setup.py (path:/tmp/pip-9U9IQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:59:17 INFO - Running setup.py install for manifestparser 09:59:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Running setup.py install for mozcrash 09:59:17 INFO - Running setup.py install for mozdebug 09:59:17 INFO - Running setup.py install for mozdevice 09:59:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Running setup.py install for mozfile 09:59:17 INFO - Running setup.py install for mozhttpd 09:59:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Running setup.py install for mozinfo 09:59:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Running setup.py install for mozInstall 09:59:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:59:17 INFO - Running setup.py install for mozleak 09:59:17 INFO - Running setup.py install for mozlog 09:59:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Running setup.py install for moznetwork 09:59:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Running setup.py install for mozprocess 09:59:18 INFO - Running setup.py install for mozprofile 09:59:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Running setup.py install for mozrunner 09:59:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Running setup.py install for mozscreenshot 09:59:18 INFO - Running setup.py install for moztest 09:59:18 INFO - Running setup.py install for mozversion 09:59:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:59:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:59:18 INFO - Cleaning up... 09:59:18 INFO - Return code: 0 09:59:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:18 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:18 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:59:18 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:18 INFO - Using env: {'DISPLAY': ':0', 09:59:18 INFO - 'HOME': '/home/cltbld', 09:59:18 INFO - 'LANG': 'en_US.UTF-8', 09:59:18 INFO - 'LANGUAGE': 'en_US:en', 09:59:18 INFO - 'LOGNAME': 'cltbld', 09:59:18 INFO - 'MAIL': '/var/mail/cltbld', 09:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:18 INFO - 'MOZ_NO_REMOTE': '1', 09:59:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:18 INFO - 'NO_EM_RESTART': '1', 09:59:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:18 INFO - 'PWD': '/builds/slave/test', 09:59:18 INFO - 'SHELL': '/bin/bash', 09:59:18 INFO - 'SHLVL': '1', 09:59:18 INFO - 'TERM': 'linux', 09:59:18 INFO - 'TMOUT': '86400', 09:59:18 INFO - 'USER': 'cltbld', 09:59:18 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:18 INFO - '_': '/tools/buildbot/bin/python'} 09:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:19 INFO - Running setup.py (path:/tmp/pip-A_tl1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:19 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:19 INFO - Running setup.py (path:/tmp/pip-s7fZT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:19 INFO - Running setup.py (path:/tmp/pip-5xZn86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:19 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:19 INFO - Running setup.py (path:/tmp/pip-oeP8mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:19 INFO - Running setup.py (path:/tmp/pip-nbcd3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:19 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:19 INFO - Running setup.py (path:/tmp/pip-T2lSxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:19 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:19 INFO - Running setup.py (path:/tmp/pip-zlDy4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:19 INFO - Running setup.py (path:/tmp/pip-4ruYNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:19 INFO - Running setup.py (path:/tmp/pip-odTvc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:19 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:19 INFO - Running setup.py (path:/tmp/pip-SukzPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:19 INFO - Running setup.py (path:/tmp/pip-fDyesx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:19 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:19 INFO - Running setup.py (path:/tmp/pip-dl33eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 09:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:19 INFO - Running setup.py (path:/tmp/pip-enYwEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:20 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)) 09:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:20 INFO - Running setup.py (path:/tmp/pip-L4c296-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 09:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:20 INFO - Running setup.py (path:/tmp/pip-v9YeLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:20 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)) 09:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:20 INFO - Running setup.py (path:/tmp/pip-YNlC9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 09:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:20 INFO - Running setup.py (path:/tmp/pip-f8HdwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:20 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)) 09:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:59:20 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)) 09:59:20 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:59:20 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:59:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59:20 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 09:59:20 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 09:59:20 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 09:59:20 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 09:59:21 INFO - Downloading blessings-1.6.tar.gz 09:59:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:59:21 INFO - Installing collected packages: blessings 09:59:21 INFO - Running setup.py install for blessings 09:59:21 INFO - Successfully installed blessings 09:59:21 INFO - Cleaning up... 09:59:21 INFO - Return code: 0 09:59:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:21 INFO - Reading from file tmpfile_stdout 09:59:21 INFO - Current package versions: 09:59:21 INFO - argparse == 1.2.1 09:59:21 INFO - blessings == 1.6 09:59:21 INFO - blobuploader == 1.2.4 09:59:21 INFO - docopt == 0.6.1 09:59:21 INFO - functools32 == 3.2.3-2 09:59:21 INFO - jsonschema == 2.5.1 09:59:21 INFO - manifestparser == 1.1 09:59:21 INFO - mozInstall == 1.13 09:59:21 INFO - mozcrash == 1.0 09:59:21 INFO - mozdebug == 0.1 09:59:21 INFO - mozdevice == 0.51 09:59:21 INFO - mozfile == 1.2 09:59:21 INFO - mozhttpd == 0.7 09:59:21 INFO - mozinfo == 0.10 09:59:21 INFO - mozleak == 0.1 09:59:21 INFO - mozlog == 3.5 09:59:21 INFO - moznetwork == 0.27 09:59:21 INFO - mozprocess == 0.25 09:59:21 INFO - mozprofile == 0.28 09:59:21 INFO - mozrunner == 6.13 09:59:21 INFO - mozscreenshot == 0.1 09:59:21 INFO - mozsystemmonitor == 0.3 09:59:21 INFO - moztest == 0.8 09:59:21 INFO - mozversion == 1.4 09:59:21 INFO - psutil == 3.1.1 09:59:21 INFO - requests == 1.2.3 09:59:21 INFO - wsgiref == 0.1.2 09:59:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:21 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:21 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:59:21 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:59:21 INFO - Using env: {'DISPLAY': ':0', 09:59:21 INFO - 'HOME': '/home/cltbld', 09:59:21 INFO - 'LANG': 'en_US.UTF-8', 09:59:21 INFO - 'LANGUAGE': 'en_US:en', 09:59:21 INFO - 'LOGNAME': 'cltbld', 09:59:21 INFO - 'MAIL': '/var/mail/cltbld', 09:59:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:21 INFO - 'MOZ_NO_REMOTE': '1', 09:59:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:21 INFO - 'NO_EM_RESTART': '1', 09:59:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:21 INFO - 'PWD': '/builds/slave/test', 09:59:21 INFO - 'SHELL': '/bin/bash', 09:59:21 INFO - 'SHLVL': '1', 09:59:21 INFO - 'TERM': 'linux', 09:59:21 INFO - 'TMOUT': '86400', 09:59:21 INFO - 'USER': 'cltbld', 09:59:21 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:21 INFO - '_': '/tools/buildbot/bin/python'} 09:59:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 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)) 09:59:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:59:22 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 09:59:22 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 09:59:22 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 09:59:22 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 09:59:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:59:23 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:59:23 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 09:59:23 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 09:59:23 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 09:59:23 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 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:59:23 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)) 09:59:23 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)) 09:59:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:59:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:59:23 INFO - Installing collected packages: marionette-driver, simplejson 09:59:23 INFO - Running setup.py install for marionette-driver 09:59:23 INFO - Running setup.py install for simplejson 09:59:23 INFO - building 'simplejson._speedups' extension 09:59:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 09:59:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 09:59:24 INFO - Successfully installed marionette-driver simplejson 09:59:24 INFO - Cleaning up... 09:59:24 INFO - Return code: 0 09:59:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:24 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/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 0x26261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27f4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2997b90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, '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': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', '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 09:59:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:59:24 INFO - Using env: {'DISPLAY': ':0', 09:59:24 INFO - 'HOME': '/home/cltbld', 09:59:24 INFO - 'LANG': 'en_US.UTF-8', 09:59:24 INFO - 'LANGUAGE': 'en_US:en', 09:59:24 INFO - 'LOGNAME': 'cltbld', 09:59:24 INFO - 'MAIL': '/var/mail/cltbld', 09:59:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:24 INFO - 'MOZ_NO_REMOTE': '1', 09:59:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:24 INFO - 'NO_EM_RESTART': '1', 09:59:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:24 INFO - 'PWD': '/builds/slave/test', 09:59:24 INFO - 'SHELL': '/bin/bash', 09:59:24 INFO - 'SHLVL': '1', 09:59:24 INFO - 'TERM': 'linux', 09:59:24 INFO - 'TMOUT': '86400', 09:59:24 INFO - 'USER': 'cltbld', 09:59:24 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:24 INFO - '_': '/tools/buildbot/bin/python'} 09:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:59:24 INFO - Cleaning up... 09:59:24 INFO - Return code: 0 09:59:24 INFO - Running post-action listener: _resource_record_post_action 09:59:24 INFO - Running post-action listener: _start_resource_monitoring 09:59:24 INFO - Starting resource monitoring. 09:59:24 INFO - [mozharness: 2017-07-16 16:59:24.928864Z] Finished create-virtualenv step (success) 09:59:24 INFO - [mozharness: 2017-07-16 16:59:24.929445Z] Running install step. 09:59:24 INFO - Running pre-action listener: _resource_record_pre_action 09:59:24 INFO - Running main action method: install 09:59:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:25 INFO - Reading from file tmpfile_stdout 09:59:25 INFO - Detecting whether we're running mozinstall >=1.0... 09:59:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:59:25 INFO - Reading from file tmpfile_stdout 09:59:25 INFO - Output received: 09:59:25 INFO - Usage: mozinstall [options] installer 09:59:25 INFO - Options: 09:59:25 INFO - -h, --help show this help message and exit 09:59:25 INFO - -d DEST, --destination=DEST 09:59:25 INFO - Directory to install application into. [default: 09:59:25 INFO - "/builds/slave/test"] 09:59:25 INFO - --app=APP Application being installed. [default: firefox] 09:59:25 INFO - mkdir: /builds/slave/test/build/application 09:59:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:59:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:59:43 INFO - Reading from file tmpfile_stdout 09:59:43 INFO - Output received: 09:59:43 INFO - /builds/slave/test/build/application/firefox/firefox 09:59:43 INFO - Running post-action listener: _resource_record_post_action 09:59:43 INFO - [mozharness: 2017-07-16 16:59:43.197670Z] Finished install step (success) 09:59:43 INFO - [mozharness: 2017-07-16 16:59:43.197925Z] Running setup-mitmproxy step. 09:59:43 INFO - Running pre-action listener: _resource_record_pre_action 09:59:43 INFO - Running main action method: setup_mitmproxy 09:59:43 INFO - Skipping: mitmproxy is not required 09:59:43 INFO - Running post-action listener: _resource_record_post_action 09:59:43 INFO - [mozharness: 2017-07-16 16:59:43.199072Z] Finished setup-mitmproxy step (success) 09:59:43 INFO - [mozharness: 2017-07-16 16:59:43.199293Z] Running run-tests step. 09:59:43 INFO - Running pre-action listener: _resource_record_pre_action 09:59:43 INFO - Running pre-action listener: _set_gcov_prefix 09:59:43 INFO - Running main action method: run_tests 09:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:59:43 INFO - Python 2.7.3 09:59:43 INFO - Return code: 0 09:59:43 INFO - grabbing minidump binary from tooltool 09:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:43 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 0x2994d10>, '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 0x2995c10>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29960c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:59:43 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 09:59:43 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 09:59:43 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 09:59:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:59:43 INFO - Return code: 0 09:59:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:59:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:59:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:59:43 INFO - ENV: RUST_BACKTRACE is now 1 09:59:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:59:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:59:43 INFO - Using env: {'DISPLAY': ':0', 09:59:43 INFO - 'HOME': '/home/cltbld', 09:59:43 INFO - 'LANG': 'en_US.UTF-8', 09:59:43 INFO - 'LANGUAGE': 'en_US:en', 09:59:43 INFO - 'LOGNAME': 'cltbld', 09:59:43 INFO - 'MAIL': '/var/mail/cltbld', 09:59:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:59:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:43 INFO - 'MOZ_NO_REMOTE': '1', 09:59:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:59:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:43 INFO - 'NO_EM_RESTART': '1', 09:59:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:43 INFO - 'PWD': '/builds/slave/test', 09:59:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:59:43 INFO - 'RUST_BACKTRACE': '1', 09:59:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:59:43 INFO - 'SHELL': '/bin/bash', 09:59:43 INFO - 'SHLVL': '1', 09:59:43 INFO - 'TERM': 'linux', 09:59:43 INFO - 'TMOUT': '86400', 09:59:43 INFO - 'USER': 'cltbld', 09:59:43 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326', 09:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:43 INFO - '_': '/tools/buildbot/bin/python'} 09:59:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:59:43 INFO - mozversion application_buildid: 20170716161811 09:59:43 INFO - mozversion application_changeset: 5f44d10bacca2d693413b529e0caadc73e634e1e 09:59:43 INFO - mozversion application_display_name: Nightly 09:59:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:59:43 INFO - mozversion application_name: Firefox 09:59:43 INFO - mozversion application_remotingname: firefox 09:59:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:59:43 INFO - mozversion application_vendor: Mozilla 09:59:43 INFO - mozversion application_version: 56.0a1 09:59:43 INFO - mozversion platform_buildid: 20170716161811 09:59:43 INFO - mozversion platform_changeset: 5f44d10bacca2d693413b529e0caadc73e634e1e 09:59:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:59:43 INFO - mozversion platform_version: 56.0a1 09:59:43 INFO - using testdate: 1500224383 09:59:43 INFO - actual date: 1500224383 09:59:43 INFO - starting webserver on 'localhost:47561' 09:59:43 INFO - SUITE-START | Running 2 tests 09:59:43 INFO - TEST-START | dromaeo_css 09:59:43 INFO - Initialising browser for dromaeo_css test... 09:59:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47561/getInfo.html -profile /tmp/tmp6w68nL/profile 09:59:43 INFO - TEST-INFO | started process 32132 (/builds/slave/test/build/application/firefox/firefox http://localhost:47561/getInfo.html) 09:59:49 INFO - TEST-INFO | 32132: exit 0 09:59:49 INFO - Browser initialized. 09:59:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:59:49 INFO - TEST-INFO | started process 32337 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6w68nL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:01 INFO - PID 32337 | RSS: Main: 167215104 10:00:01 INFO - PID 32337 | 10:02:58 INFO - PID 32337 | Cycle 1(1): loaded http://localhost:47561/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47561/tests/dromaeo/cssquery-ext.html) 10:03:00 INFO - PID 32337 | RSS: Main: 175529984 10:03:00 INFO - PID 32337 | 10:06:02 INFO - PID 32337 | Cycle 1(1): loaded http://localhost:47561/tests/dromaeo/cssquery-ext.html (next: http://localhost:47561/tests/dromaeo/cssquery-jquery.html) 10:06:02 INFO - PID 32337 | RSS: Main: 173645824 10:06:02 INFO - PID 32337 | 10:09:04 INFO - PID 32337 | Cycle 1(1): loaded http://localhost:47561/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47561/tests/dromaeo/cssquery-mootools.html) 10:09:04 INFO - PID 32337 | RSS: Main: 175992832 10:09:04 INFO - PID 32337 | 10:12:06 INFO - PID 32337 | Cycle 1(1): loaded http://localhost:47561/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47561/tests/dromaeo/cssquery-prototype.html) 10:12:07 INFO - PID 32337 | RSS: Main: 175022080 10:12:07 INFO - PID 32337 | 10:15:10 INFO - PID 32337 | Cycle 1(1): loaded http://localhost:47561/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47561/tests/dromaeo/cssquery-yui.html) 10:15:11 INFO - PID 32337 | RSS: Main: 174403584 10:15:11 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | Cycle 1(1): loaded http://localhost:47561/tests/dromaeo/cssquery-yui.html (next: http://localhost:47561/tests/dromaeo/cssquery-dojo.html) 10:18:12 INFO - PID 32337 | RSS: Main: 177700864 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | __start_tp_report 10:18:12 INFO - PID 32337 | _x_x_mozilla_page_load 10:18:12 INFO - PID 32337 | _x_x_mozilla_page_load_details 10:18:12 INFO - PID 32337 | |i|pagename|runs| 10:18:12 INFO - PID 32337 | |0;dojo.html;2987.686292939243;3055.358374741304;3081.6764239754825;3084.6606873243945;3093.9535906961387;5908.231929849119;5923.407659234074;5942.61373010754;5976.043832986723;5989.490893274072;5990.400959904007;6031.035034394497;6036.788712395067;6042.244719410074;6043.365446628107;5494.862628434281;5523.6685798851995;5529.917051244234;5531.3915469298345;5547.057000309957;5501.202325662776;5540;5553.055980483307;5554.361248456418;5558.277423934883;5550.69471179085;5552.028395030853;5559;5559.666420014792;5566.693831839248;5554.805581804638;5557.221988921554;5561.63849349791;5564.10974244123;5567.276254086943;5424.321959755031;5490.6980116094;5499.257600223958;5529.281193444866;5602.655362712961;5967.433093856077;6010.639361638316;6014.699265036731;6068.241469816273;6080.905437021334;6278.686065696743;6376.713047912809;6435.388638079422;6442.613443193377;6492.9935859941315;6465.967670161619;6506.869862602769;6510.316416776265;6539.836504087389;6555.737770489175;6008.128821320927;6041.124037014608;6044.032954727222;6051.273847138327;6057.091436284549;5674.631148975303;6592.340765923465;6604.5046621502615;6659.101021362054;6725.361090696376;6300.1494798202575;6319.431251187323;6364.681765911686;6389.744410223632;6390.8721825563225;6307.5269354798575;6429.3570642936265;6436.581622194542;6493.967530162414;6498.902516462257;6134.754609815646;6229.065640154013;6233.2208473940755;6323.588966717149;6395.904061439082;6329.177206963065;6358.491320694333;6374.713137908805;6425.228972523234;6510.283868774431;5102.566281866083;5180.404253510895;5197.870053248624;5264.578833693372;5269.578433725293;4441.244988351923;4454.821807127679;4463.24124898768;4486.641068714495;4488.483824360175;5505.449455054542;5601.663900166003;5615.382307946123;5621.831345059655;5640.633358831663;6523.8695226095215;6535.0850880876715;6540.738370465223;6552.967235163794;6559.770408035696;5519.530839878656;5537.972310138423;5606.85982850432;5619.634723742944;5634.746436410372;5575.721213939287;5676.971615141898;5679.091345384719;5724.799632012943;5738.942610573924;6158.661273629904;6175.84560385994;6209.037599172136;6226.844328891814;6238.313785483593;5330.920036199538;6341.6195028297525;6463.321351258238;6501.447376972821;6573.901391479228;6253.593516421418;6286.811395658137;6337.176167098248;6359.459445947146;6365.96817015912;6347.333529979285;6366.395192456709;6425.935740642533;6431.163948686641;6452.483801295791;6364.427201551883;6457.192850893638;6501.284858665448;6505.804825855232;6588.143541339787;6216.751329946752;6259.843503912257;6262.091996660368;6281.152044473937;6295.748170073146;6231.40801623845;6263.812085637438;6278.591891527036;6284.811455656338;6292.339304372975;6160.075988601746;6171.660558669317;6187.102870083722;6211.472024877936;6241.906371404523;5427.701476418835;6353.110564520878;6371.39471750183;6409.070684750778;6413.743450261937;6078.3921607838865;6103.298120716175;6122.571420000557;6169.5064394849205;6173.629582224901 10:18:12 INFO - PID 32337 | |1;ext.html;718.6945548142041;763.3053920931953;785.685725709716;803.1927912447987;812.8943237379143;41325.79337103314;41854.37218441718;42036;42090.57909420909;42096.57903420969;38270.617293827054;38376.80811595945;38469.61530384696;38500.42249366255;38520.614793852124;6877.346652068046;6992.265812089734;6998.615076170809;7007.299270072977;7019.052427922216;6818.488613353993;6944.02783610295;6948.3746462818335;6954.826129346756;6979.616121113337;6923.723051077976;6925.3074692530845;6929.7574584889535;6970;7562.659680314399;7715.6527956241825;7757.224277572198;7771.10632277284;7773.805654858617;7788.4937479063965;7541.132769731496;7551.924480755232;7568.318851303409;7585.506942048804;7591.810204744926;37925.62074379248;37943.4308485373;38189.80905095484;38410.615893840986;38533.42199867004;3451.6375780543185;3474.9826250868837;3492.965070349315;3498.387782138141;3500.177458297298;31283.217919552193;31457.528137077734;31675.524867127013;31687.36625267505;31705.682943170505;4803.423589169287;4852.684575502581;4865.172920603471;4892.584130348924;4924.015196960622;186465;188828;218359;220693;221685;48221.75889120532;48508.5149148504;48509;48509.27236091461;48530.757346213046;50231;50280.497195028314;50369.24446133311;50435.49564504381;50467;16021;16138.193090345436;16161.4343497977;16179.191040447931;16219;13394.464221431228;13408.19550826934;13408.932955335356;13429.462821487226;13469.057165998482;14259.144451332953;14301.213433261271;14308.427662893577;14308.57074287773;14558.199299038442;5811.883762324815;5813.563982701315;5846.795362162389;5847.386024467455;5925.674087925119;976.3946353261024;985.980280394388;996.0637001218831;1033.4625994482826;1034.8395998620226;22732.886335568546;22848.44244281739;23103.653445198335;23732.762672373054;23862;1073.313079629038;1107.1087774341731;1110.6612483192703;1120.2998126171144;1124.6738445850776;12008.579699710468;12115.757684846254;12193.93903030497;12265;12365.443829504808;220655;224026;224660;225412;226502;53792.462075378746;54066.72966635221;54283;54351;54472.45527544753;1358.2054498118514;1372.0327169345587;1375.9931200343933;1495.6933828565336;1515.7423238049557;27583.586246205923;27605.585916211676;27619.86190069037;27828.72171278261;27948.58077128804;36881.63118368782;37180.44229336508;37252.25495490129;37286;37300.81349593234;171202.14398927926;172160;172448;173776;173924;35375.64624353724;35430.29139417249;35619.10952226215;35626.8218658905;35923.281534369686;41675;42569.57430425656;43051;43174.568254317055;44147.55852441563;30465.238369040064;30908.22729431782;31922.680773191973;32351.191220219684;32469.18827029343;31109.53335699921;31928.52107218347;32058.67941320557;32155.356892861542;32209.67790322067;34999.82500087483;35565;35778;35792.64207357893;35942;30470.84764576163;30638.693613063584;30927.69072309248;31422.214444639067;31611.84194079015;22170.89376509229;23177.88411057934;23316.417089572897;23554.411139721644;23608.29175124749 10:18:12 INFO - PID 32337 | |2;jquery.html;569.3992837556376;570.7374607680467;571.5656101362965;578.8003138917073;590.6072461812895;21467.03398347074;21546;21637.3508794736;21656.458588535275;21748.782512174872;23887.880560597216;24848.50302993939;26150.73849261507;26285;26498.602520962202;6452.193475815535;6647.50143739219;6651.135352404205;6675.966620166892;6677.966610166966;6393.392627700384;6542.705578248991;6559.93440065598;6578.671066446683;6579.736810527573;5842.503387212071;6383.648899310536;6390.712417941204;6537.64042977636;6548.214214294291;6241.28225254097;6319.74721011159;6362.872742545146;6368.235811702625;6394.488440924715;5966.820995370146;5980.401959803985;5983.6110652807865;5990.8502287442725;5994.940050599482;26718.732812672013;28548.286292842637;28676.426471470662;28854.855725721445;29157.56263656047;1701.8808683392167;1906.075553356621;1917.9328723494611;1930.5270208798934;1932.4202739178331;18240.726389104173;18313.3590324338;18455.35406260788;18572.81427185738;18578.349757758548;2200.8459407841456;2275.0899640143907;2277.430642339415;2305.5273668897835;2363.8936247868887;136701.3164934169;138256;140970;141056;141281;14287.357068931922;14781.26093695311;15075;15076.321565529577;15080.622984425258;23319.300420987398;23371;23426.18008369699;23439.531209375717;23483.647745283834;10148.441835698959;10170.898291016994;10172.542235599416;10437;10573.9471302643;9633.518324083769;9731.367461115005;9847.80304393908;9853.950730246446;9863.654772083086;9080.727578172666;9155.313351498664;9276.907230927603;9451.149396554205;9613.807723845484;2418.322869596516;2673.9866300668373;2680.2361327021704;2770.224337185591;2788.8326700398043;958.5446912716404;966.468442356701;972.3728195313982;987.7234374375187;992.1963209800085;19288.324908628132;19688.507787305432;19843.603127937648;20194.29319973794;20207;1012.8885822559513;1041.7916416716687;1059.1262208677872;1063.914886809069;1064.387976913278;10786.784264314827;10791.892081079088;10898.727531811768;10957.287776294675;11282.266652667551;448119;464993;466743;467856;479017;106908;110140.449297753;111585;111959;117431.41284293524;1255.9811602825964;1268.315109840694;1302.1937724204354;1307.1046333261745;1311.3836496846468;19879;20654.277100301417;20734.377968660963;20997;21080.472988175417;57191;59751.402485974584;60017.69991150016;60235;60537;72854;73183.63408182925;73841;73937.26062739304;74279.2572074294;14217.786733198804;14559;14954.327055282321;15047.623809404415;15085.24573771329;23391;23423;23624.881875590512;23654.645180322652;24020.63969040501;18149.546261343574;18378.816211838246;18402.35591754282;18506.81493185051;18547.44357669272;16416.343346266014;16518.83481165221;16963.406280780113;17027.82972170312;17410;18753;18954.52613684669;19068.618627627646;19080;19090.23639054478;14615.853841461449;14632.268386580841;14671.486497972519;14697.265136743334;14761;18281.725774113664;18480.537986550444;18547.258109675462;18619.534511637317;18786.154623041723 10:18:12 INFO - PID 32337 | |3;mootools.html;1337.8862796662286;1400.5588239704493;1403.0879928046775;1404.1715387921129;1407.0853944935784;13246.933765331172;13981.161130332188;14054.718905621894;14056.086354386958;14081.788773168411;13268.137571057898;13669.248191349472;13760.174389536634;13768.31158442079;13846.515371961983;2593.2220333899895;2665.2270841455957;2685.140754958414;2685.5703087506004;2695.663042119735;2328.231683544435;2402.5915594349003;2403.6514705367713;2406.278116565032;2408.5182963407387;2442.2795275393714;2481.0323973650293;2489.278109348296;2489.626556016594;2497.463045445229;2472.938176545583;2480.776730094282;2486.76375744304;2503.336615796806;2506.5738824399896;2420.189236605739;2427.963580546293;2436.8781560921884;2449.473363226915;2451.7548245175517;13190.53833115846;13412.396442160029;13456.394462249222;13458.52895148665;13479.66300842477;7304.342609165201;7613.934049233112;7618.8476230475635;7633.923660763377;7664.271894170045;4975.664050709311;5141.357330333708;5160.974195129038;5233.136532472137;5274.604404669665;1912.3498010676299;1913.894735789531;1918.7026010968323;1976.505873531617;1980.9801901981084;20679.689804652942;21449.463763405885;21497;21733.891330543247;21801.127954881787;5653.293338332708;5821.94178058214;6162.291336496361;6307.432331090224;6354.3010268870385;15475.613109672347;15609;15706.214689265491;15725.44960926362;15746.9212653936;7172.748953786592;7357.153927298322;7397.075365579302;7405.925940740632;7415.88876166858;6819.727210911508;6823.897641535381;6852.040714300002;6870.690818913161;6942;4789.544993225638;4986.501349865057;5003.149464591028;5073.7716802743225;5074.188129899198;1488.9925550372395;1491.7240310542584;1500.5873384819263;1500.8274048484345;1518.6507103366066;875.5228400521731;890.425675439338;913.8263729891432;915.5742579700338;934.345957829522;8001.479903806234;8049.27556519916;8050.235227653364;8061.596920154087;8108.270255677017;704.5314865614412;710.4671496377716;731.9817004574821;742.6286856571714;748.5321673953779;4446.199684056901;4469.776511174428;4472.597466228055;4505.864824055284;4509.661775366861;17639.73540396895;17815.931044137393;18418.447446576625;18459.723104153447;18794.436166915013;17876.55308617295;18105.456836294932;18342.082895855154;18417;18876.528086797938;10602.522886470128;10675;10828.891711082946;10882.564697412174;10993.395363255098;7860.253275938777;8153.22544358285;8199.467034642728;8210;8320.542370169698;15387.923060385074;16017.839821602101;16036.67926641484;16308.510744677678;16465.094419807025;15098.471553496014;16385.836141638432;16473.588160296087;16492.75260871112;16716.164191790118;11298.60454884075;11585.304881706787;11627.011704005254;11630.88369116298;11659.125565582446;12459.626211213677;12673.366331683563;12742.171758835646;12748.4263208154;12913.289769062792;10432.426216558162;10480.63317783874;10574.8413773795;10676.62631807883;10779.083777878968;9864.457454840052;10134.594616215269;10236.488175591041;10256.230782691477;10501.737456563647;8875.556222188994;9006.54967251648;9009.684661036832;9014.504202268676;9015.053419391132;7061.152661680631;7123.501354905107;7170.0678911743225;7220.711171553079;7228;7055.38401873487;7106.147262328347;7262.092238470192;7264;7381.767035422497 10:18:12 INFO - PID 32337 | |4;prototype.html;486.8039340550483;497.37306420142625;508.40516595583165;515.9742012899356;516.4345042178815;6006.189164462796;6026.367231440701;6120.378099803555;6173.444390004899;6185;5830.105215804859;5885.468718768737;5991.610545314563;6054.636721796695;6076.210092687956;4874.756262186894;4888.682235654671;4908.386451693538;4937.333459982892;4963.783872951114;4836.661433699643;4850.927236091461;4900.848300649344;4930.359053323063;4955.132851750947;4819.770958405602;4900.877478063041;4906.533879281463;4927.950720492785;4930.4330002049865;4679.087577922306;4806.8077276909235;4864.246041863482;4896.192128298826;4898.583620392271;4331.480222373335;4332.805023773906;4360.231253154238;4377.606015458624;4399.956000439986;5837.445442682938;5891.381404952461;5932.110183472513;5965.13505541694;5966.70166491678;5524.254225679522;5528.861778455531;5587.692676902769;5594.524465420444;5613.21415001896;6308.716107775161;6336.651484168369;6351.079093531458;6376.202974628171;6445.291017988017;4722.197226471474;4753.36009076868;4766.571008609207;4784.760761961888;4787.265932572599;6648.700808463583;6674.3325667433355;6796.966015169942;6807.965960170217;6863.524342266387;4967.155583550805;4985;4988.72562009093;5000.6249531284575;5012.8496145115705;5001.299818025479;5015.899682006339;5027.522385373383;5031.421386540522;5042.512458824655;5487.890242195213;5489.560835133179;5509.201165830932;5538.529225015919;5583.302087239095;6413.422791948654;6432.839179020562;6456.612603243821;6474.320196379406;6490.4807615390655;1741.3731056819536;1748.5803407182184;1749.8600111991266;1750.4398592450548;1759.410597449843;1130.5760339872547;1214.9976269577635;1218.2446882932638;1218.774526712561;1225.4669218889812;5458;5561.803087780304;5620.634658747168;5628.155776633538;5698.287714035746;6586.27550969393;6614.603123812587;6622.437092847065;6633.943477521983;6647.4349680276755;5626.268585083994;5673.978683836949;5687.919295333873;5703.458171473703;5703.832543633393;3459.7578169528397;3475.6176820549717;3502.824858757103;3530.293941211768;3545.4150065238946;6297.338779428185;6363.809085727436;6438.420542151229;6446.548741588043;6458.315643309153;6611.206655201407;6748.7300507979135;6775.220849602359;6788.185417749902;6789.490788190906;6400.559874028307;6411.050916578333;6442.645654489048;6469.708863101266;6478.222613286436;6502.60984340941;6537.836554086186;6547.985062315349;6607.900881486686;6673.864363403664;6454.225492940689;6495.610263384212;6526.869462610816;6534;6583.733013589014;6191.4118158774845;6272.435480806749;6320.652364119834;6369.554131210771;6381.8085457436355;4810.460652591277;4826.686265392738;4826.758662066813;4842.685225460334;4871.585915197248;6637.203535575762;6641.734330626721;6656.866862662817;6657.700403481952;6712.83217919556;6470.708818103102;6474.255460622088;6484.448821850196;6494.253160886558;6506;6464.612123272619;6496.935030649633;6497.5126865484335;6499.252585952676;6516.446102081376;6284.780032698834;6304.369563043659;6308.886523014841;6363;6406.487481001415;5822.214001109796;6224.284207316216;6358.650274234961;6362.936370636235;6522;6097.7865774697675;6104.389561044038;6170.876582468415;6171.148655403271;6205.875882482415 10:18:12 INFO - PID 32337 | |5;yui.html;922.9123233292835;924.8797656304679;938.8075444533871;947.6920000999672;959.9616015359386;17976.370827021055;18695.3456629018;18737.063146842636;18746.718799218022;18775.718364224547;18769.530761730963;19370.806291937075;20186.697199542017;20223.292184773534;20225.898870505665;1027.8972102789717;1052.36857885269;1052.952617132227;1054.346305290717;1054.5149231353587;1041.3907863899628;1047.1570385839395;1047.601911273715;1050.9947450262775;1052.8157572424834;793.3811626931004;1113.8106521891298;1117.402189828442;1120.383788916097;1122.5846436818388;949.3117489819895;1117.1398023521883;1119.6864877834219;1125.774845030993;1127.261643623428;819.3199644295221;819.4673462249526;822.1039067416544;822.2763967708438;823.9382046346548;18573.90713046426;19166.80833191678;19212.71180932287;19458.22167113314;19580.51048723779;19885.403437896886;20321.695174572393;20614.381568552817;20663.896680516653;20805.791942080537;15256.618584535476;15763.921180394025;15869.44456944013;15923.52229433119;16041.518754437386;3530.3292374448774;3537.3102245062223;3560.9821950890337;3569.5538057742783;3589.964100358989;106652.99999999923;110070;110672.44663776629;110976;112803.43598282037;16225.51323460298;16457.177141142896;16548.669026619642;16559.25483353252;16620.833791661928;26206.21381358524;27682.307942301602;27786.44427111487;27849.16452506428;28104.29739256535;8464.703735369232;8558.101399353103;8560.0583935767;8578.871316930126;8581.356398270154;4060.167665628539;4109.589041095866;4137.193247316804;4224.197402493516;4279.87160385189;4730.668853180313;4786.593139583175;4808.422989241313;4812.855614331575;4824.396950381202;986.8865080515833;996.8803743550675;1030.0472063341529;1031.1493018259816;1038.5638032026416;611.3458599298797;613.3804857094367;649.9480041596661;676.1818199978081;702.2345643248883;10535.367877927349;11592.652220433232;11920.105992050458;11928.403579821148;12185.390730463441;672.1128110893671;695.7425752471618;696.4880812602732;706.6749295324106;719.4352433339803;5150.768215430315;5169.3796744390165;5200.739963001911;5277.366715994105;5358.089124848785;97598.51200743951;97883;98674.5066274664;100658.496707516;101914.49042754887;86633.56683216544;88994.11005889988;90781;91782;93759.53120234492;75262.62368688121;75740.24259757443;77918;79496;79920.60039699764;11446.313221206754;11830.704232394259;11860.703482413008;12347.444365003417;12530;34473.482897756054;34902.12744681403;35060.64939350574;37300.6269937308;37607.43588846115;43420.348694768974;46058.30912536247;46646.30030549477;47472.52527474819;47548.524514755794;17658;17848.645406378295;18737.1262873717;18818.24727010904;19222;19971.700424493352;20274.59450811005;20735.27426540064;21056.26303079385;21256.893715531944;15866.365345386055;16489.34042638281;16800.243989020768;16826.074565898503;17945.641087177923;18266.817331827042;18833.434996950113;19618.215271389556;19856;19954.30159944395;16578.83421165773;17491.56271093182;17542;17549.736753948957;17600.91199543994;12940.676483087998;13150.671233218864;13399.330033498481;13922.09506382082;14102.506412275521;13011.154274972096;14020.64948376258;14465.49370772018;14690.706185876008;14835.258237088317 10:18:12 INFO - PID 32337 | __end_tp_report 10:18:12 INFO - PID 32337 | __start_cc_report 10:18:12 INFO - PID 32337 | _x_x_mozilla_cycle_collect,367 10:18:12 INFO - PID 32337 | __end_cc_report 10:18:12 INFO - PID 32337 | __startTimestamp1500225492909__endTimestamp 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | ------- Summary: start ------- 10:18:12 INFO - PID 32337 | Number of tests: 6 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | [#0] dojo.html Cycles:165 Average:5921.19 Median:6158.66 stddev:694.19 (11.3%) stddev-sans-first:657.07 10:18:12 INFO - PID 32337 | Values: 2987.7 3055.4 3081.7 3084.7 3094.0 5908.2 5923.4 5942.6 5976.0 5989.5 5990.4 6031.0 6036.8 6042.2 6043.4 5494.9 5523.7 5529.9 5531.4 5547.1 5501.2 5540.0 5553.1 5554.4 5558.3 5550.7 5552.0 5559.0 5559.7 5566.7 5554.8 5557.2 5561.6 5564.1 5567.3 5424.3 5490.7 5499.3 5529.3 5602.7 5967.4 6010.6 6014.7 6068.2 6080.9 6278.7 6376.7 6435.4 6442.6 6493.0 6466.0 6506.9 6510.3 6539.8 6555.7 6008.1 6041.1 6044.0 6051.3 6057.1 5674.6 6592.3 6604.5 6659.1 6725.4 6300.1 6319.4 6364.7 6389.7 6390.9 6307.5 6429.4 6436.6 6494.0 6498.9 6134.8 6229.1 6233.2 6323.6 6395.9 6329.2 6358.5 6374.7 6425.2 6510.3 5102.6 5180.4 5197.9 5264.6 5269.6 4441.2 4454.8 4463.2 4486.6 4488.5 5505.4 5601.7 5615.4 5621.8 5640.6 6523.9 6535.1 6540.7 6553.0 6559.8 5519.5 5538.0 5606.9 5619.6 5634.7 5575.7 5677.0 5679.1 5724.8 5738.9 6158.7 6175.8 6209.0 6226.8 6238.3 5330.9 6341.6 6463.3 6501.4 6573.9 6253.6 6286.8 6337.2 6359.5 6366.0 6347.3 6366.4 6425.9 6431.2 6452.5 6364.4 6457.2 6501.3 6505.8 6588.1 6216.8 6259.8 6262.1 6281.2 6295.7 6231.4 6263.8 6278.6 6284.8 6292.3 6160.1 6171.7 6187.1 6211.5 6241.9 5427.7 6353.1 6371.4 6409.1 6413.7 6078.4 6103.3 6122.6 6169.5 6173.6 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | [#1] ext.html Cycles:180 Average:37170.37 Median:35592.05 stddev:52533.67 (147.6%) stddev-sans-first:52609.74 10:18:12 INFO - PID 32337 | Values: 718.7 763.3 785.7 803.2 812.9 41325.8 41854.4 42036.0 42090.6 42096.6 38270.6 38376.8 38469.6 38500.4 38520.6 6877.3 6992.3 6998.6 7007.3 7019.1 6818.5 6944.0 6948.4 6954.8 6979.6 6923.7 6925.3 6929.8 6970.0 7562.7 7715.7 7757.2 7771.1 7773.8 7788.5 7541.1 7551.9 7568.3 7585.5 7591.8 37925.6 37943.4 38189.8 38410.6 38533.4 3451.6 3475.0 3493.0 3498.4 3500.2 31283.2 31457.5 31675.5 31687.4 31705.7 4803.4 4852.7 4865.2 4892.6 4924.0 186465.0 188828.0 218359.0 220693.0 221685.0 48221.8 48508.5 48509.0 48509.3 48530.8 50231.0 50280.5 50369.2 50435.5 50467.0 16021.0 16138.2 16161.4 16179.2 16219.0 13394.5 13408.2 13408.9 13429.5 13469.1 14259.1 14301.2 14308.4 14308.6 14558.2 5811.9 5813.6 5846.8 5847.4 5925.7 976.4 986.0 996.1 1033.5 1034.8 22732.9 22848.4 23103.7 23732.8 23862.0 1073.3 1107.1 1110.7 1120.3 1124.7 12008.6 12115.8 12193.9 12265.0 12365.4 220655.0 224026.0 224660.0 225412.0 226502.0 53792.5 54066.7 54283.0 54351.0 54472.5 1358.2 1372.0 1376.0 1495.7 1515.7 27583.6 27605.6 27619.9 27828.7 27948.6 36881.6 37180.4 37252.3 37286.0 37300.8 171202.1 172160.0 172448.0 173776.0 173924.0 35375.6 35430.3 35619.1 35626.8 35923.3 41675.0 42569.6 43051.0 43174.6 44147.6 30465.2 30908.2 31922.7 32351.2 32469.2 31109.5 31928.5 32058.7 32155.4 32209.7 34999.8 35565.0 35778.0 35792.6 35942.0 30470.8 30638.7 30927.7 31422.2 31611.8 22170.9 23177.9 23316.4 23554.4 23608.3 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | [#2] jquery.html Cycles:180 Average:34201.75 Median:2237.97 stddev:78797.14 (3520.9%) stddev-sans-first:78977.73 10:18:12 INFO - PID 32337 | Values: 569.4 570.7 571.6 578.8 590.6 21467.0 21546.0 21637.4 21656.5 21748.8 23887.9 24848.5 26150.7 26285.0 26498.6 6452.2 6647.5 6651.1 6676.0 6678.0 6393.4 6542.7 6559.9 6578.7 6579.7 5842.5 6383.6 6390.7 6537.6 6548.2 6241.3 6319.7 6362.9 6368.2 6394.5 5966.8 5980.4 5983.6 5990.9 5994.9 26718.7 28548.3 28676.4 28854.9 29157.6 1701.9 1906.1 1917.9 1930.5 1932.4 18240.7 18313.4 18455.4 18572.8 18578.3 2200.8 2275.1 2277.4 2305.5 2363.9 136701.3 138256.0 140970.0 141056.0 141281.0 14287.4 14781.3 15075.0 15076.3 15080.6 23319.3 23371.0 23426.2 23439.5 23483.6 10148.4 10170.9 10172.5 10437.0 10573.9 9633.5 9731.4 9847.8 9854.0 9863.7 9080.7 9155.3 9276.9 9451.1 9613.8 2418.3 2674.0 2680.2 2770.2 2788.8 958.5 966.5 972.4 987.7 992.2 19288.3 19688.5 19843.6 20194.3 20207.0 1012.9 1041.8 1059.1 1063.9 1064.4 10786.8 10791.9 10898.7 10957.3 11282.3 448119.0 464993.0 466743.0 467856.0 479017.0 106908.0 110140.4 111585.0 111959.0 117431.4 1256.0 1268.3 1302.2 1307.1 1311.4 19879.0 20654.3 20734.4 20997.0 21080.5 57191.0 59751.4 60017.7 60235.0 60537.0 72854.0 73183.6 73841.0 73937.3 74279.3 14217.8 14559.0 14954.3 15047.6 15085.2 23391.0 23423.0 23624.9 23654.6 24020.6 18149.5 18378.8 18402.4 18506.8 18547.4 16416.3 16518.8 16963.4 17027.8 17410.0 18753.0 18954.5 19068.6 19080.0 19090.2 14615.9 14632.3 14671.5 14697.3 14761.0 18281.7 18480.5 18547.3 18619.5 18786.2 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | [#3] mootools.html Cycles:180 Average:8434.96 Median:2432.42 stddev:5648.78 (232.2%) stddev-sans-first:5639.45 10:18:12 INFO - PID 32337 | Values: 1337.9 1400.6 1403.1 1404.2 1407.1 13246.9 13981.2 14054.7 14056.1 14081.8 13268.1 13669.2 13760.2 13768.3 13846.5 2593.2 2665.2 2685.1 2685.6 2695.7 2328.2 2402.6 2403.7 2406.3 2408.5 2442.3 2481.0 2489.3 2489.6 2497.5 2472.9 2480.8 2486.8 2503.3 2506.6 2420.2 2428.0 2436.9 2449.5 2451.8 13190.5 13412.4 13456.4 13458.5 13479.7 7304.3 7613.9 7618.8 7633.9 7664.3 4975.7 5141.4 5161.0 5233.1 5274.6 1912.3 1913.9 1918.7 1976.5 1981.0 20679.7 21449.5 21497.0 21733.9 21801.1 5653.3 5821.9 6162.3 6307.4 6354.3 15475.6 15609.0 15706.2 15725.4 15746.9 7172.7 7357.2 7397.1 7405.9 7415.9 6819.7 6823.9 6852.0 6870.7 6942.0 4789.5 4986.5 5003.1 5073.8 5074.2 1489.0 1491.7 1500.6 1500.8 1518.7 875.5 890.4 913.8 915.6 934.3 8001.5 8049.3 8050.2 8061.6 8108.3 704.5 710.5 732.0 742.6 748.5 4446.2 4469.8 4472.6 4505.9 4509.7 17639.7 17815.9 18418.4 18459.7 18794.4 17876.6 18105.5 18342.1 18417.0 18876.5 10602.5 10675.0 10828.9 10882.6 10993.4 7860.3 8153.2 8199.5 8210.0 8320.5 15387.9 16017.8 16036.7 16308.5 16465.1 15098.5 16385.8 16473.6 16492.8 16716.2 11298.6 11585.3 11627.0 11630.9 11659.1 12459.6 12673.4 12742.2 12748.4 12913.3 10432.4 10480.6 10574.8 10676.6 10779.1 9864.5 10134.6 10236.5 10256.2 10501.7 8875.6 9006.5 9009.7 9014.5 9015.1 7061.2 7123.5 7170.1 7220.7 7228.0 7055.4 7106.1 7262.1 7264.0 7381.8 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | [#4] prototype.html Cycles:180 Average:5399.57 Median:5948.62 stddev:1512.74 (25.4%) stddev-sans-first:1471.36 10:18:12 INFO - PID 32337 | Values: 486.8 497.4 508.4 516.0 516.4 6006.2 6026.4 6120.4 6173.4 6185.0 5830.1 5885.5 5991.6 6054.6 6076.2 4874.8 4888.7 4908.4 4937.3 4963.8 4836.7 4850.9 4900.8 4930.4 4955.1 4819.8 4900.9 4906.5 4928.0 4930.4 4679.1 4806.8 4864.2 4896.2 4898.6 4331.5 4332.8 4360.2 4377.6 4400.0 5837.4 5891.4 5932.1 5965.1 5966.7 5524.3 5528.9 5587.7 5594.5 5613.2 6308.7 6336.7 6351.1 6376.2 6445.3 4722.2 4753.4 4766.6 4784.8 4787.3 6648.7 6674.3 6797.0 6808.0 6863.5 4967.2 4985.0 4988.7 5000.6 5012.8 5001.3 5015.9 5027.5 5031.4 5042.5 5487.9 5489.6 5509.2 5538.5 5583.3 6413.4 6432.8 6456.6 6474.3 6490.5 1741.4 1748.6 1749.9 1750.4 1759.4 1130.6 1215.0 1218.2 1218.8 1225.5 5458.0 5561.8 5620.6 5628.2 5698.3 6586.3 6614.6 6622.4 6633.9 6647.4 5626.3 5674.0 5687.9 5703.5 5703.8 3459.8 3475.6 3502.8 3530.3 3545.4 6297.3 6363.8 6438.4 6446.5 6458.3 6611.2 6748.7 6775.2 6788.2 6789.5 6400.6 6411.1 6442.6 6469.7 6478.2 6502.6 6537.8 6548.0 6607.9 6673.9 6454.2 6495.6 6526.9 6534.0 6583.7 6191.4 6272.4 6320.7 6369.6 6381.8 4810.5 4826.7 4826.8 4842.7 4871.6 6637.2 6641.7 6656.9 6657.7 6712.8 6470.7 6474.3 6484.4 6494.3 6506.0 6464.6 6496.9 6497.5 6499.3 6516.4 6284.8 6304.4 6308.9 6363.0 6406.5 5822.2 6224.3 6358.7 6362.9 6522.0 6097.8 6104.4 6170.9 6171.1 6205.9 10:18:12 INFO - PID 32337 | 10:18:12 INFO - PID 32337 | [#5] yui.html Cycles:180 Average:21557.56 Median:19963.00 stddev:28111.19 (140.8%) stddev-sans-first:28147.34 10:18:12 INFO - PID 32337 | Values: 922.9 924.9 938.8 947.7 960.0 17976.4 18695.3 18737.1 18746.7 18775.7 18769.5 19370.8 20186.7 20223.3 20225.9 1027.9 1052.4 1053.0 1054.3 1054.5 1041.4 1047.2 1047.6 1051.0 1052.8 793.4 1113.8 1117.4 1120.4 1122.6 949.3 1117.1 1119.7 1125.8 1127.3 819.3 819.5 822.1 822.3 823.9 18573.9 19166.8 19212.7 19458.2 19580.5 19885.4 20321.7 20614.4 20663.9 20805.8 15256.6 15763.9 15869.4 15923.5 16041.5 3530.3 3537.3 3561.0 3569.6 3590.0 106653.0 110070.0 110672.4 110976.0 112803.4 16225.5 16457.2 16548.7 16559.3 16620.8 26206.2 27682.3 27786.4 27849.2 28104.3 8464.7 8558.1 8560.1 8578.9 8581.4 4060.2 4109.6 4137.2 4224.2 4279.9 4730.7 4786.6 4808.4 4812.9 4824.4 986.9 996.9 1030.0 1031.1 1038.6 611.3 613.4 649.9 676.2 702.2 10535.4 11592.7 11920.1 11928.4 12185.4 672.1 695.7 696.5 706.7 719.4 5150.8 5169.4 5200.7 5277.4 5358.1 97598.5 97883.0 98674.5 100658.5 101914.5 86633.6 88994.1 90781.0 91782.0 93759.5 75262.6 75740.2 77918.0 79496.0 79920.6 11446.3 11830.7 11860.7 12347.4 12530.0 34473.5 34902.1 35060.6 37300.6 37607.4 43420.3 46058.3 46646.3 47472.5 47548.5 17658.0 17848.6 18737.1 18818.2 19222.0 19971.7 20274.6 20735.3 21056.3 21256.9 15866.4 16489.3 16800.2 16826.1 17945.6 18266.8 18833.4 19618.2 19856.0 19954.3 16578.8 17491.6 17542.0 17549.7 17600.9 12940.7 13150.7 13399.3 13922.1 14102.5 13011.2 14020.6 14465.5 14690.7 14835.3 10:18:12 INFO - PID 32337 | -------- Summary: end -------- 10:18:12 INFO - PID 32337 | 10:18:13 INFO - TEST-INFO | 32337: exit 0 10:18:13 INFO - TEST-OK | dromaeo_css | took 1109914ms 10:18:13 INFO - TEST-START | kraken 10:18:13 INFO - Initialising browser for kraken test... 10:18:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47561/getInfo.html -profile /tmp/tmpENyEJE/profile 10:18:13 INFO - TEST-INFO | started process 32740 (/builds/slave/test/build/application/firefox/firefox http://localhost:47561/getInfo.html) 10:18:19 INFO - TEST-INFO | 32740: exit 0 10:18:19 INFO - Browser initialized. 10:18:19 INFO - Running cycle 1/1 for kraken test... 10:18:19 INFO - TEST-INFO | started process 485 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpENyEJE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:18:31 INFO - PID 485 | RSS: Main: 167202816 10:18:31 INFO - PID 485 | 10:18:33 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47561/tests/kraken/driver.html?testName=audio-beat-detection) 10:18:34 INFO - PID 485 | RSS: Main: 173940736 10:18:34 INFO - PID 485 | 10:18:37 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47561/tests/kraken/driver.html?testName=audio-dft) 10:18:37 INFO - PID 485 | RSS: Main: 174018560 10:18:37 INFO - PID 485 | 10:18:40 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47561/tests/kraken/driver.html?testName=audio-fft) 10:18:41 INFO - PID 485 | RSS: Main: 167690240 10:18:41 INFO - PID 485 | 10:18:43 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47561/tests/kraken/driver.html?testName=audio-oscillator) 10:18:43 INFO - PID 485 | RSS: Main: 169021440 10:18:43 INFO - PID 485 | 10:18:46 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47561/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:46 INFO - PID 485 | RSS: Main: 169558016 10:18:46 INFO - PID 485 | 10:18:49 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47561/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:50 INFO - PID 485 | RSS: Main: 172298240 10:18:50 INFO - PID 485 | 10:18:53 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47561/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:54 INFO - PID 485 | RSS: Main: 174718976 10:18:54 INFO - PID 485 | 10:18:57 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47561/tests/kraken/driver.html?testName=json-parse-financial) 10:18:57 INFO - PID 485 | RSS: Main: 173121536 10:18:57 INFO - PID 485 | 10:18:59 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47561/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:59 INFO - PID 485 | RSS: Main: 173088768 10:18:59 INFO - PID 485 | 10:19:01 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:02 INFO - PID 485 | RSS: Main: 173051904 10:19:02 INFO - PID 485 | 10:19:04 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:19:04 INFO - PID 485 | RSS: Main: 172949504 10:19:04 INFO - PID 485 | 10:19:07 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:19:08 INFO - PID 485 | RSS: Main: 173252608 10:19:08 INFO - PID 485 | 10:19:11 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:19:11 INFO - PID 485 | RSS: Main: 173035520 10:19:11 INFO - PID 485 | 10:19:13 INFO - PID 485 | Cycle 1(1): loaded http://localhost:47561/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47561/tests/kraken/driver.html?testName=ai-astar) 10:19:13 INFO - PID 485 | RSS: Main: 174465024 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | __start_tp_report 10:19:13 INFO - PID 485 | _x_x_mozilla_page_load 10:19:13 INFO - PID 485 | _x_x_mozilla_page_load_details 10:19:13 INFO - PID 485 | |i|pagename|runs| 10:19:13 INFO - PID 485 | |0;ai-astar;100;110;110;108;117;108;107;107;110;110 10:19:13 INFO - PID 485 | |1;audio-beat-detection;155;123;146;177;120;151;147;121;171;122 10:19:13 INFO - PID 485 | |2;audio-dft;163;168;162;163;163;163;161;165;166;170 10:19:13 INFO - PID 485 | |3;audio-fft;76;74;75;160;75;75;76;75;102;75 10:19:13 INFO - PID 485 | |4;audio-oscillator;82;83;135;82;81;81;131;81;82;130 10:19:13 INFO - PID 485 | |5;imaging-gaussian-blur;113;114;114;114;114;114;114;115;115;113 10:19:13 INFO - PID 485 | |6;imaging-darkroom;150;150;150;150;149;152;152;150;150;150 10:19:13 INFO - PID 485 | |7;imaging-desaturate;94;94;94;93;94;94;94;94;94;94 10:19:13 INFO - PID 485 | |8;json-parse-financial;54;56;54;55;55;55;54;54;55;55 10:19:13 INFO - PID 485 | |9;json-stringify-tinderbox;55;53;55;54;54;53;55;55;55;56 10:19:13 INFO - PID 485 | |10;stanford-crypto-aes;79;78;88;78;78;79;78;77;75;79 10:19:13 INFO - PID 485 | |11;stanford-crypto-ccm;132;128;129;135;143;124;125;130;143;163 10:19:13 INFO - PID 485 | |12;stanford-crypto-pbkdf2;186;178;181;182;183;191;193;191;180;184 10:19:13 INFO - PID 485 | |13;stanford-crypto-sha256-iterative;69;69;68;70;68;70;68;68;67;74 10:19:13 INFO - PID 485 | __end_tp_report 10:19:13 INFO - PID 485 | __start_cc_report 10:19:13 INFO - PID 485 | _x_x_mozilla_cycle_collect,938 10:19:13 INFO - PID 485 | __end_cc_report 10:19:13 INFO - PID 485 | __startTimestamp1500225553880__endTimestamp 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | ------- Summary: start ------- 10:19:13 INFO - PID 485 | Number of tests: 14 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:4.19 (3.8%) stddev-sans-first:3.04 10:19:13 INFO - PID 485 | Values: 100.0 110.0 110.0 108.0 117.0 108.0 107.0 107.0 110.0 110.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:149.00 stddev:21.15 (14.2%) stddev-sans-first:22.01 10:19:13 INFO - PID 485 | Values: 155.0 123.0 146.0 177.0 120.0 151.0 147.0 121.0 171.0 122.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:2.84 (1.7%) stddev-sans-first:2.96 10:19:13 INFO - PID 485 | Values: 163.0 168.0 162.0 163.0 163.0 163.0 161.0 165.0 166.0 170.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#3] audio-fft Cycles:10 Average:86.30 Median:75.00 stddev:27.24 (36.3%) stddev-sans-first:28.64 10:19:13 INFO - PID 485 | Values: 76.0 74.0 75.0 160.0 75.0 75.0 76.0 75.0 102.0 75.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:81.50 stddev:24.33 (29.9%) stddev-sans-first:25.21 10:19:13 INFO - PID 485 | Values: 82.0 83.0 135.0 82.0 81.0 81.0 131.0 81.0 82.0 130.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 10:19:13 INFO - PID 485 | Values: 113.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 115.0 113.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 10:19:13 INFO - PID 485 | Values: 150.0 150.0 150.0 150.0 149.0 152.0 152.0 150.0 150.0 150.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:19:13 INFO - PID 485 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.67 10:19:13 INFO - PID 485 | Values: 54.0 56.0 54.0 55.0 55.0 55.0 54.0 54.0 55.0 55.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 10:19:13 INFO - PID 485 | Values: 55.0 53.0 55.0 54.0 54.0 53.0 55.0 55.0 55.0 56.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:78.50 stddev:3.41 (4.3%) stddev-sans-first:3.62 10:19:13 INFO - PID 485 | Values: 79.0 78.0 88.0 78.0 78.0 79.0 78.0 77.0 75.0 79.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#11] stanford-crypto-ccm Cycles:10 Average:135.20 Median:133.50 stddev:11.79 (8.8%) stddev-sans-first:12.45 10:19:13 INFO - PID 485 | Values: 132.0 128.0 129.0 135.0 143.0 124.0 125.0 130.0 143.0 163.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:185.00 stddev:5.17 (2.8%) stddev-sans-first:5.47 10:19:13 INFO - PID 485 | Values: 186.0 178.0 181.0 182.0 183.0 191.0 193.0 191.0 180.0 184.0 10:19:13 INFO - PID 485 | 10:19:13 INFO - PID 485 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.97 (2.9%) stddev-sans-first:2.09 10:19:13 INFO - PID 485 | Values: 69.0 69.0 68.0 70.0 68.0 70.0 68.0 68.0 67.0 74.0 10:19:13 INFO - PID 485 | -------- Summary: end -------- 10:19:13 INFO - PID 485 | 10:19:14 INFO - PID 485 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:19:14 INFO - TEST-INFO | 485: exit 0 10:19:14 INFO - TEST-OK | kraken | took 61105ms 10:19:14 INFO - SUITE-END | took 1171s 10:19:15 INFO - Completed test suite (00:19:31) 10:19:15 INFO - javascript benchmark 10:19:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8276.112002673117, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2987.686292939243, 3055.358374741304, 3081.6764239754825, 3084.6606873243945, 3093.9535906961387, 5908.231929849119, 5923.407659234074, 5942.61373010754, 5976.043832986723, 5989.490893274072, 5990.400959904007, 6031.035034394497, 6036.788712395067, 6042.244719410074, 6043.365446628107, 5494.862628434281, 5523.6685798851995, 5529.917051244234, 5531.3915469298345, 5547.057000309957, 5501.202325662776, 5540.0, 5553.055980483307, 5554.361248456418, 5558.277423934883, 5550.69471179085, 5552.028395030853, 5559.0, 5559.666420014792, 5566.693831839248, 5554.805581804638, 5557.221988921554, 5561.63849349791, 5564.10974244123, 5567.276254086943, 5424.321959755031, 5490.6980116094, 5499.257600223958, 5529.281193444866, 5602.655362712961, 5967.433093856077, 6010.639361638316, 6014.699265036731, 6068.241469816273, 6080.905437021334, 6278.686065696743, 6376.713047912809, 6435.388638079422, 6442.613443193377, 6492.9935859941315, 6465.967670161619, 6506.869862602769, 6510.316416776265, 6539.836504087389, 6555.737770489175, 6008.128821320927, 6041.124037014608, 6044.032954727222, 6051.273847138327, 6057.091436284549, 5674.631148975303, 6592.340765923465, 6604.5046621502615, 6659.101021362054, 6725.361090696376, 6300.1494798202575, 6319.431251187323, 6364.681765911686, 6389.744410223632, 6390.8721825563225, 6307.5269354798575, 6429.3570642936265, 6436.581622194542, 6493.967530162414, 6498.902516462257, 6134.754609815646, 6229.065640154013, 6233.2208473940755, 6323.588966717149, 6395.904061439082, 6329.177206963065, 6358.491320694333, 6374.713137908805, 6425.228972523234, 6510.283868774431, 5102.566281866083, 5180.404253510895, 5197.870053248624, 5264.578833693372, 5269.578433725293, 4441.244988351923, 4454.821807127679, 4463.24124898768, 4486.641068714495, 4488.483824360175, 5505.449455054542, 5601.663900166003, 5615.382307946123, 5621.831345059655, 5640.633358831663, 6523.8695226095215, 6535.0850880876715, 6540.738370465223, 6552.967235163794, 6559.770408035696, 5519.530839878656, 5537.972310138423, 5606.85982850432, 5619.634723742944, 5634.746436410372, 5575.721213939287, 5676.971615141898, 5679.091345384719, 5724.799632012943, 5738.942610573924, 6158.661273629904, 6175.84560385994, 6209.037599172136, 6226.844328891814, 6238.313785483593, 5330.920036199538, 6341.6195028297525, 6463.321351258238, 6501.447376972821, 6573.901391479228, 6253.593516421418, 6286.811395658137, 6337.176167098248, 6359.459445947146, 6365.96817015912, 6347.333529979285, 6366.395192456709, 6425.935740642533, 6431.163948686641, 6452.483801295791, 6364.427201551883, 6457.192850893638, 6501.284858665448, 6505.804825855232, 6588.143541339787, 6216.751329946752, 6259.843503912257, 6262.091996660368, 6281.152044473937, 6295.748170073146, 6231.40801623845, 6263.812085637438, 6278.591891527036, 6284.811455656338, 6292.339304372975, 6160.075988601746, 6171.660558669317, 6187.102870083722, 6211.472024877936, 6241.906371404523, 5427.701476418835, 6353.110564520878, 6371.39471750183, 6409.070684750778, 6413.743450261937, 6078.3921607838865, 6103.298120716175, 6122.571420000557, 6169.5064394849205, 6173.629582224901], "value": 5870.768232762274, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.6945548142041, 763.3053920931953, 785.685725709716, 803.1927912447987, 812.8943237379143, 41325.79337103314, 41854.37218441718, 42036.0, 42090.57909420909, 42096.57903420969, 38270.617293827054, 38376.80811595945, 38469.61530384696, 38500.42249366255, 38520.614793852124, 6877.346652068046, 6992.265812089734, 6998.615076170809, 7007.299270072977, 7019.052427922216, 6818.488613353993, 6944.02783610295, 6948.3746462818335, 6954.826129346756, 6979.616121113337, 6923.723051077976, 6925.3074692530845, 6929.7574584889535, 6970.0, 7562.659680314399, 7715.6527956241825, 7757.224277572198, 7771.10632277284, 7773.805654858617, 7788.4937479063965, 7541.132769731496, 7551.924480755232, 7568.318851303409, 7585.506942048804, 7591.810204744926, 37925.62074379248, 37943.4308485373, 38189.80905095484, 38410.615893840986, 38533.42199867004, 3451.6375780543185, 3474.9826250868837, 3492.965070349315, 3498.387782138141, 3500.177458297298, 31283.217919552193, 31457.528137077734, 31675.524867127013, 31687.36625267505, 31705.682943170505, 4803.423589169287, 4852.684575502581, 4865.172920603471, 4892.584130348924, 4924.015196960622, 186465.0, 188828.0, 218359.0, 220693.0, 221685.0, 48221.75889120532, 48508.5149148504, 48509.0, 48509.27236091461, 48530.757346213046, 50231.0, 50280.497195028314, 50369.24446133311, 50435.49564504381, 50467.0, 16021.0, 16138.193090345436, 16161.4343497977, 16179.191040447931, 16219.0, 13394.464221431228, 13408.19550826934, 13408.932955335356, 13429.462821487226, 13469.057165998482, 14259.144451332953, 14301.213433261271, 14308.427662893577, 14308.57074287773, 14558.199299038442, 5811.883762324815, 5813.563982701315, 5846.795362162389, 5847.386024467455, 5925.674087925119, 976.3946353261024, 985.980280394388, 996.0637001218831, 1033.4625994482826, 1034.8395998620226, 22732.886335568546, 22848.44244281739, 23103.653445198335, 23732.762672373054, 23862.0, 1073.313079629038, 1107.1087774341731, 1110.6612483192703, 1120.2998126171144, 1124.6738445850776, 12008.579699710468, 12115.757684846254, 12193.93903030497, 12265.0, 12365.443829504808, 220655.0, 224026.0, 224660.0, 225412.0, 226502.0, 53792.462075378746, 54066.72966635221, 54283.0, 54351.0, 54472.45527544753, 1358.2054498118514, 1372.0327169345587, 1375.9931200343933, 1495.6933828565336, 1515.7423238049557, 27583.586246205923, 27605.585916211676, 27619.86190069037, 27828.72171278261, 27948.58077128804, 36881.63118368782, 37180.44229336508, 37252.25495490129, 37286.0, 37300.81349593234, 171202.14398927926, 172160.0, 172448.0, 173776.0, 173924.0, 35375.64624353724, 35430.29139417249, 35619.10952226215, 35626.8218658905, 35923.281534369686, 41675.0, 42569.57430425656, 43051.0, 43174.568254317055, 44147.55852441563, 30465.238369040064, 30908.22729431782, 31922.680773191973, 32351.191220219684, 32469.18827029343, 31109.53335699921, 31928.52107218347, 32058.67941320557, 32155.356892861542, 32209.67790322067, 34999.82500087483, 35565.0, 35778.0, 35792.64207357893, 35942.0, 30470.84764576163, 30638.693613063584, 30927.69072309248, 31422.214444639067, 31611.84194079015, 22170.89376509229, 23177.88411057934, 23316.417089572897, 23554.411139721644, 23608.29175124749], "value": 16958.888362525264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.3992837556376, 570.7374607680467, 571.5656101362965, 578.8003138917073, 590.6072461812895, 21467.03398347074, 21546.0, 21637.3508794736, 21656.458588535275, 21748.782512174872, 23887.880560597216, 24848.50302993939, 26150.73849261507, 26285.0, 26498.602520962202, 6452.193475815535, 6647.50143739219, 6651.135352404205, 6675.966620166892, 6677.966610166966, 6393.392627700384, 6542.705578248991, 6559.93440065598, 6578.671066446683, 6579.736810527573, 5842.503387212071, 6383.648899310536, 6390.712417941204, 6537.64042977636, 6548.214214294291, 6241.28225254097, 6319.74721011159, 6362.872742545146, 6368.235811702625, 6394.488440924715, 5966.820995370146, 5980.401959803985, 5983.6110652807865, 5990.8502287442725, 5994.940050599482, 26718.732812672013, 28548.286292842637, 28676.426471470662, 28854.855725721445, 29157.56263656047, 1701.8808683392167, 1906.075553356621, 1917.9328723494611, 1930.5270208798934, 1932.4202739178331, 18240.726389104173, 18313.3590324338, 18455.35406260788, 18572.81427185738, 18578.349757758548, 2200.8459407841456, 2275.0899640143907, 2277.430642339415, 2305.5273668897835, 2363.8936247868887, 136701.3164934169, 138256.0, 140970.0, 141056.0, 141281.0, 14287.357068931922, 14781.26093695311, 15075.0, 15076.321565529577, 15080.622984425258, 23319.300420987398, 23371.0, 23426.18008369699, 23439.531209375717, 23483.647745283834, 10148.441835698959, 10170.898291016994, 10172.542235599416, 10437.0, 10573.9471302643, 9633.518324083769, 9731.367461115005, 9847.80304393908, 9853.950730246446, 9863.654772083086, 9080.727578172666, 9155.313351498664, 9276.907230927603, 9451.149396554205, 9613.807723845484, 2418.322869596516, 2673.9866300668373, 2680.2361327021704, 2770.224337185591, 2788.8326700398043, 958.5446912716404, 966.468442356701, 972.3728195313982, 987.7234374375187, 992.1963209800085, 19288.324908628132, 19688.507787305432, 19843.603127937648, 20194.29319973794, 20207.0, 1012.8885822559513, 1041.7916416716687, 1059.1262208677872, 1063.914886809069, 1064.387976913278, 10786.784264314827, 10791.892081079088, 10898.727531811768, 10957.287776294675, 11282.266652667551, 448119.0, 464993.0, 466743.0, 467856.0, 479017.0, 106908.0, 110140.449297753, 111585.0, 111959.0, 117431.41284293524, 1255.9811602825964, 1268.315109840694, 1302.1937724204354, 1307.1046333261745, 1311.3836496846468, 19879.0, 20654.277100301417, 20734.377968660963, 20997.0, 21080.472988175417, 57191.0, 59751.402485974584, 60017.69991150016, 60235.0, 60537.0, 72854.0, 73183.63408182925, 73841.0, 73937.26062739304, 74279.2572074294, 14217.786733198804, 14559.0, 14954.327055282321, 15047.623809404415, 15085.24573771329, 23391.0, 23423.0, 23624.881875590512, 23654.645180322652, 24020.63969040501, 18149.546261343574, 18378.816211838246, 18402.35591754282, 18506.81493185051, 18547.44357669272, 16416.343346266014, 16518.83481165221, 16963.406280780113, 17027.82972170312, 17410.0, 18753.0, 18954.52613684669, 19068.618627627646, 19080.0, 19090.23639054478, 14615.853841461449, 14632.268386580841, 14671.486497972519, 14697.265136743334, 14761.0, 18281.725774113664, 18480.537986550444, 18547.258109675462, 18619.534511637317, 18786.154623041723], "value": 11987.991093938199, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.8862796662286, 1400.5588239704493, 1403.0879928046775, 1404.1715387921129, 1407.0853944935784, 13246.933765331172, 13981.161130332188, 14054.718905621894, 14056.086354386958, 14081.788773168411, 13268.137571057898, 13669.248191349472, 13760.174389536634, 13768.31158442079, 13846.515371961983, 2593.2220333899895, 2665.2270841455957, 2685.140754958414, 2685.5703087506004, 2695.663042119735, 2328.231683544435, 2402.5915594349003, 2403.6514705367713, 2406.278116565032, 2408.5182963407387, 2442.2795275393714, 2481.0323973650293, 2489.278109348296, 2489.626556016594, 2497.463045445229, 2472.938176545583, 2480.776730094282, 2486.76375744304, 2503.336615796806, 2506.5738824399896, 2420.189236605739, 2427.963580546293, 2436.8781560921884, 2449.473363226915, 2451.7548245175517, 13190.53833115846, 13412.396442160029, 13456.394462249222, 13458.52895148665, 13479.66300842477, 7304.342609165201, 7613.934049233112, 7618.8476230475635, 7633.923660763377, 7664.271894170045, 4975.664050709311, 5141.357330333708, 5160.974195129038, 5233.136532472137, 5274.604404669665, 1912.3498010676299, 1913.894735789531, 1918.7026010968323, 1976.505873531617, 1980.9801901981084, 20679.689804652942, 21449.463763405885, 21497.0, 21733.891330543247, 21801.127954881787, 5653.293338332708, 5821.94178058214, 6162.291336496361, 6307.432331090224, 6354.3010268870385, 15475.613109672347, 15609.0, 15706.214689265491, 15725.44960926362, 15746.9212653936, 7172.748953786592, 7357.153927298322, 7397.075365579302, 7405.925940740632, 7415.88876166858, 6819.727210911508, 6823.897641535381, 6852.040714300002, 6870.690818913161, 6942.0, 4789.544993225638, 4986.501349865057, 5003.149464591028, 5073.7716802743225, 5074.188129899198, 1488.9925550372395, 1491.7240310542584, 1500.5873384819263, 1500.8274048484345, 1518.6507103366066, 875.5228400521731, 890.425675439338, 913.8263729891432, 915.5742579700338, 934.345957829522, 8001.479903806234, 8049.27556519916, 8050.235227653364, 8061.596920154087, 8108.270255677017, 704.5314865614412, 710.4671496377716, 731.9817004574821, 742.6286856571714, 748.5321673953779, 4446.199684056901, 4469.776511174428, 4472.597466228055, 4505.864824055284, 4509.661775366861, 17639.73540396895, 17815.931044137393, 18418.447446576625, 18459.723104153447, 18794.436166915013, 17876.55308617295, 18105.456836294932, 18342.082895855154, 18417.0, 18876.528086797938, 10602.522886470128, 10675.0, 10828.891711082946, 10882.564697412174, 10993.395363255098, 7860.253275938777, 8153.22544358285, 8199.467034642728, 8210.0, 8320.542370169698, 15387.923060385074, 16017.839821602101, 16036.67926641484, 16308.510744677678, 16465.094419807025, 15098.471553496014, 16385.836141638432, 16473.588160296087, 16492.75260871112, 16716.164191790118, 11298.60454884075, 11585.304881706787, 11627.011704005254, 11630.88369116298, 11659.125565582446, 12459.626211213677, 12673.366331683563, 12742.171758835646, 12748.4263208154, 12913.289769062792, 10432.426216558162, 10480.63317783874, 10574.8413773795, 10676.62631807883, 10779.083777878968, 9864.457454840052, 10134.594616215269, 10236.488175591041, 10256.230782691477, 10501.737456563647, 8875.556222188994, 9006.54967251648, 9009.684661036832, 9014.504202268676, 9015.053419391132, 7061.152661680631, 7123.501354905107, 7170.0678911743225, 7220.711171553079, 7228.0, 7055.38401873487, 7106.147262328347, 7262.092238470192, 7264.0, 7381.767035422497], "value": 6159.046523005055, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.8039340550483, 497.37306420142625, 508.40516595583165, 515.9742012899356, 516.4345042178815, 6006.189164462796, 6026.367231440701, 6120.378099803555, 6173.444390004899, 6185.0, 5830.105215804859, 5885.468718768737, 5991.610545314563, 6054.636721796695, 6076.210092687956, 4874.756262186894, 4888.682235654671, 4908.386451693538, 4937.333459982892, 4963.783872951114, 4836.661433699643, 4850.927236091461, 4900.848300649344, 4930.359053323063, 4955.132851750947, 4819.770958405602, 4900.877478063041, 4906.533879281463, 4927.950720492785, 4930.4330002049865, 4679.087577922306, 4806.8077276909235, 4864.246041863482, 4896.192128298826, 4898.583620392271, 4331.480222373335, 4332.805023773906, 4360.231253154238, 4377.606015458624, 4399.956000439986, 5837.445442682938, 5891.381404952461, 5932.110183472513, 5965.13505541694, 5966.70166491678, 5524.254225679522, 5528.861778455531, 5587.692676902769, 5594.524465420444, 5613.21415001896, 6308.716107775161, 6336.651484168369, 6351.079093531458, 6376.202974628171, 6445.291017988017, 4722.197226471474, 4753.36009076868, 4766.571008609207, 4784.760761961888, 4787.265932572599, 6648.700808463583, 6674.3325667433355, 6796.966015169942, 6807.965960170217, 6863.524342266387, 4967.155583550805, 4985.0, 4988.72562009093, 5000.6249531284575, 5012.8496145115705, 5001.299818025479, 5015.899682006339, 5027.522385373383, 5031.421386540522, 5042.512458824655, 5487.890242195213, 5489.560835133179, 5509.201165830932, 5538.529225015919, 5583.302087239095, 6413.422791948654, 6432.839179020562, 6456.612603243821, 6474.320196379406, 6490.4807615390655, 1741.3731056819536, 1748.5803407182184, 1749.8600111991266, 1750.4398592450548, 1759.410597449843, 1130.5760339872547, 1214.9976269577635, 1218.2446882932638, 1218.774526712561, 1225.4669218889812, 5458.0, 5561.803087780304, 5620.634658747168, 5628.155776633538, 5698.287714035746, 6586.27550969393, 6614.603123812587, 6622.437092847065, 6633.943477521983, 6647.4349680276755, 5626.268585083994, 5673.978683836949, 5687.919295333873, 5703.458171473703, 5703.832543633393, 3459.7578169528397, 3475.6176820549717, 3502.824858757103, 3530.293941211768, 3545.4150065238946, 6297.338779428185, 6363.809085727436, 6438.420542151229, 6446.548741588043, 6458.315643309153, 6611.206655201407, 6748.7300507979135, 6775.220849602359, 6788.185417749902, 6789.490788190906, 6400.559874028307, 6411.050916578333, 6442.645654489048, 6469.708863101266, 6478.222613286436, 6502.60984340941, 6537.836554086186, 6547.985062315349, 6607.900881486686, 6673.864363403664, 6454.225492940689, 6495.610263384212, 6526.869462610816, 6534.0, 6583.733013589014, 6191.4118158774845, 6272.435480806749, 6320.652364119834, 6369.554131210771, 6381.8085457436355, 4810.460652591277, 4826.686265392738, 4826.758662066813, 4842.685225460334, 4871.585915197248, 6637.203535575762, 6641.734330626721, 6656.866862662817, 6657.700403481952, 6712.83217919556, 6470.708818103102, 6474.255460622088, 6484.448821850196, 6494.253160886558, 6506.0, 6464.612123272619, 6496.935030649633, 6497.5126865484335, 6499.252585952676, 6516.446102081376, 6284.780032698834, 6304.369563043659, 6308.886523014841, 6363.0, 6406.487481001415, 5822.214001109796, 6224.284207316216, 6358.650274234961, 6362.936370636235, 6522.0, 6097.7865774697675, 6104.389561044038, 6170.876582468415, 6171.148655403271, 6205.875882482415], "value": 4956.375317438231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [922.9123233292835, 924.8797656304679, 938.8075444533871, 947.6920000999672, 959.9616015359386, 17976.370827021055, 18695.3456629018, 18737.063146842636, 18746.718799218022, 18775.718364224547, 18769.530761730963, 19370.806291937075, 20186.697199542017, 20223.292184773534, 20225.898870505665, 1027.8972102789717, 1052.36857885269, 1052.952617132227, 1054.346305290717, 1054.5149231353587, 1041.3907863899628, 1047.1570385839395, 1047.601911273715, 1050.9947450262775, 1052.8157572424834, 793.3811626931004, 1113.8106521891298, 1117.402189828442, 1120.383788916097, 1122.5846436818388, 949.3117489819895, 1117.1398023521883, 1119.6864877834219, 1125.774845030993, 1127.261643623428, 819.3199644295221, 819.4673462249526, 822.1039067416544, 822.2763967708438, 823.9382046346548, 18573.90713046426, 19166.80833191678, 19212.71180932287, 19458.22167113314, 19580.51048723779, 19885.403437896886, 20321.695174572393, 20614.381568552817, 20663.896680516653, 20805.791942080537, 15256.618584535476, 15763.921180394025, 15869.44456944013, 15923.52229433119, 16041.518754437386, 3530.3292374448774, 3537.3102245062223, 3560.9821950890337, 3569.5538057742783, 3589.964100358989, 106652.99999999923, 110070.0, 110672.44663776629, 110976.0, 112803.43598282037, 16225.51323460298, 16457.177141142896, 16548.669026619642, 16559.25483353252, 16620.833791661928, 26206.21381358524, 27682.307942301602, 27786.44427111487, 27849.16452506428, 28104.29739256535, 8464.703735369232, 8558.101399353103, 8560.0583935767, 8578.871316930126, 8581.356398270154, 4060.167665628539, 4109.589041095866, 4137.193247316804, 4224.197402493516, 4279.87160385189, 4730.668853180313, 4786.593139583175, 4808.422989241313, 4812.855614331575, 4824.396950381202, 986.8865080515833, 996.8803743550675, 1030.0472063341529, 1031.1493018259816, 1038.5638032026416, 611.3458599298797, 613.3804857094367, 649.9480041596661, 676.1818199978081, 702.2345643248883, 10535.367877927349, 11592.652220433232, 11920.105992050458, 11928.403579821148, 12185.390730463441, 672.1128110893671, 695.7425752471618, 696.4880812602732, 706.6749295324106, 719.4352433339803, 5150.768215430315, 5169.3796744390165, 5200.739963001911, 5277.366715994105, 5358.089124848785, 97598.51200743951, 97883.0, 98674.5066274664, 100658.496707516, 101914.49042754887, 86633.56683216544, 88994.11005889988, 90781.0, 91782.0, 93759.53120234492, 75262.62368688121, 75740.24259757443, 77918.0, 79496.0, 79920.60039699764, 11446.313221206754, 11830.704232394259, 11860.703482413008, 12347.444365003417, 12530.0, 34473.482897756054, 34902.12744681403, 35060.64939350574, 37300.6269937308, 37607.43588846115, 43420.348694768974, 46058.30912536247, 46646.30030549477, 47472.52527474819, 47548.524514755794, 17658.0, 17848.645406378295, 18737.1262873717, 18818.24727010904, 19222.0, 19971.700424493352, 20274.59450811005, 20735.27426540064, 21056.26303079385, 21256.893715531944, 15866.365345386055, 16489.34042638281, 16800.243989020768, 16826.074565898503, 17945.641087177923, 18266.817331827042, 18833.434996950113, 19618.215271389556, 19856.0, 19954.30159944395, 16578.83421165773, 17491.56271093182, 17542.0, 17549.736753948957, 17600.91199543994, 12940.676483087998, 13150.671233218864, 13399.330033498481, 13922.09506382082, 14102.506412275521, 13011.154274972096, 14020.64948376258, 14465.49370772018, 14690.706185876008, 14835.258237088317], "value": 8818.879823160534, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 110.0, 108.0, 117.0, 108.0, 107.0, 107.0, 110.0, 110.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 146.0, 177.0, 120.0, 151.0, 147.0, 121.0, 171.0, 122.0], "value": 143.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 168.0, 162.0, 163.0, 163.0, 163.0, 161.0, 165.0, 166.0, 170.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 75.0, 160.0, 75.0, 75.0, 76.0, 75.0, 102.0, 75.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 135.0, 82.0, 81.0, 81.0, 131.0, 81.0, 82.0, 130.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0, 113.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 149.0, 152.0, 152.0, 150.0, 150.0, 150.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 54.0, 55.0, 55.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 54.0, 54.0, 53.0, 55.0, 55.0, 55.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 88.0, 78.0, 78.0, 79.0, 78.0, 77.0, 75.0, 79.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 128.0, 129.0, 135.0, 143.0, 124.0, 125.0, 130.0, 143.0, 163.0], "value": 135.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 178.0, 181.0, 182.0, 183.0, 191.0, 193.0, 191.0, 180.0, 184.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 70.0, 68.0, 70.0, 68.0, 68.0, 67.0, 74.0], "value": 69.1, "unit": "score"}]}]} 10:19:15 INFO - Return code: 0 10:19:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:19:15 INFO - # TBPL SUCCESS # 10:19:15 INFO - Running post-action listener: _package_coverage_data 10:19:15 INFO - Running post-action listener: _resource_record_post_action 10:19:15 INFO - [mozharness: 2017-07-16 17:19:15.178661Z] Finished run-tests step (success) 10:19:15 INFO - Running post-run listener: _resource_record_post_run 10:19:15 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 435130368; Read time: 188; Write time: 319724 10:19:15 INFO - TinderboxPrint: CPU usage
13.1% 10:19:15 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 188 10:19:15 INFO - TinderboxPrint: I/O write bytes / time
435,130,368 / 319,724 10:19:15 INFO - TinderboxPrint: CPU idle
8,248.0 (86.9%) 10:19:15 INFO - TinderboxPrint: CPU user
1,196.0 (12.6%) 10:19:15 INFO - TinderboxPrint: Swap in / out
0 / 0 10:19:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107180032; Read time: 0; Write time: 98180 10:19:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:19:15 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 327950336; Read time: 184; Write time: 221544 10:19:15 INFO - Running post-run listener: _upload_blobber_files 10:19:15 INFO - Blob upload gear active. 10:19:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:19:15 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 10:19:15 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:19:15 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:19:16 INFO - Traceback (most recent call last): 10:19:16 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 10:19:16 INFO - main() 10:19:16 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 10:19:16 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 10:19:16 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 10:19:16 INFO - return set(response.json().get('whitelist', [])) 10:19:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 10:19:16 INFO - return json.loads(self.text or self.content, **kwargs) 10:19:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 10:19:16 INFO - return _default_decoder.decode(s) 10:19:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 10:19:16 INFO - obj, end = self.raw_decode(s) 10:19:16 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 10:19:16 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 10:19:16 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 10:19:16 ERROR - Return code: 1 10:19:16 INFO - Setting buildbot property blobber_files to {} 10:19:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:19:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:19:16 INFO - Contents: 10:19:16 INFO - blobber_files:{} 10:19:16 INFO - Running post-run listener: copy_logs_to_upload_dir 10:19:16 INFO - Copying logs to upload dir... 10:19:16 INFO - mkdir: /builds/slave/test/build/upload/logs 10:19:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.027239 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-07-16 10:19:15.984274) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:15.988014) ========= 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=652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011168 build_url: 'https://queue.taskcluster.net/v1/task/UQpJi8ppT0atoWxfZMc-4A/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:16.042883) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:16.043264) ========= 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=652eb03db935cfc1c15ebda0000001ad-1500220810.948738-2116400326 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:16.093841) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 10:19:16.094206) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 10:19:16.094579) ========= ========= Total master_lag: 0.19 =========