builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-007 starttime: 1487370713.04 results: success (0) revision: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.035799) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.036282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.036617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.053243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.053566) ========= 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=923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 14:31:53-- 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% 36.9M=0s 2017-02-17 14:31:53 (36.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.083058 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.150488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.150843) ========= 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=923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019288 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:31:53.204961) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-02-17 14:31:53.205652) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 --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 d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 --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=923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 14:31:53,214 truncating revision to first 12 chars 2017-02-17 14:31:53,214 Setting DEBUG logging. 2017-02-17 14:31:53,214 attempt 1/10 2017-02-17 14:31:53,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d11c29c1db3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 14:31:53,319 attempt 1/10 2017-02-17 14:31:53,596 current task status: no status available at this point. state: PENDING 2017-02-17 14:31:53,597 sleeping for 11.00s (attempt 1/10) 2017-02-17 14:32:04,608 attempt 2/10 2017-02-17 14:32:04,667 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-02-17 14:32:04,870 unpacking tar archive at: mozilla-central-d11c29c1db3a/testing/mozharness/ program finished with exit code 0 elapsedTime=11.932052 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-02-17 14:32:05.151728) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:32:05.152077) ========= script_repo_revision: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 14:32:05.152488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:32:05.152757) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 14:32:05.161577) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-17 14:32:05.161853) ========= /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=923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:32:05 INFO - MultiFileLogger online at 20170217 14:32:05 in /builds/slave/test 14:32:05 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 14:32:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:32:05 INFO - {'append_to_log': False, 14:32:05 INFO - 'base_work_dir': '/builds/slave/test', 14:32:05 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:32:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:32:05 INFO - 'branch': 'Firefox-Non-PGO', 14:32:05 INFO - 'buildbot_json_path': 'buildprops.json', 14:32:05 INFO - 'config_files': ('talos/linux_config.py',), 14:32:05 INFO - 'default_actions': ('clobber', 14:32:05 INFO - 'read-buildbot-config', 14:32:05 INFO - 'download-and-extract', 14:32:05 INFO - 'populate-webroot', 14:32:05 INFO - 'create-virtualenv', 14:32:05 INFO - 'install', 14:32:05 INFO - 'run-tests'), 14:32:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:32:05 INFO - 'download_minidump_stackwalk': True, 14:32:05 INFO - 'download_symbols': 'ondemand', 14:32:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:32:05 INFO - 'tooltool.py': '/tools/tooltool.py', 14:32:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:32:05 INFO - '/tools/misc-python/virtualenv.py')}, 14:32:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:32:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:32:05 INFO - 'installer_path': 'installer.exe', 14:32:05 INFO - 'log_level': 'info', 14:32:05 INFO - 'log_name': 'talos', 14:32:05 INFO - 'log_to_console': True, 14:32:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:32:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:32:05 INFO - 'opt_config_files': (), 14:32:05 INFO - 'pip_index': False, 14:32:05 INFO - 'sps_profile': False, 14:32:05 INFO - 'sps_profile_interval': 0, 14:32:05 INFO - 'suite': 'dromaeojs-e10s', 14:32:05 INFO - 'system_bits': '32', 14:32:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:32:05 INFO - 'title': 'talos-linux64-ix-007', 14:32:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:32:05 INFO - 'use_talos_json': True, 14:32:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:32:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:32:05 INFO - 'work_dir': 'build'} 14:32:05 INFO - [mozharness: 2017-02-17 22:32:05.268966Z] Running clobber step. 14:32:05 INFO - Running pre-action listener: _resource_record_pre_action 14:32:05 INFO - Running main action method: clobber 14:32:05 INFO - rmtree: /builds/slave/test/build 14:32:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:32:06 INFO - Running post-action listener: _resource_record_post_action 14:32:06 INFO - [mozharness: 2017-02-17 22:32:06.230411Z] Finished clobber step (success) 14:32:06 INFO - [mozharness: 2017-02-17 22:32:06.230509Z] Running read-buildbot-config step. 14:32:06 INFO - Running pre-action listener: _resource_record_pre_action 14:32:06 INFO - Running main action method: read_buildbot_config 14:32:06 INFO - Using buildbot properties: 14:32:06 INFO - { 14:32:06 INFO - "project": "", 14:32:06 INFO - "product": "firefox", 14:32:06 INFO - "who": "kwierso@gmail.com", 14:32:06 INFO - "installer_path": "public/build/target.tar.bz2", 14:32:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:32:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:32:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:32:06 INFO - "stage_platform": "linux64", 14:32:06 INFO - "basedir": "/builds/slave/test", 14:32:06 INFO - "buildnumber": 681, 14:32:06 INFO - "platform": "ubuntu64_hw", 14:32:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:32:06 INFO - "slavebuilddir": "test", 14:32:06 INFO - "taskId": "PujWeKYzQJSrwxPgaba6iw", 14:32:06 INFO - "branch": "mozilla-central", 14:32:06 INFO - "script_repo_revision": "production", 14:32:06 INFO - "revision": "d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85", 14:32:06 INFO - "slavename": "talos-linux64-ix-007", 14:32:06 INFO - "repo_path": "mozilla-central" 14:32:06 INFO - } 14:32:06 INFO - Finding installer, test and symbols from parent task. 14:32:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PujWeKYzQJSrwxPgaba6iw'}, attempt #1 14:32:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PujWeKYzQJSrwxPgaba6iw'}, attempt #1 14:32:07 INFO - Task dependencies: JuvUKJ0xRCS_MIz4Y2dtkA 14:32:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA'}, attempt #1 14:32:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2 14:32:07 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/test_packages.json 14:32:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.crashreporter-symbols.zip 14:32:07 INFO - Running post-action listener: _resource_record_post_action 14:32:07 INFO - [mozharness: 2017-02-17 22:32:07.616926Z] Finished read-buildbot-config step (success) 14:32:07 INFO - [mozharness: 2017-02-17 22:32:07.617234Z] Running download-and-extract step. 14:32:07 INFO - Running pre-action listener: _resource_record_pre_action 14:32:07 INFO - Running main action method: download_and_extract 14:32:07 INFO - mkdir: /builds/slave/test/build/tests 14:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:07 INFO - trying https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.test_packages.json 14:32:07 INFO - Downloading https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:32:08 INFO - Downloaded 896 bytes. 14:32:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:32:08 INFO - Using the following test package requirements: 14:32:08 INFO - {u'common': [u'target.common.tests.zip'], 14:32:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:32:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:32:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:32:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:32:08 INFO - u'mozbase': [u'target.common.tests.zip'], 14:32:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:32:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:32:08 INFO - u'web-platform': [u'target.common.tests.zip', 14:32:08 INFO - u'target.web-platform.tests.zip'], 14:32:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:32:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 14:32:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.common.tests.zip 14:32:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:32:08 INFO - Fetch https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.common.tests.zip into memory 14:32:10 INFO - Expected file size: 57145642 14:32:10 INFO - Obtained file size: 57145642 14:32:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 14:32:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.common.tests.zip 14:32:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:32:20 INFO - Fetch https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.common.tests.zip into memory 14:32:21 INFO - Expected file size: 57145642 14:32:21 INFO - Obtained file size: 57145642 14:32:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.talos.tests.zip 14:32:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:32:32 INFO - Fetch https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.talos.tests.zip into memory 14:32:33 INFO - Expected file size: 14403647 14:32:33 INFO - Obtained file size: 14403647 14:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:34 INFO - trying https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2 14:32:34 INFO - Downloading https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:32:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:32:36 INFO - Downloaded 61419567 bytes. 14:32:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2 14:32:36 INFO - mkdir: /builds/slave/test/properties 14:32:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:32:36 INFO - Writing to file /builds/slave/test/properties/build_url 14:32:36 INFO - Contents: 14:32:36 INFO - build_url:https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2 14:32:36 INFO - Running post-action listener: _resource_record_post_action 14:32:36 INFO - Running post-action listener: set_extra_try_arguments 14:32:36 INFO - [mozharness: 2017-02-17 22:32:36.185596Z] Finished download-and-extract step (success) 14:32:36 INFO - [mozharness: 2017-02-17 22:32:36.185692Z] Running populate-webroot step. 14:32:36 INFO - Running pre-action listener: _resource_record_pre_action 14:32:36 INFO - Running main action method: populate_webroot 14:32:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:32:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:32:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:32:36 INFO - '--tppagecycles', 14:32:36 INFO - '7'], 14:32:36 INFO - 'tests': ['tsvgm']}}, 14:32:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:32:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:32:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:32:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:32:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:32:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:32:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['damp', 'tps']}, 14:32:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'tests': ['damp', 'tps']}, 14:32:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['dromaeo_dom']}, 14:32:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:32:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['basic_compositor_video']}, 14:32:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:32:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['a11yr', 14:32:36 INFO - 'ts_paint', 14:32:36 INFO - 'tpaint', 14:32:36 INFO - 'sessionrestore', 14:32:36 INFO - 'sessionrestore_no_auto_restore', 14:32:36 INFO - 'tabpaint']}, 14:32:36 INFO - 'other-e10s': {'tests': ['a11yr', 14:32:36 INFO - 'ts_paint', 14:32:36 INFO - 'tpaint', 14:32:36 INFO - 'sessionrestore', 14:32:36 INFO - 'sessionrestore_no_auto_restore', 14:32:36 INFO - 'tabpaint']}, 14:32:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:32:36 INFO - 'ts_paint', 14:32:36 INFO - 'tpaint', 14:32:36 INFO - 'sessionrestore', 14:32:36 INFO - 'sessionrestore_no_auto_restore', 14:32:36 INFO - 'tabpaint']}, 14:32:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:32:36 INFO - 'ts_paint', 14:32:36 INFO - 'tpaint', 14:32:36 INFO - 'sessionrestore', 14:32:36 INFO - 'sessionrestore_no_auto_restore', 14:32:36 INFO - 'tabpaint']}, 14:32:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['a11yr', 14:32:36 INFO - 'ts_paint', 14:32:36 INFO - 'tpaint', 14:32:36 INFO - 'sessionrestore', 14:32:36 INFO - 'sessionrestore_no_auto_restore', 14:32:36 INFO - 'tabpaint']}, 14:32:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['a11yr', 14:32:36 INFO - 'ts_paint', 14:32:36 INFO - 'tpaint', 14:32:36 INFO - 'sessionrestore', 14:32:36 INFO - 'sessionrestore_no_auto_restore', 14:32:36 INFO - 'tabpaint']}, 14:32:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['tsvgx', 14:32:36 INFO - 'tsvgr_opacity', 14:32:36 INFO - 'tart', 14:32:36 INFO - 'tscrollx', 14:32:36 INFO - 'cart', 14:32:36 INFO - 'tsvg_static']}, 14:32:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:32:36 INFO - 'tsvgr_opacity', 14:32:36 INFO - 'tart', 14:32:36 INFO - 'tscrollx', 14:32:36 INFO - 'cart', 14:32:36 INFO - 'tsvg_static']}, 14:32:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'talos_options': ['--disable-e10s'], 14:32:36 INFO - 'tests': ['tp5o']}, 14:32:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:32:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'talos_options': ['--disable-e10s', 14:32:36 INFO - '--xperf_path', 14:32:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:32:36 INFO - 'tests': ['tp5n']}, 14:32:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:32:36 INFO - 'talos_options': ['--xperf_path', 14:32:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:32:36 INFO - 'tests': ['tp5n']}}, 14:32:36 INFO - 'talos.zip': {'path': '', 14:32:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:32:36 INFO - Running post-action listener: _resource_record_post_action 14:32:36 INFO - [mozharness: 2017-02-17 22:32:36.198939Z] Finished populate-webroot step (success) 14:32:36 INFO - [mozharness: 2017-02-17 22:32:36.199028Z] Running create-virtualenv step. 14:32:36 INFO - Running pre-action listener: _resource_record_pre_action 14:32:36 INFO - Running main action method: create_virtualenv 14:32:36 INFO - Creating virtualenv /builds/slave/test/build/venv 14:32:36 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 14:32:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:32:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:32:36 INFO - Using real prefix '/usr' 14:32:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:32:36 INFO - Installing distribute.............................................................................................................................................................................................done. 14:32:38 INFO - Installing pip.................done. 14:32:38 INFO - Return code: 0 14:32:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:32:38 INFO - Reading from file tmpfile_stdout 14:32:38 INFO - Output received: 14:32:38 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) 14:32:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:32:38 INFO - Using env: {'DISPLAY': ':0', 14:32:38 INFO - 'HOME': '/home/cltbld', 14:32:38 INFO - 'LANG': 'en_US.UTF-8', 14:32:38 INFO - 'LANGUAGE': 'en_US:en', 14:32:38 INFO - 'LOGNAME': 'cltbld', 14:32:38 INFO - 'MAIL': '/var/mail/cltbld', 14:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:38 INFO - 'MOZ_NO_REMOTE': '1', 14:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:38 INFO - 'NO_EM_RESTART': '1', 14:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:38 INFO - 'PWD': '/builds/slave/test', 14:32:38 INFO - 'SHELL': '/bin/bash', 14:32:38 INFO - 'SHLVL': '1', 14:32:38 INFO - 'TERM': 'linux', 14:32:38 INFO - 'TMOUT': '86400', 14:32:38 INFO - 'USER': 'cltbld', 14:32:38 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:38 INFO - '_': '/tools/buildbot/bin/python'} 14:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:39 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 14:32:39 INFO - Cleaning up... 14:32:39 INFO - Return code: 0 14:32:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:32:39 INFO - Using env: {'DISPLAY': ':0', 14:32:39 INFO - 'HOME': '/home/cltbld', 14:32:39 INFO - 'LANG': 'en_US.UTF-8', 14:32:39 INFO - 'LANGUAGE': 'en_US:en', 14:32:39 INFO - 'LOGNAME': 'cltbld', 14:32:39 INFO - 'MAIL': '/var/mail/cltbld', 14:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:39 INFO - 'MOZ_NO_REMOTE': '1', 14:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:39 INFO - 'NO_EM_RESTART': '1', 14:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:39 INFO - 'PWD': '/builds/slave/test', 14:32:39 INFO - 'SHELL': '/bin/bash', 14:32:39 INFO - 'SHLVL': '1', 14:32:39 INFO - 'TERM': 'linux', 14:32:39 INFO - 'TMOUT': '86400', 14:32:39 INFO - 'USER': 'cltbld', 14:32:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:39 INFO - '_': '/tools/buildbot/bin/python'} 14:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:39 INFO - Downloading/unpacking psutil>=3.1.1 14:32:39 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 14:32:39 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 14:32:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:39 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 14:32:39 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 14:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:32:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:32:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:32:41 INFO - Installing collected packages: psutil 14:32:41 INFO - Running setup.py install for psutil 14:32:41 INFO - building 'psutil._psutil_linux' extension 14:32:41 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 14:32:41 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 14:32:41 INFO - building 'psutil._psutil_posix' extension 14:32:41 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 14:32:41 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 14:32:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:32:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:32:42 INFO - Successfully installed psutil 14:32:42 INFO - Cleaning up... 14:32:42 INFO - Return code: 0 14:32:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:32:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:32:42 INFO - Using env: {'DISPLAY': ':0', 14:32:42 INFO - 'HOME': '/home/cltbld', 14:32:42 INFO - 'LANG': 'en_US.UTF-8', 14:32:42 INFO - 'LANGUAGE': 'en_US:en', 14:32:42 INFO - 'LOGNAME': 'cltbld', 14:32:42 INFO - 'MAIL': '/var/mail/cltbld', 14:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:42 INFO - 'MOZ_NO_REMOTE': '1', 14:32:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:42 INFO - 'NO_EM_RESTART': '1', 14:32:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:42 INFO - 'PWD': '/builds/slave/test', 14:32:42 INFO - 'SHELL': '/bin/bash', 14:32:42 INFO - 'SHLVL': '1', 14:32:42 INFO - 'TERM': 'linux', 14:32:42 INFO - 'TMOUT': '86400', 14:32:42 INFO - 'USER': 'cltbld', 14:32:42 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:42 INFO - '_': '/tools/buildbot/bin/python'} 14:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:32:42 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 14:32:42 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 14:32:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:42 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 14:32:42 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 14:32:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:32:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:32:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:32:44 INFO - Installing collected packages: mozsystemmonitor 14:32:44 INFO - Running setup.py install for mozsystemmonitor 14:32:44 INFO - Successfully installed mozsystemmonitor 14:32:44 INFO - Cleaning up... 14:32:44 INFO - Return code: 0 14:32:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:32:44 INFO - Using env: {'DISPLAY': ':0', 14:32:44 INFO - 'HOME': '/home/cltbld', 14:32:44 INFO - 'LANG': 'en_US.UTF-8', 14:32:44 INFO - 'LANGUAGE': 'en_US:en', 14:32:44 INFO - 'LOGNAME': 'cltbld', 14:32:44 INFO - 'MAIL': '/var/mail/cltbld', 14:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:44 INFO - 'MOZ_NO_REMOTE': '1', 14:32:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:44 INFO - 'NO_EM_RESTART': '1', 14:32:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:44 INFO - 'PWD': '/builds/slave/test', 14:32:44 INFO - 'SHELL': '/bin/bash', 14:32:44 INFO - 'SHLVL': '1', 14:32:44 INFO - 'TERM': 'linux', 14:32:44 INFO - 'TMOUT': '86400', 14:32:44 INFO - 'USER': 'cltbld', 14:32:44 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:44 INFO - '_': '/tools/buildbot/bin/python'} 14:32:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:44 INFO - Downloading/unpacking jsonschema==2.5.1 14:32:44 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 14:32:44 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 14:32:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:44 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 14:32:44 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 14:32:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:32:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:32:46 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 14:32:46 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 14:32:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:46 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 14:32:46 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 14:32:46 INFO - Downloading functools32-3.2.3-2.tar.gz 14:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:32:47 INFO - warning: no files found matching '*.txt' 14:32:47 INFO - no previously-included directories found matching 'build' 14:32:47 INFO - no previously-included directories found matching 'dist' 14:32:47 INFO - no previously-included directories found matching '.git*' 14:32:47 INFO - Installing collected packages: jsonschema, functools32 14:32:47 INFO - Running setup.py install for functools32 14:32:47 INFO - warning: no files found matching '*.txt' 14:32:47 INFO - no previously-included directories found matching 'build' 14:32:47 INFO - no previously-included directories found matching 'dist' 14:32:47 INFO - no previously-included directories found matching '.git*' 14:32:47 INFO - Successfully installed jsonschema functools32 14:32:47 INFO - Cleaning up... 14:32:47 INFO - Return code: 0 14:32:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:32:47 INFO - Using env: {'DISPLAY': ':0', 14:32:47 INFO - 'HOME': '/home/cltbld', 14:32:47 INFO - 'LANG': 'en_US.UTF-8', 14:32:47 INFO - 'LANGUAGE': 'en_US:en', 14:32:47 INFO - 'LOGNAME': 'cltbld', 14:32:47 INFO - 'MAIL': '/var/mail/cltbld', 14:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:47 INFO - 'MOZ_NO_REMOTE': '1', 14:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:47 INFO - 'NO_EM_RESTART': '1', 14:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:47 INFO - 'PWD': '/builds/slave/test', 14:32:47 INFO - 'SHELL': '/bin/bash', 14:32:47 INFO - 'SHLVL': '1', 14:32:47 INFO - 'TERM': 'linux', 14:32:47 INFO - 'TMOUT': '86400', 14:32:47 INFO - 'USER': 'cltbld', 14:32:47 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:47 INFO - '_': '/tools/buildbot/bin/python'} 14:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:32:47 INFO - Cleaning up... 14:32:47 INFO - Return code: 0 14:32:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:32:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:32:47 INFO - Using env: {'DISPLAY': ':0', 14:32:47 INFO - 'HOME': '/home/cltbld', 14:32:47 INFO - 'LANG': 'en_US.UTF-8', 14:32:47 INFO - 'LANGUAGE': 'en_US:en', 14:32:47 INFO - 'LOGNAME': 'cltbld', 14:32:47 INFO - 'MAIL': '/var/mail/cltbld', 14:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:47 INFO - 'MOZ_NO_REMOTE': '1', 14:32:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:47 INFO - 'NO_EM_RESTART': '1', 14:32:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:47 INFO - 'PWD': '/builds/slave/test', 14:32:47 INFO - 'SHELL': '/bin/bash', 14:32:47 INFO - 'SHLVL': '1', 14:32:47 INFO - 'TERM': 'linux', 14:32:47 INFO - 'TMOUT': '86400', 14:32:47 INFO - 'USER': 'cltbld', 14:32:47 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:47 INFO - '_': '/tools/buildbot/bin/python'} 14:32:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:47 INFO - Downloading/unpacking blobuploader==1.2.4 14:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:49 INFO - Downloading blobuploader-1.2.4.tar.gz 14:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:32:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:32:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:50 INFO - Downloading docopt-0.6.1.tar.gz 14:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:32:50 INFO - Installing collected packages: blobuploader, requests, docopt 14:32:50 INFO - Running setup.py install for blobuploader 14:32:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:32:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:32:50 INFO - Running setup.py install for requests 14:32:50 INFO - Running setup.py install for docopt 14:32:51 INFO - Successfully installed blobuploader requests docopt 14:32:51 INFO - Cleaning up... 14:32:51 INFO - Return code: 0 14:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:32:51 INFO - Using env: {'DISPLAY': ':0', 14:32:51 INFO - 'HOME': '/home/cltbld', 14:32:51 INFO - 'LANG': 'en_US.UTF-8', 14:32:51 INFO - 'LANGUAGE': 'en_US:en', 14:32:51 INFO - 'LOGNAME': 'cltbld', 14:32:51 INFO - 'MAIL': '/var/mail/cltbld', 14:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:51 INFO - 'MOZ_NO_REMOTE': '1', 14:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:51 INFO - 'NO_EM_RESTART': '1', 14:32:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:51 INFO - 'PWD': '/builds/slave/test', 14:32:51 INFO - 'SHELL': '/bin/bash', 14:32:51 INFO - 'SHLVL': '1', 14:32:51 INFO - 'TERM': 'linux', 14:32:51 INFO - 'TMOUT': '86400', 14:32:51 INFO - 'USER': 'cltbld', 14:32:51 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:51 INFO - '_': '/tools/buildbot/bin/python'} 14:32:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:32:51 INFO - Running setup.py (path:/tmp/pip-e4xiLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:32:51 INFO - Running setup.py (path:/tmp/pip-yhclum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:32:51 INFO - Running setup.py (path:/tmp/pip-wRQtO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:32:51 INFO - Running setup.py (path:/tmp/pip-Zb1V7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:32:51 INFO - Running setup.py (path:/tmp/pip-w34T_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:32:51 INFO - Running setup.py (path:/tmp/pip-OLg5qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:32:51 INFO - Running setup.py (path:/tmp/pip-1F33UL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:32:51 INFO - Running setup.py (path:/tmp/pip-IPjjfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:32:51 INFO - Running setup.py (path:/tmp/pip-o56yDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:32:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:32:51 INFO - Running setup.py (path:/tmp/pip-RHUlqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:32:52 INFO - Running setup.py (path:/tmp/pip-eMjspS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:32:52 INFO - Running setup.py (path:/tmp/pip-GkVPAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:32:52 INFO - Running setup.py (path:/tmp/pip-n6ZxUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:32:52 INFO - Running setup.py (path:/tmp/pip-ywxf2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:52 INFO - Running setup.py (path:/tmp/pip-bZEW3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:32:52 INFO - Running setup.py (path:/tmp/pip-MIROMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:32:52 INFO - Running setup.py (path:/tmp/pip-WQWXWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:32:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:32:52 INFO - Running setup.py install for manifestparser 14:32:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:32:52 INFO - Running setup.py install for mozcrash 14:32:52 INFO - Running setup.py install for mozdebug 14:32:52 INFO - Running setup.py install for mozdevice 14:32:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:32:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:32:52 INFO - Running setup.py install for mozfile 14:32:53 INFO - Running setup.py install for mozhttpd 14:32:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozinfo 14:32:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozInstall 14:32:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozleak 14:32:53 INFO - Running setup.py install for mozlog 14:32:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for moznetwork 14:32:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozprocess 14:32:53 INFO - Running setup.py install for mozprofile 14:32:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:32:53 INFO - Running setup.py install for mozrunner 14:32:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Running setup.py install for mozscreenshot 14:32:54 INFO - Running setup.py install for moztest 14:32:54 INFO - Running setup.py install for mozversion 14:32:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:32:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:32:54 INFO - Cleaning up... 14:32:54 INFO - Return code: 0 14:32:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:32:54 INFO - Using env: {'DISPLAY': ':0', 14:32:54 INFO - 'HOME': '/home/cltbld', 14:32:54 INFO - 'LANG': 'en_US.UTF-8', 14:32:54 INFO - 'LANGUAGE': 'en_US:en', 14:32:54 INFO - 'LOGNAME': 'cltbld', 14:32:54 INFO - 'MAIL': '/var/mail/cltbld', 14:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:54 INFO - 'MOZ_NO_REMOTE': '1', 14:32:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:54 INFO - 'NO_EM_RESTART': '1', 14:32:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:54 INFO - 'PWD': '/builds/slave/test', 14:32:54 INFO - 'SHELL': '/bin/bash', 14:32:54 INFO - 'SHLVL': '1', 14:32:54 INFO - 'TERM': 'linux', 14:32:54 INFO - 'TMOUT': '86400', 14:32:54 INFO - 'USER': 'cltbld', 14:32:54 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:54 INFO - '_': '/tools/buildbot/bin/python'} 14:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:32:54 INFO - Running setup.py (path:/tmp/pip-bgJUQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:32:54 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)) 14:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:32:54 INFO - Running setup.py (path:/tmp/pip-Jp_L3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:32:54 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)) 14:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:32:54 INFO - Running setup.py (path:/tmp/pip-HSnaYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:32:54 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)) 14:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:32:54 INFO - Running setup.py (path:/tmp/pip-OCXNBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 14:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:32:54 INFO - Running setup.py (path:/tmp/pip-u0y2hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:32:54 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)) 14:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:32:54 INFO - Running setup.py (path:/tmp/pip-61CwNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:32:55 INFO - Running setup.py (path:/tmp/pip-dKDvbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:32:55 INFO - Running setup.py (path:/tmp/pip-jPiWhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:32:55 INFO - Running setup.py (path:/tmp/pip-rnWmM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:32:55 INFO - Running setup.py (path:/tmp/pip-WGb3Fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:32:55 INFO - Running setup.py (path:/tmp/pip-LeVe47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:32:55 INFO - Running setup.py (path:/tmp/pip-l631EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:32:55 INFO - Running setup.py (path:/tmp/pip-6TVlWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:32:55 INFO - Running setup.py (path:/tmp/pip-raiOIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:55 INFO - Running setup.py (path:/tmp/pip-YShWJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:32:55 INFO - Running setup.py (path:/tmp/pip-2hHRxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:32:55 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)) 14:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:32:55 INFO - Running setup.py (path:/tmp/pip-9_eiiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:32:55 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)) 14:32:55 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)) 14:32:55 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)) 14:32:55 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)) 14:32:55 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:32:55 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:32:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:32:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:32:57 INFO - Downloading blessings-1.6.tar.gz 14:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:32:57 INFO - Installing collected packages: blessings 14:32:57 INFO - Running setup.py install for blessings 14:32:58 INFO - Successfully installed blessings 14:32:58 INFO - Cleaning up... 14:32:58 INFO - Return code: 0 14:32:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:32:58 INFO - Reading from file tmpfile_stdout 14:32:58 INFO - Current package versions: 14:32:58 INFO - argparse == 1.2.1 14:32:58 INFO - blessings == 1.6 14:32:58 INFO - blobuploader == 1.2.4 14:32:58 INFO - docopt == 0.6.1 14:32:58 INFO - functools32 == 3.2.3-2 14:32:58 INFO - jsonschema == 2.5.1 14:32:58 INFO - manifestparser == 1.1 14:32:58 INFO - mozInstall == 1.12 14:32:58 INFO - mozcrash == 1.0 14:32:58 INFO - mozdebug == 0.1 14:32:58 INFO - mozdevice == 0.49 14:32:58 INFO - mozfile == 1.2 14:32:58 INFO - mozhttpd == 0.7 14:32:58 INFO - mozinfo == 0.9 14:32:58 INFO - mozleak == 0.1 14:32:58 INFO - mozlog == 3.4 14:32:58 INFO - moznetwork == 0.27 14:32:58 INFO - mozprocess == 0.25 14:32:58 INFO - mozprofile == 0.28 14:32:58 INFO - mozrunner == 6.13 14:32:58 INFO - mozscreenshot == 0.1 14:32:58 INFO - mozsystemmonitor == 0.3 14:32:58 INFO - moztest == 0.8 14:32:58 INFO - mozversion == 1.4 14:32:58 INFO - psutil == 3.1.1 14:32:58 INFO - requests == 1.2.3 14:32:58 INFO - wsgiref == 0.1.2 14:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:32:58 INFO - Using env: {'DISPLAY': ':0', 14:32:58 INFO - 'HOME': '/home/cltbld', 14:32:58 INFO - 'LANG': 'en_US.UTF-8', 14:32:58 INFO - 'LANGUAGE': 'en_US:en', 14:32:58 INFO - 'LOGNAME': 'cltbld', 14:32:58 INFO - 'MAIL': '/var/mail/cltbld', 14:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:58 INFO - 'MOZ_NO_REMOTE': '1', 14:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:58 INFO - 'NO_EM_RESTART': '1', 14:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:58 INFO - 'PWD': '/builds/slave/test', 14:32:58 INFO - 'SHELL': '/bin/bash', 14:32:58 INFO - 'SHLVL': '1', 14:32:58 INFO - 'TERM': 'linux', 14:32:58 INFO - 'TMOUT': '86400', 14:32:58 INFO - 'USER': 'cltbld', 14:32:58 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:58 INFO - '_': '/tools/buildbot/bin/python'} 14:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 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)) 14:32:58 INFO - Cleaning up... 14:32:58 INFO - Return code: 0 14:32:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:32:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2033b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, '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': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', '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 14:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:32:58 INFO - Using env: {'DISPLAY': ':0', 14:32:58 INFO - 'HOME': '/home/cltbld', 14:32:58 INFO - 'LANG': 'en_US.UTF-8', 14:32:58 INFO - 'LANGUAGE': 'en_US:en', 14:32:58 INFO - 'LOGNAME': 'cltbld', 14:32:58 INFO - 'MAIL': '/var/mail/cltbld', 14:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:32:58 INFO - 'MOZ_NO_REMOTE': '1', 14:32:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:32:58 INFO - 'NO_EM_RESTART': '1', 14:32:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:32:58 INFO - 'PWD': '/builds/slave/test', 14:32:58 INFO - 'SHELL': '/bin/bash', 14:32:58 INFO - 'SHLVL': '1', 14:32:58 INFO - 'TERM': 'linux', 14:32:58 INFO - 'TMOUT': '86400', 14:32:58 INFO - 'USER': 'cltbld', 14:32:58 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:32:58 INFO - '_': '/tools/buildbot/bin/python'} 14:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:32:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:32:58 INFO - Cleaning up... 14:32:58 INFO - Return code: 0 14:32:58 INFO - Running post-action listener: _resource_record_post_action 14:32:58 INFO - Running post-action listener: _start_resource_monitoring 14:32:58 INFO - Starting resource monitoring. 14:32:58 INFO - [mozharness: 2017-02-17 22:32:58.907168Z] Finished create-virtualenv step (success) 14:32:58 INFO - [mozharness: 2017-02-17 22:32:58.907637Z] Running install step. 14:32:58 INFO - Running pre-action listener: _resource_record_pre_action 14:32:58 INFO - Running main action method: install 14:32:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:32:59 INFO - Reading from file tmpfile_stdout 14:32:59 INFO - Detecting whether we're running mozinstall >=1.0... 14:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:32:59 INFO - Reading from file tmpfile_stdout 14:32:59 INFO - Output received: 14:32:59 INFO - Usage: mozinstall [options] installer 14:32:59 INFO - Options: 14:32:59 INFO - -h, --help show this help message and exit 14:32:59 INFO - -d DEST, --destination=DEST 14:32:59 INFO - Directory to install application into. [default: 14:32:59 INFO - "/builds/slave/test"] 14:32:59 INFO - --app=APP Application being installed. [default: firefox] 14:32:59 INFO - mkdir: /builds/slave/test/build/application 14:32:59 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'] 14:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:33:14 INFO - Reading from file tmpfile_stdout 14:33:14 INFO - Output received: 14:33:14 INFO - /builds/slave/test/build/application/firefox/firefox 14:33:14 INFO - Running post-action listener: _resource_record_post_action 14:33:14 INFO - [mozharness: 2017-02-17 22:33:14.633555Z] Finished install step (success) 14:33:14 INFO - [mozharness: 2017-02-17 22:33:14.633834Z] Running run-tests step. 14:33:14 INFO - Running pre-action listener: _resource_record_pre_action 14:33:14 INFO - Running main action method: run_tests 14:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:33:14 INFO - Python 2.7.3 14:33:14 INFO - Return code: 0 14:33:14 INFO - grabbing minidump binary from tooltool 14:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:14 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 0x2031290>, '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 0x2031b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2032040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:33:14 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 14:33:14 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 14:33:14 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 14:33:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:33:14 INFO - Return code: 0 14:33:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:33:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:33:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:33:14 INFO - ENV: RUST_BACKTRACE is now 1 14:33:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:33:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:33:14 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/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 14:33:14 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/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 14:33:14 INFO - Using env: {'DISPLAY': ':0', 14:33:14 INFO - 'HOME': '/home/cltbld', 14:33:14 INFO - 'LANG': 'en_US.UTF-8', 14:33:14 INFO - 'LANGUAGE': 'en_US:en', 14:33:14 INFO - 'LOGNAME': 'cltbld', 14:33:14 INFO - 'MAIL': '/var/mail/cltbld', 14:33:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:33:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:14 INFO - 'MOZ_NO_REMOTE': '1', 14:33:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:33:14 INFO - 'NO_EM_RESTART': '1', 14:33:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:14 INFO - 'PWD': '/builds/slave/test', 14:33:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:33:14 INFO - 'RUST_BACKTRACE': '1', 14:33:14 INFO - 'SHELL': '/bin/bash', 14:33:14 INFO - 'SHLVL': '1', 14:33:14 INFO - 'TERM': 'linux', 14:33:14 INFO - 'TMOUT': '86400', 14:33:14 INFO - 'USER': 'cltbld', 14:33:14 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059', 14:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:14 INFO - '_': '/tools/buildbot/bin/python'} 14:33:14 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/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 14:33:14 INFO - mozversion application_buildid: 20170217214422 14:33:14 INFO - mozversion application_changeset: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 14:33:14 INFO - mozversion application_display_name: Nightly 14:33:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:33:14 INFO - mozversion application_name: Firefox 14:33:14 INFO - mozversion application_remotingname: firefox 14:33:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:33:14 INFO - mozversion application_vendor: Mozilla 14:33:14 INFO - mozversion application_version: 54.0a1 14:33:14 INFO - mozversion platform_buildid: 20170217214422 14:33:14 INFO - mozversion platform_changeset: d11c29c1db3a1bc96ad5792ebf8a89b2fbadcf85 14:33:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:33:14 INFO - mozversion platform_version: 54.0a1 14:33:14 INFO - using testdate: 1487370794 14:33:14 INFO - actual date: 1487370794 14:33:14 INFO - starting webserver on 'localhost:33631' 14:33:14 INFO - SUITE-START | Running 2 tests 14:33:14 INFO - TEST-START | dromaeo_css 14:33:14 INFO - operating with platform_type : linux_ 14:33:14 INFO - Initialising browser for dromaeo_css test... 14:33:14 INFO - TEST-INFO | started process 12370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6d4Z55/profile http://localhost:33631/getInfo.html) 14:33:18 INFO - PROCESS | 12370 | Iterating over profile /tmp/tmp6d4Z55/profile 14:33:18 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:18 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/extensions.json = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:18 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:18 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/safebrowsing = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:18 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:18 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/cert8.db = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:18 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:18 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/directoryLinks.json = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:18 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:18 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/bookmarkbackups = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | Stat failure 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/gmp = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:33:18 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | __metrics Screen width/height:1600/1200 14:33:19 INFO - PROCESS | 12370 | colorDepth:24 14:33:19 INFO - PROCESS | 12370 | Browser inner width/height: 1024/697 14:33:19 INFO - PROCESS | 12370 | __metrics 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/pluginreg.dat = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/addons.json = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/.parentlock = Fri Feb 17 2017 14:33:14 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/compatibility.ini = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/localstore.rdf = Fri Feb 17 2017 14:32:33 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/user.js = Fri Feb 17 2017 14:33:14 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/key3.db = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/frequencyCap.json = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/places.sqlite-shm = Fri Feb 17 2017 14:33:16 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/places.sqlite = Fri Feb 17 2017 14:33:19 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/extensions = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/places.sqlite-wal = Fri Feb 17 2017 14:33:19 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/minidumps = Fri Feb 17 2017 14:33:14 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/startupCache = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/extensions.ini = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/prefs.js = Fri Feb 17 2017 14:33:19 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/thumbnails = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/content-prefs.sqlite = Fri Feb 17 2017 14:33:17 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/blocklist.xml = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/cache2 = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/crashes = Fri Feb 17 2017 14:33:14 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/secmod.db = Fri Feb 17 2017 14:33:15 GMT-0800 (PST) 14:33:19 INFO - PROCESS | 12370 | No birth date. Using mtime. 14:33:19 INFO - PROCESS | 12370 | Using date: /tmp/tmp6d4Z55/profile/permissions.sqlite = Fri Feb 17 2017 14:33:16 GMT-0800 (PST) 14:33:20 ERROR - PROCESS | 12370 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 14:33:21 INFO - TEST-INFO | 12370: exit 0 14:33:21 INFO - Browser initialized. 14:33:21 INFO - Running cycle 1/1 for dromaeo_css test... 14:33:21 INFO - TEST-INFO | started process 12503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6d4Z55/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:33:33 INFO - PROCESS | 12503 | RSS: Main: 153452544 14:33:33 INFO - PROCESS | 12503 | 14:36:37 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:33631/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33631/tests/dromaeo/cssquery-ext.html) 14:36:39 INFO - PROCESS | 12503 | RSS: Main: 165756928 14:36:39 INFO - PROCESS | 12503 | 14:38:21 INFO - PROCESS | 12503 | ************************* 14:38:21 INFO - PROCESS | 12503 | A coding exception was thrown and uncaught in a Task. 14:38:21 INFO - PROCESS | 12503 | 14:38:21 ERROR - PROCESS | 12503 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:38:21 INFO - PROCESS | 12503 | Full stack: 14:38:21 INFO - PROCESS | 12503 | ************************* 14:39:42 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:33631/tests/dromaeo/cssquery-ext.html (next: http://localhost:33631/tests/dromaeo/cssquery-jquery.html) 14:39:42 INFO - PROCESS | 12503 | RSS: Main: 158179328 14:39:42 INFO - PROCESS | 12503 | 14:42:44 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:33631/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33631/tests/dromaeo/cssquery-mootools.html) 14:42:45 INFO - PROCESS | 12503 | RSS: Main: 167641088 14:42:45 INFO - PROCESS | 12503 | 14:45:47 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:33631/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33631/tests/dromaeo/cssquery-prototype.html) 14:45:48 INFO - PROCESS | 12503 | RSS: Main: 158478336 14:45:48 INFO - PROCESS | 12503 | 14:48:51 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:33631/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33631/tests/dromaeo/cssquery-yui.html) 14:48:51 INFO - PROCESS | 12503 | RSS: Main: 162312192 14:48:51 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:33631/tests/dromaeo/cssquery-yui.html (next: http://localhost:33631/tests/dromaeo/cssquery-dojo.html) 14:51:54 INFO - PROCESS | 12503 | RSS: Main: 163811328 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | __start_tp_report 14:51:54 INFO - PROCESS | 12503 | _x_x_mozilla_page_load 14:51:54 INFO - PROCESS | 12503 | _x_x_mozilla_page_load_details 14:51:54 INFO - PROCESS | 12503 | |i|pagename|runs| 14:51:54 INFO - PROCESS | 12503 | |0;dojo.html;2573.961390579141;2602.7787638050763;2605.9609105863406;2619.9607005894914;2634.7496987786153;4759.167145749498;4812.807487700487;4855.490173531777;4867.561919427251;4886.8778280543;4800.255960326155;4880.926786098203;4885.584725298345;4930.778114984825;4946.678465899723;4420.557944205586;4469.977650111745;4476.216662084138;4493.550644935497;4537.546245375469;4474.597286244238;4553.248713962209;4553.817847286105;4565.315202719586;4573.7027093239;4382.868513944555;4399.12017596482;4540.8864778380475;4594.931076033862;4602.677812553123;4417.18282117806;4419.381286619876;4488.865334039984;4524.68327217095;4539.659525535565;4413.051193993275;4432.933505997413;4461.933071003938;4473.552644735533;4488.438945131858;4805.711657300573;4869.70781753096;4882.072406242838;4888.193448081062;4902.3626928499425;5069.518395752398;5119.795208191668;5150.871228219287;5216.37403511581;5227.137522308853;5366.865828354284;5373.919391209135;5381.139017757179;5386.084365657847;5425.104857698475;4971.304017437562;4978.079055374767;4989.750512474435;5019.096562618691;5020.046191223656;5476.397596264408;5500.532454741312;5502.257195278587;5504.366997795305;5521.061419558685;5059.8988020239385;5106.029854327666;5106.029854327666;5175.741212939413;5264.763085661154;5194.7922083116255;5227.3465816772905;5233.058049551118;5247.449017853147;5300.522952934254;5216.191490318931;5217.217417387422;5219.217117432339;5234.659747116425;5253.133233016586;5001.199808030698;5029.220470827027;5159.458256883048;5195.922061169085;5226.111561034633;4446.888827779331;4482.058767658763;4483.14820184164;4504.954950450519;4544.204764166324;3834.9616503834604;3856.151646637735;3860.864869729602;3899.6490315871706;3899.6880249579967;4439.933400998988;4567.1550763108935;4624.907501849944;4630.421197350332;4661.3707149534375;5149.48505149482;5245.423003469616;5250.789968401297;5291.682499050069;5340.813071542478;4575.313702944519;4591.701539399995;4592.058627981256;4592.7014744041535;4637.258038713857;4761.071591039714;4762.047590481918;4773.856784296477;4774.880627984258;4808.687435316763;4791.496892826342;4835.323054772264;4840.322354870251;4885.731284779371;4895.167821470287;5306.044911915892;5340.519353258225;5344.679319240858;5366.6243362964215;5429.918551221814;4855.223164293696;4898.510148985216;4906.509349065065;4950.108980383421;4966.602671786321;5097.668651537638;5109.8722531937;5129.307543481582;5142.948570514247;5146.305248791366;5219.243209734643;5223.503767142115;5229.529342359206;5232.189010703347;5232.712200828991;5083.2883396323805;5089.6691715038405;5094.057599344133;5095.337606111182;5098.2862399263395;5073.416557095834;5080.263361812443;5081.695098294114;5082.593392528515;5154.329937108152;4916.557509824133;4967.155583550732;5025.572826309804;5070.594352451869;5112.590992720648;5106.438291787975;5109.795608175631;5151.1243088675765;5159.148740457783;5199.48005199477;5002.499750024968;5045;5049.7727602257255;5052.040112378636;5100.209467532539 14:51:54 INFO - PROCESS | 12503 | |1;ext.html;523.8873642166934;538.284082170713;554.0746952589176;554.8002719021151;620.1101419463074;32714.345713085753;36836.63163368365;36899.26201475972;36978;36988.630113698855;36930;37356.25287494248;37416.625833741724;37479.62520374796;37508.812455937754;7520.360769334613;7736.1877002914725;7750.7674769756995;7751.961240193791;8676.566171691422;8660.480371177719;8866.955665221698;8872.37893347466;8887.911120888806;8897.68858089967;8891.199792018717;8900.37697361185;8902.643894244222;8918.73243802687;8975.95512022439;8895.911040889572;8939.32955028369;8963.058878817728;8975.506347150904;8984.955075224647;9045.502497362642;9104.453732775988;9114.225290850285;9164.633414663405;9173.862392064124;31478.37043259122;31631.367372652418;31646.841765791254;31767;31836.497985221606;3307.7684562080663;3360.6573725044414;3398.7790793598338;3418.3505134024454;3432.1591210153424;24034.27897163088;24122.39694007646;24166.637500437508;24622.384440388956;24808;3263.2494526258856;3381.543491628623;3382.3404436134965;3437.242256516246;3471.060346506708;216743;218372.9999999984;218579;218951;219965;37444.62555374411;38513.229735405694;43815.34276985848;44418.55581444209;52270.47729522732;51782.22326665103;52072;56091.439085608625;56521.43478565162;56943;12840.678983025498;12956.67608309781;12980.221186728826;12998.220106793433;13027.804582931263;10361.222908281758;10367.377957322586;10445.373277603368;10477.633282835064;10477.738056548495;18265.17806698675;18485.260589576534;18524.25902963893;18533.443996680122;18549.721754173697;6811.352921472452;7080.150381954096;7220.530665506725;7297.233790452032;7435.888461673079;743.1379599664364;750.4746677325894;761.432732614206;773.1688434932357;778.4200770426069;25523.489530209292;26620.467590648466;26880.193594192206;27020.864895675397;27117.728822711913;929.697848199338;935.5556110847293;944.5654998700675;946.8978866915437;983.8573406856109;13215.60353189406;13713.588592342247;14062.71874562503;14224.288785560888;14377.6405589859;335180;335255;336935;340423;340504;40927.38608920869;41046.589534104874;41085;41219.96950076212;41260.381094283606;1140.7547377313917;1191.7914364986266;1198.8321634971112;1204.7470031293526;1206.7827790997706;23450.765492344857;23869.522609548058;24555.631665524674;24590.631140532547;24758.38104047413;35929.82035089808;36207.275854482235;36437.635623644484;36532.63467365292;36619;292296;294626;295053;295497;298975;22652;22944.196953106562;23631.291061268188;23637.409064773517;24748.752512474646;34764.82617586896;34775.304493909476;35526.822365888;35537.28925421529;35652.108697281736;28865.71134288714;29306.853465732536;29406;30014.69985300119;30474.238144046576;28836.855815720788;29064;29096;29232.41535169327;30075.097747067623;29646.11061668067;30179.849100755233;30271.789128434615;30465.695343046285;30563.694363056085;25219;27025.1892443227;27090.510021948983;27295.86352068227;27782.861085694443;21158.10369102693;21198.36404907855;21389.037493312528;21510.462238444165;21544.353669389944 14:51:54 INFO - PROCESS | 12503 | |2;jquery.html;381.78619972815244;392.6070599694862;392.84089943572843;401.4259608759474;417.4614746976401;12069.336186509738;12446.626601201955;17197.22612482438;17722.822771772277;17829;13022.413991370388;18158.63682726348;18167.364142255024;18234.361797337097;18522.907385463088;5044.924326135111;5281.9207711884355;5282.022825777224;5289.735513224343;5300.469953004707;5044.571211447032;5236.659617124894;5256.053910296145;5299.735013249341;5300.178472336775;5238.738063096849;5253.133233016548;5259.395169555492;5279.788808447657;5289.576833853302;5048.217526283432;5218.817341393033;5262.947370526284;5286.656367336111;5290.312259406291;4755.762211889391;4765.451973023112;4770.904581908377;4779.522047795227;4783.976080119577;21056.894715526323;21559.892200539052;21667.67498487524;21850.453738656503;22073.779262207332;2366.7396586375485;2489.9751002489925;2516.937076573082;2528.759767822054;2544.6819147606548;13139;13619.523316683868;13900.791488127687;14309.427622895071;14398;1641.532163333456;1713.366054559821;1731.4026660802128;1744.9912750436295;1861.1810803246663;72900;73494.63252683755;75104.24895751027;75765;76897;10437.32157409766;10553.630622928315;10591.205659575562;10725.678229653124;10864.185186110915;15289.541313760605;15340.622203558243;15340.92329538345;15409.152496612569;15425;6966.268541803038;7036.014957905897;7287.890681639779;7292.307230813064;7335.596542190231;6479.254885688112;6504.544681872224;6523;6720.563163394364;6738.764143254963;4614.76926153691;4624.28323609841;4680.297955306732;4698.248280275139;4724.551167639069;1862.8789128706592;1944.0765636322633;1975.427126133434;1984.176566724822;1993.4318719165258;935.7520257131865;965.3773316211004;972.2805124208035;978.6966040527459;980.127686359141;16713.247903844356;17319;17335.393261236048;18066.728999065024;18429.723554146698;922.5617831530104;932.6922115701802;936.4147407870081;954.9188318992964;965.4448692002127;9420.811583768285;9967;10029.949850250701;10071.841808135287;10173.898261017443;452365;463766;482937;484934;485961;64022.67988660027;64989;70202.29797701958;84224;86180.56909715412;910.7859652981545;912.056022017209;947.0482165423842;949.3971328206638;950.7813202963218;15075.396984120951;15159.848401516285;15599.766003509729;15892.284847182136;16045.512945493467;37238.25523489569;37391.43912841255;37483.625163748016;37515.249695006496;37543.43684844675;46396.53603463922;48503.27245091256;48694;48745;48831;7915.9208407915185;7935.2064793520185;7998.360131189607;8033;8752.343574232085;9132.771680708036;12063.81904271419;12141.15011949155;12311.13822032485;12322.691932701755;10204.744881378025;10249.692509224735;10278.936964638664;10741.24811263204;10748.838767418338;10166.135878450414;10475.633352832614;10531.368117912949;10551.841722374016;10882.455877206266;10418.895811042095;10458.372497650165;10827.566897324019;10832.133429325793;11165.106791456501;7939.84120317602;8066.153053929487;8124.67501299965;8431.072582015851;8495.95752021236;9361.485118318558;9483.14651681352;9948.054934781185;10038.698839034842;10941.45292735344 14:51:54 INFO - PROCESS | 12503 | |3;mootools.html;1352.5401363536396;1358.252960871521;1365.5015919189486;1373.6016555198992;1375.3054707372776;7096.325849044346;7493.288137626931;7522.736704215346;7530.284622960817;7727.72952946647;7607.581583012932;7686.769396918087;7687.192844751305;7717.807054823618;7720.652570634321;1967.1049672399024;1994.8803071815737;2007.3977806658017;2011.8792872427628;2016.6168427998707;1918.4436513411094;1979.1094007696522;1984.0575726529928;1989.8109679580416;1992.9402117936486;1919.4913347962802;1944.7471828662324;1963.8904019229162;1967.1836187982042;1969.8719583227116;1960.794116617756;1972.339266345783;1973.8124878136555;1975.3481351154085;1976.357683752786;1930.9903450482798;1988.1947811136513;1992.950176245591;2004.4587961250381;2034.888081155536;7672.232776722283;7735.071791385069;7742.922570774333;7749;7754.302112809818;4295.849645262432;4457.777111144463;4466.039801442673;4471.552844715502;4471.731696098244;2526.6967963844454;2635.9604605930926;2678.9464210715873;2681.6647919010124;2695.6226128342046;1058.164050400183;1083.1334932054403;1086.4839201379361;1099.1042300525046;1120.467777805544;8651.394402391836;9041.954790226073;10108.292419530635;10114.089731924161;10190.337628054152;3652.8538858445895;3756.549214094326;3769.4911186989407;3802.9239415212096;3806.06751345922;8167.387445941578;8179.141190174946;8185.222403871623;8200.343972482187;8204.58977051157;4342.32693932441;4348.28253338202;4362.738235705868;4364.279893817485;4367.912641747147;3914.9804250978564;3986.123052928351;4039.434479172918;4058.2492238935893;4088.141490287073;3135.639401468815;3178.3484385700876;3201.6478187399366;3242.821644809558;3252.4308246056844;930.1767935377239;948.2793077261331;965.7392504024015;966.2462630325936;969.8981606931312;755.8186035351476;766.7239793674273;777.7355699062233;785.2971590426537;786.3669745854528;4616.884577885579;4631.467381251199;4695.694779839299;4796.184648609745;4841.080194762983;496.9652124351333;511.34037092151453;516.2849453506919;517.6221358408308;524.2529395611239;2999.970000299969;3057.143999680093;3059.663437021926;3086.66046734859;3127.702868227515;8852.203301702877;9254.583543740548;9254.86117708235;9266.675666351646;9288.071192880792;9098.727038188865;9105.681301154427;9135.77160570991;9178.082191780768;9187.908120918839;6764.3573860483175;7138.678759455837;7182.7126914923865;7206;7207.567545947155;4847.684900481458;4986.825461108989;5145.176771716508;5204.115300398866;5252;8050.114487406264;8182.549959752271;8190.631421586162;8302.045264794626;8314.625841837253;7476.962615186889;8269.255766981001;8398.874016889873;8526.104759000464;8536.871946920677;5791.276090488689;6474.935250647433;6524.5432819704065;6629.303923088007;6631.966840165961;6973.3375329343635;7283.453740969342;7316.487845850739;7318.597477138808;7359.595222262827;6494.350564943468;6590.373914478117;6603.075569420189;6610.405063544112;6705.865882682221;6420.302360727976;6466.353364663496;6471.838204044937;6627.900581491185;6679.666016699049;5131.358580177478;5149.0474262261605;5182.24857395683;5190.6107041971245;5206.765695543634;4196.853110141131;4218.894527636834;4320.330348795942;4332.523422423594;4421.911561768811;4381.802818873095;4382.58365455294;4402.229609818333;4414.977925110354;4540.863774086651 14:51:54 INFO - PROCESS | 12503 | |4;prototype.html;325.51173240139775;337.8158903397648;338.2052177383149;338.67318038093237;342.7686311739576;4845.632285561527;4964.156093464111;4994.825181118662;5069.645124841258;5095.43950165482;4902.4607293197805;4975.184057818898;4976.427710813257;4994.427869647135;5050.419201791795;3995.62041606047;4004.899877503057;4015.6787457003443;4015.7791321477307;4023.7384570002855;3689.8524059037604;4002.75983440993;4004.799760011988;4020.8592699255532;4021.296273152201;3820.36963900956;3828.5979972102946;3840.9423858642144;3847.4228865670093;3857.9228415431676;3818.4845045918723;3836.040989752576;3848.5766565677754;3850.653441190292;3857.614238576148;3358.7313014958745;3361.344537815127;3372.0726800129914;3386.255023894739;3395.6604339566093;4749.453812811536;4924;4927.965127323267;4954.355933728592;4999.700017998932;4381.956180438218;4389.056352884145;4393.132918509653;4408.559144085598;4409.250427427313;5244.790208391698;5248.381424346589;5250.658707184059;5267.894642107175;5270.868228294285;3570.321638888603;3656.597774244831;3668.3396988542054;3678;3678.6873115785193;5493.478119578634;5537.335519737617;5544;5546.611737178399;5552.555795536347;3692.7415080944074;3705.9073523162137;3716.646918542734;3720.2187540616233;3721.404575267944;3781.6596506314563;3791.3933770596573;3792.4311353296675;3799.6200379961783;3809.504764380613;4254.808533615995;4361.629261512807;4374.321980093091;4400.251957167226;4410.757408342572;5306.52241298285;5316.893662126736;5324.6805191687845;5339.385970613429;5341.118715411992;1229.1887354346088;1249.4065318973596;1252.8935040521476;1254.83927367185;1274.7386785708907;818.2594751749676;850.6299759604592;856.4047986649219;857.8412993596205;859.2481578618708;4481.641468682563;4496.64026877849;4498.437695288089;4498.977505112453;4534.478534968455;5239.659422137549;5250.632455728062;5272.261883336336;5286.629935904527;5290.92063619046;4537.931931021038;4541.591256786905;4549.431321084864;4566.086782643484;4589.839355622537;2630.5396555603074;2809.8735556899987;2820.830750154997;2822.8870845166375;2828.4343131373807;4982.975085124551;4995.300657907896;4996.625253106032;5018.444282272507;5029.547340739351;5243.4232234454175;5418.022421816087;5443.101888188484;5467.316585426822;5470.538257783934;4921.876953076058;4924.3105965164905;4926.211806111077;4938.2839488274;4938.777755001033;5221.712805795718;5225.817096401608;5289.5503882168905;5315.787368505216;5341.759620816995;4957.752112394438;5213.791448342024;5227.61468210932;5245.973770131125;5247.55395791362;4920.975395123001;4947.975260123676;4971.850844474671;4987.326710894128;5004.649674522894;3621.6378362163573;3636.927261454809;3665.9266814663024;3670.394384926564;3694.0949467380665;5157.510036546522;5318.361796584435;5327.334083239595;5334.973325133349;5451.509364157245;5287.603429742708;5295.90189739007;5304.734763261744;5310.442403547572;5335.546478549211;5310.946890531045;5319.893602128013;5320.14877619579;5349.786008559614;5350.4382039886805;5069.543741063322;5179.326687530597;5223.817166399158;5255;5270.683758974474;5375.56995440356;5389.622726409114;5400.972995134999;5410.188471729272;5429.375621803385;5270.209468579744;5280.20796880471;5285.2336411219385;5311.787528498971;5323.707196104251 14:51:54 INFO - PROCESS | 12503 | |5;yui.html;642.9485641148708;653.6466340669916;719.6365835253197;734.8677238097142;750.613434081448;11517.136214783883;11653.184277100607;11713.941430292858;11769.941150294248;11861.703457413569;12101.455434505446;12305.815412768794;12378.690532736686;12420.627381178556;12425;806.1656185847648;825.533573530953;827.7020272701852;828.5443006346516;828.8508068547659;816.2939057715064;832.7626626411474;838.9622466988969;845.1168528887297;847.4618617178074;882.6557642519423;885.1900511032397;887.5429153985702;888.404768804899;888.8666699994988;866.3545658484406;875.7810547363159;878.1438097854605;878.5431575580662;881.0528681667201;642.1235014205575;643.9195100612424;646.0309535696455;648.6108334998963;649.2792999770252;13474.191548507022;13504.594862154152;13816.171029738249;13874.236916969563;13932.442702291895;12971.449418043921;13648.522301719393;13794.241316727575;13836.377363018688;13843.30783460823;9705.466199359033;9716.854247186298;9798.216142708567;9946.35348702332;9998.900010999869;3295.2750394913082;3310.2220978070136;3310.2717402171484;3320.8007519548905;3432.7597068205237;60949.86675466337;60954.695226524025;61041.38958610446;61100.69449652768;61811.381886181014;9704.369216001078;9815.312928095107;10060.346077504939;10082.44546549932;10331.380117192992;19677;19806.801931980495;19816.504587385432;19852.50368740793;19872.801271987384;6302.779402720975;6451.228355999692;6477.914388664865;6695.4308883745425;6710;3366.915827104342;3412.744044196695;3430.1596108952967;3444.4661077533024;3557.7865328080397;2728.563429851214;2796;2815.42283831814;2820.0411859967708;2822.463731890934;470.80885360039156;474.9976250118796;476.7473239183169;476.8235752771497;483.47059969333526;597.4742226840352;620.950323974081;621.8352136683692;622.6886556721639;633.1389310537666;8066.556339401389;8126.674933002732;8322.791930201793;8395.24442800151;8466.195711407407;566.8157848699107;581.9272590926134;596.6092209602717;600.7236671131241;600.9519238460913;3834.559025712079;3975.5030621172355;4035.9506528302854;4065.1463192729857;4156.106437115974;59535.404645952985;60146;62320.37679623236;63488.36511634824;63926.36073639204;53613.195802063;54046.45953540493;54967.725161374736;55019.17471237935;56958.71520642453;48504.27243591349;49335;50041;50707;51418.74290628523;7315.709528827916;7366.400063666243;7647.235276472531;7695.307422332017;7842.882356764539;34761.65238347584;35176.64823351836;36083.27833443264;36336;36589.634103659686;41047;44110.33834492549;44238.557614423444;44642.55357446384;45346.54653453423;10012.499375031364;10108.342957707726;10156.593736250466;10244.692659220234;10581.418022008864;10471.267011309135;10675.786484270116;10790.838137427787;10823.621173258898;11009.834852477059;9630.903690963001;9675.806483870143;9949.751256218653;10288.639897603547;10308.889880509574;8996.415233009833;9151.084891510796;9206.125418085272;9220.44677319363;9261.305402094735;8970.551472426483;9081.500517471339;9314.301427392837;9583.424994500352;9702.563384647849;6909.481788865955;7223.8555228896175;7403.629818509161;7533.359664428366;7639.656215470428;7492.925070749441;7965.163657815846;9214.44713317203;9469.810603787748;9533.112714804123 14:51:54 INFO - PROCESS | 12503 | __end_tp_report 14:51:54 INFO - PROCESS | 12503 | __start_cc_report 14:51:54 INFO - PROCESS | 12503 | _x_x_mozilla_cycle_collect,380 14:51:54 INFO - PROCESS | 12503 | __end_cc_report 14:51:54 INFO - PROCESS | 12503 | __startTimestamp1487371914783__endTimestamp 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | ------- Summary: start ------- 14:51:54 INFO - PROCESS | 12503 | Number of tests: 6 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | [#0] dojo.html Cycles:165 Average:4845.09 Median:4971.30 stddev:528.75 (10.6%) stddev-sans-first:499.45 14:51:54 INFO - PROCESS | 12503 | Values: 2574.0 2602.8 2606.0 2620.0 2634.7 4759.2 4812.8 4855.5 4867.6 4886.9 4800.3 4880.9 4885.6 4930.8 4946.7 4420.6 4470.0 4476.2 4493.6 4537.5 4474.6 4553.2 4553.8 4565.3 4573.7 4382.9 4399.1 4540.9 4594.9 4602.7 4417.2 4419.4 4488.9 4524.7 4539.7 4413.1 4432.9 4461.9 4473.6 4488.4 4805.7 4869.7 4882.1 4888.2 4902.4 5069.5 5119.8 5150.9 5216.4 5227.1 5366.9 5373.9 5381.1 5386.1 5425.1 4971.3 4978.1 4989.8 5019.1 5020.0 5476.4 5500.5 5502.3 5504.4 5521.1 5059.9 5106.0 5106.0 5175.7 5264.8 5194.8 5227.3 5233.1 5247.4 5300.5 5216.2 5217.2 5219.2 5234.7 5253.1 5001.2 5029.2 5159.5 5195.9 5226.1 4446.9 4482.1 4483.1 4505.0 4544.2 3835.0 3856.2 3860.9 3899.6 3899.7 4439.9 4567.2 4624.9 4630.4 4661.4 5149.5 5245.4 5250.8 5291.7 5340.8 4575.3 4591.7 4592.1 4592.7 4637.3 4761.1 4762.0 4773.9 4774.9 4808.7 4791.5 4835.3 4840.3 4885.7 4895.2 5306.0 5340.5 5344.7 5366.6 5429.9 4855.2 4898.5 4906.5 4950.1 4966.6 5097.7 5109.9 5129.3 5142.9 5146.3 5219.2 5223.5 5229.5 5232.2 5232.7 5083.3 5089.7 5094.1 5095.3 5098.3 5073.4 5080.3 5081.7 5082.6 5154.3 4916.6 4967.2 5025.6 5070.6 5112.6 5106.4 5109.8 5151.1 5159.1 5199.5 5002.5 5045.0 5049.8 5052.0 5100.2 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | [#1] ext.html Cycles:180 Average:42228.45 Median:170147.83 stddev:75704.12 (44.5%) stddev-sans-first:75851.73 14:51:54 INFO - PROCESS | 12503 | Values: 523.9 538.3 554.1 554.8 620.1 32714.3 36836.6 36899.3 36978.0 36988.6 36930.0 37356.3 37416.6 37479.6 37508.8 7520.4 7736.2 7750.8 7752.0 8676.6 8660.5 8867.0 8872.4 8887.9 8897.7 8891.2 8900.4 8902.6 8918.7 8976.0 8895.9 8939.3 8963.1 8975.5 8985.0 9045.5 9104.5 9114.2 9164.6 9173.9 31478.4 31631.4 31646.8 31767.0 31836.5 3307.8 3360.7 3398.8 3418.4 3432.2 24034.3 24122.4 24166.6 24622.4 24808.0 3263.2 3381.5 3382.3 3437.2 3471.1 216743.0 218373.0 218579.0 218951.0 219965.0 37444.6 38513.2 43815.3 44418.6 52270.5 51782.2 52072.0 56091.4 56521.4 56943.0 12840.7 12956.7 12980.2 12998.2 13027.8 10361.2 10367.4 10445.4 10477.6 10477.7 18265.2 18485.3 18524.3 18533.4 18549.7 6811.4 7080.2 7220.5 7297.2 7435.9 743.1 750.5 761.4 773.2 778.4 25523.5 26620.5 26880.2 27020.9 27117.7 929.7 935.6 944.6 946.9 983.9 13215.6 13713.6 14062.7 14224.3 14377.6 335180.0 335255.0 336935.0 340423.0 340504.0 40927.4 41046.6 41085.0 41220.0 41260.4 1140.8 1191.8 1198.8 1204.7 1206.8 23450.8 23869.5 24555.6 24590.6 24758.4 35929.8 36207.3 36437.6 36532.6 36619.0 292296.0 294626.0 295053.0 295497.0 298975.0 22652.0 22944.2 23631.3 23637.4 24748.8 34764.8 34775.3 35526.8 35537.3 35652.1 28865.7 29306.9 29406.0 30014.7 30474.2 28836.9 29064.0 29096.0 29232.4 30075.1 29646.1 30179.8 30271.8 30465.7 30563.7 25219.0 27025.2 27090.5 27295.9 27782.9 21158.1 21198.4 21389.0 21510.5 21544.4 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | [#2] jquery.html Cycles:180 Average:26912.35 Median:4619.53 stddev:77826.58 (1684.7%) stddev-sans-first:78019.41 14:51:54 INFO - PROCESS | 12503 | Values: 381.8 392.6 392.8 401.4 417.5 12069.3 12446.6 17197.2 17722.8 17829.0 13022.4 18158.6 18167.4 18234.4 18522.9 5044.9 5281.9 5282.0 5289.7 5300.5 5044.6 5236.7 5256.1 5299.7 5300.2 5238.7 5253.1 5259.4 5279.8 5289.6 5048.2 5218.8 5262.9 5286.7 5290.3 4755.8 4765.5 4770.9 4779.5 4784.0 21056.9 21559.9 21667.7 21850.5 22073.8 2366.7 2490.0 2516.9 2528.8 2544.7 13139.0 13619.5 13900.8 14309.4 14398.0 1641.5 1713.4 1731.4 1745.0 1861.2 72900.0 73494.6 75104.2 75765.0 76897.0 10437.3 10553.6 10591.2 10725.7 10864.2 15289.5 15340.6 15340.9 15409.2 15425.0 6966.3 7036.0 7287.9 7292.3 7335.6 6479.3 6504.5 6523.0 6720.6 6738.8 4614.8 4624.3 4680.3 4698.2 4724.6 1862.9 1944.1 1975.4 1984.2 1993.4 935.8 965.4 972.3 978.7 980.1 16713.2 17319.0 17335.4 18066.7 18429.7 922.6 932.7 936.4 954.9 965.4 9420.8 9967.0 10029.9 10071.8 10173.9 452365.0 463766.0 482937.0 484934.0 485961.0 64022.7 64989.0 70202.3 84224.0 86180.6 910.8 912.1 947.0 949.4 950.8 15075.4 15159.8 15599.8 15892.3 16045.5 37238.3 37391.4 37483.6 37515.2 37543.4 46396.5 48503.3 48694.0 48745.0 48831.0 7915.9 7935.2 7998.4 8033.0 8752.3 9132.8 12063.8 12141.2 12311.1 12322.7 10204.7 10249.7 10278.9 10741.2 10748.8 10166.1 10475.6 10531.4 10551.8 10882.5 10418.9 10458.4 10827.6 10832.1 11165.1 7939.8 8066.2 8124.7 8431.1 8496.0 9361.5 9483.1 9948.1 10038.7 10941.5 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | [#3] mootools.html Cycles:180 Average:4809.07 Median:2749.08 stddev:2740.77 (99.7%) stddev-sans-first:2736.15 14:51:54 INFO - PROCESS | 12503 | Values: 1352.5 1358.3 1365.5 1373.6 1375.3 7096.3 7493.3 7522.7 7530.3 7727.7 7607.6 7686.8 7687.2 7717.8 7720.7 1967.1 1994.9 2007.4 2011.9 2016.6 1918.4 1979.1 1984.1 1989.8 1992.9 1919.5 1944.7 1963.9 1967.2 1969.9 1960.8 1972.3 1973.8 1975.3 1976.4 1931.0 1988.2 1993.0 2004.5 2034.9 7672.2 7735.1 7742.9 7749.0 7754.3 4295.8 4457.8 4466.0 4471.6 4471.7 2526.7 2636.0 2678.9 2681.7 2695.6 1058.2 1083.1 1086.5 1099.1 1120.5 8651.4 9042.0 10108.3 10114.1 10190.3 3652.9 3756.5 3769.5 3802.9 3806.1 8167.4 8179.1 8185.2 8200.3 8204.6 4342.3 4348.3 4362.7 4364.3 4367.9 3915.0 3986.1 4039.4 4058.2 4088.1 3135.6 3178.3 3201.6 3242.8 3252.4 930.2 948.3 965.7 966.2 969.9 755.8 766.7 777.7 785.3 786.4 4616.9 4631.5 4695.7 4796.2 4841.1 497.0 511.3 516.3 517.6 524.3 3000.0 3057.1 3059.7 3086.7 3127.7 8852.2 9254.6 9254.9 9266.7 9288.1 9098.7 9105.7 9135.8 9178.1 9187.9 6764.4 7138.7 7182.7 7206.0 7207.6 4847.7 4986.8 5145.2 5204.1 5252.0 8050.1 8182.5 8190.6 8302.0 8314.6 7477.0 8269.3 8398.9 8526.1 8536.9 5791.3 6474.9 6524.5 6629.3 6632.0 6973.3 7283.5 7316.5 7318.6 7359.6 6494.4 6590.4 6603.1 6610.4 6705.9 6420.3 6466.4 6471.8 6627.9 6679.7 5131.4 5149.0 5182.2 5190.6 5206.8 4196.9 4218.9 4320.3 4332.5 4421.9 4381.8 4382.6 4402.2 4415.0 4540.9 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | [#4] prototype.html Cycles:180 Average:4326.45 Median:4943.38 stddev:1278.09 (25.9%) stddev-sans-first:1245.90 14:51:54 INFO - PROCESS | 12503 | Values: 325.5 337.8 338.2 338.7 342.8 4845.6 4964.2 4994.8 5069.6 5095.4 4902.5 4975.2 4976.4 4994.4 5050.4 3995.6 4004.9 4015.7 4015.8 4023.7 3689.9 4002.8 4004.8 4020.9 4021.3 3820.4 3828.6 3840.9 3847.4 3857.9 3818.5 3836.0 3848.6 3850.7 3857.6 3358.7 3361.3 3372.1 3386.3 3395.7 4749.5 4924.0 4928.0 4954.4 4999.7 4382.0 4389.1 4393.1 4408.6 4409.3 5244.8 5248.4 5250.7 5267.9 5270.9 3570.3 3656.6 3668.3 3678.0 3678.7 5493.5 5537.3 5544.0 5546.6 5552.6 3692.7 3705.9 3716.6 3720.2 3721.4 3781.7 3791.4 3792.4 3799.6 3809.5 4254.8 4361.6 4374.3 4400.3 4410.8 5306.5 5316.9 5324.7 5339.4 5341.1 1229.2 1249.4 1252.9 1254.8 1274.7 818.3 850.6 856.4 857.8 859.2 4481.6 4496.6 4498.4 4499.0 4534.5 5239.7 5250.6 5272.3 5286.6 5290.9 4537.9 4541.6 4549.4 4566.1 4589.8 2630.5 2809.9 2820.8 2822.9 2828.4 4983.0 4995.3 4996.6 5018.4 5029.5 5243.4 5418.0 5443.1 5467.3 5470.5 4921.9 4924.3 4926.2 4938.3 4938.8 5221.7 5225.8 5289.6 5315.8 5341.8 4957.8 5213.8 5227.6 5246.0 5247.6 4921.0 4948.0 4971.9 4987.3 5004.6 3621.6 3636.9 3665.9 3670.4 3694.1 5157.5 5318.4 5327.3 5335.0 5451.5 5287.6 5295.9 5304.7 5310.4 5335.5 5310.9 5319.9 5320.1 5349.8 5350.4 5069.5 5179.3 5223.8 5255.0 5270.7 5375.6 5389.6 5401.0 5410.2 5429.4 5270.2 5280.2 5285.2 5311.8 5323.7 14:51:54 INFO - PROCESS | 12503 | 14:51:54 INFO - PROCESS | 12503 | [#5] yui.html Cycles:180 Average:14071.83 Median:600.84 stddev:17740.80 (2952.7%) stddev-sans-first:17761.91 14:51:54 INFO - PROCESS | 12503 | Values: 642.9 653.6 719.6 734.9 750.6 11517.1 11653.2 11713.9 11769.9 11861.7 12101.5 12305.8 12378.7 12420.6 12425.0 806.2 825.5 827.7 828.5 828.9 816.3 832.8 839.0 845.1 847.5 882.7 885.2 887.5 888.4 888.9 866.4 875.8 878.1 878.5 881.1 642.1 643.9 646.0 648.6 649.3 13474.2 13504.6 13816.2 13874.2 13932.4 12971.4 13648.5 13794.2 13836.4 13843.3 9705.5 9716.9 9798.2 9946.4 9998.9 3295.3 3310.2 3310.3 3320.8 3432.8 60949.9 60954.7 61041.4 61100.7 61811.4 9704.4 9815.3 10060.3 10082.4 10331.4 19677.0 19806.8 19816.5 19852.5 19872.8 6302.8 6451.2 6477.9 6695.4 6710.0 3366.9 3412.7 3430.2 3444.5 3557.8 2728.6 2796.0 2815.4 2820.0 2822.5 470.8 475.0 476.7 476.8 483.5 597.5 621.0 621.8 622.7 633.1 8066.6 8126.7 8322.8 8395.2 8466.2 566.8 581.9 596.6 600.7 601.0 3834.6 3975.5 4036.0 4065.1 4156.1 59535.4 60146.0 62320.4 63488.4 63926.4 53613.2 54046.5 54967.7 55019.2 56958.7 48504.3 49335.0 50041.0 50707.0 51418.7 7315.7 7366.4 7647.2 7695.3 7842.9 34761.7 35176.6 36083.3 36336.0 36589.6 41047.0 44110.3 44238.6 44642.6 45346.5 10012.5 10108.3 10156.6 10244.7 10581.4 10471.3 10675.8 10790.8 10823.6 11009.8 9630.9 9675.8 9949.8 10288.6 10308.9 8996.4 9151.1 9206.1 9220.4 9261.3 8970.6 9081.5 9314.3 9583.4 9702.6 6909.5 7223.9 7403.6 7533.4 7639.7 7492.9 7965.2 9214.4 9469.8 9533.1 14:51:54 INFO - PROCESS | 12503 | -------- Summary: end -------- 14:51:54 INFO - PROCESS | 12503 | 14:51:55 INFO - TEST-INFO | 12503: exit 0 14:51:55 INFO - TEST-OK | dromaeo_css | took 1120702ms 14:51:55 INFO - TEST-START | kraken 14:51:55 INFO - operating with platform_type : linux_ 14:51:55 INFO - Initialising browser for kraken test... 14:51:55 INFO - TEST-INFO | started process 12872 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphUQR5K/profile http://localhost:33631/getInfo.html) 14:51:58 INFO - PROCESS | 12872 | Iterating over profile /tmp/tmphUQR5K/profile 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/extensions.json = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/safebrowsing = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/cert8.db = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/directoryLinks.json = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/bookmarkbackups = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | Stat failure 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/gmp = Fri Feb 17 2017 14:51:56 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:51:59 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/pluginreg.dat = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/addons.json = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/.parentlock = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/compatibility.ini = Fri Feb 17 2017 14:51:56 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/localstore.rdf = Fri Feb 17 2017 14:32:33 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/user.js = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/key3.db = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/frequencyCap.json = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/places.sqlite-shm = Fri Feb 17 2017 14:51:56 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | __metrics Screen width/height:1600/1200 14:51:59 INFO - PROCESS | 12872 | colorDepth:24 14:51:59 INFO - PROCESS | 12872 | Browser inner width/height: 1024/697 14:51:59 INFO - PROCESS | 12872 | __metrics 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/places.sqlite = Fri Feb 17 2017 14:51:59 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/extensions = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/places.sqlite-wal = Fri Feb 17 2017 14:51:59 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/minidumps = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/startupCache = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/extensions.ini = Fri Feb 17 2017 14:51:56 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/prefs.js = Fri Feb 17 2017 14:32:33 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/thumbnails = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/content-prefs.sqlite = Fri Feb 17 2017 14:51:57 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/blocklist.xml = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/cache2 = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/crashes = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/secmod.db = Fri Feb 17 2017 14:51:55 GMT-0800 (PST) 14:51:59 INFO - PROCESS | 12872 | No birth date. Using mtime. 14:51:59 INFO - PROCESS | 12872 | Using date: /tmp/tmphUQR5K/profile/permissions.sqlite = Fri Feb 17 2017 14:51:56 GMT-0800 (PST) 14:52:00 ERROR - PROCESS | 12872 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 14:52:01 INFO - TEST-INFO | 12872: exit 0 14:52:01 INFO - Browser initialized. 14:52:01 INFO - Running cycle 1/1 for kraken test... 14:52:01 INFO - TEST-INFO | started process 13000 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphUQR5K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:52:13 INFO - PROCESS | 13000 | RSS: Main: 153403392 14:52:13 INFO - PROCESS | 13000 | 14:52:16 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33631/tests/kraken/driver.html?testName=audio-beat-detection) 14:52:17 INFO - PROCESS | 13000 | RSS: Main: 159993856 14:52:17 INFO - PROCESS | 13000 | 14:52:20 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33631/tests/kraken/driver.html?testName=audio-dft) 14:52:20 INFO - PROCESS | 13000 | RSS: Main: 158556160 14:52:20 INFO - PROCESS | 13000 | 14:52:23 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33631/tests/kraken/driver.html?testName=audio-fft) 14:52:24 INFO - PROCESS | 13000 | RSS: Main: 155291648 14:52:24 INFO - PROCESS | 13000 | 14:52:26 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33631/tests/kraken/driver.html?testName=audio-oscillator) 14:52:27 INFO - PROCESS | 13000 | RSS: Main: 155910144 14:52:27 INFO - PROCESS | 13000 | 14:52:29 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33631/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:52:29 INFO - PROCESS | 13000 | RSS: Main: 157462528 14:52:29 INFO - PROCESS | 13000 | 14:52:32 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33631/tests/kraken/driver.html?testName=imaging-darkroom) 14:52:33 INFO - PROCESS | 13000 | RSS: Main: 161103872 14:52:33 INFO - PROCESS | 13000 | 14:52:37 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33631/tests/kraken/driver.html?testName=imaging-desaturate) 14:52:37 INFO - PROCESS | 13000 | RSS: Main: 157732864 14:52:37 INFO - PROCESS | 13000 | 14:52:40 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33631/tests/kraken/driver.html?testName=json-parse-financial) 14:52:41 INFO - PROCESS | 13000 | RSS: Main: 159387648 14:52:41 INFO - PROCESS | 13000 | 14:52:43 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33631/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:52:43 INFO - PROCESS | 13000 | RSS: Main: 159408128 14:52:43 INFO - PROCESS | 13000 | 14:52:45 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:52:46 INFO - PROCESS | 13000 | RSS: Main: 159428608 14:52:46 INFO - PROCESS | 13000 | 14:52:48 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:52:49 INFO - PROCESS | 13000 | RSS: Main: 159465472 14:52:49 INFO - PROCESS | 13000 | 14:52:51 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:52:52 INFO - PROCESS | 13000 | RSS: Main: 159453184 14:52:52 INFO - PROCESS | 13000 | 14:52:55 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:52:56 INFO - PROCESS | 13000 | RSS: Main: 159555584 14:52:56 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | Cycle 1(1): loaded http://localhost:33631/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33631/tests/kraken/driver.html?testName=ai-astar) 14:52:58 INFO - PROCESS | 13000 | RSS: Main: 161497088 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | __start_tp_report 14:52:58 INFO - PROCESS | 13000 | _x_x_mozilla_page_load 14:52:58 INFO - PROCESS | 13000 | _x_x_mozilla_page_load_details 14:52:58 INFO - PROCESS | 13000 | |i|pagename|runs| 14:52:58 INFO - PROCESS | 13000 | |0;ai-astar;109;117;117;115;116;115;116;116;115;116 14:52:58 INFO - PROCESS | 13000 | |1;audio-beat-detection;155;131;128;164;133;130;129;131;154;135 14:52:58 INFO - PROCESS | 13000 | |2;audio-dft;163;164;166;171;167;170;163;164;164;165 14:52:58 INFO - PROCESS | 13000 | |3;audio-fft;75;77;76;106;78;78;79;78;97;78 14:52:58 INFO - PROCESS | 13000 | |4;audio-oscillator;85;84;102;84;84;83;106;85;84;113 14:52:58 INFO - PROCESS | 13000 | |5;imaging-gaussian-blur;114;114;114;115;114;119;114;115;114;118 14:52:58 INFO - PROCESS | 13000 | |6;imaging-darkroom;152;155;151;151;150;151;149;152;151;163 14:52:58 INFO - PROCESS | 13000 | |7;imaging-desaturate;96;97;97;95;96;97;96;96;96;96 14:52:58 INFO - PROCESS | 13000 | |8;json-parse-financial;66;65;65;64;65;67;68;68;67;67 14:52:58 INFO - PROCESS | 13000 | |9;json-stringify-tinderbox;66;66;65;66;66;67;66;65;65;66 14:52:58 INFO - PROCESS | 13000 | |10;stanford-crypto-aes;88;88;99;88;94;87;87;87;88;88 14:52:58 INFO - PROCESS | 13000 | |11;stanford-crypto-ccm;146;148;144;149;141;139;140;142;192;136 14:52:58 INFO - PROCESS | 13000 | |12;stanford-crypto-pbkdf2;185;184;189;191;192;188;187;189;185;182 14:52:58 INFO - PROCESS | 13000 | |13;stanford-crypto-sha256-iterative;77;72;72;70;72;73;71;71;71;76 14:52:58 INFO - PROCESS | 13000 | __end_tp_report 14:52:58 INFO - PROCESS | 13000 | __start_cc_report 14:52:58 INFO - PROCESS | 13000 | _x_x_mozilla_cycle_collect,874 14:52:58 INFO - PROCESS | 13000 | __end_cc_report 14:52:58 INFO - PROCESS | 13000 | __startTimestamp1487371978577__endTimestamp 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | ------- Summary: start ------- 14:52:58 INFO - PROCESS | 13000 | Number of tests: 14 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.00 stddev:2.30 (2.0%) stddev-sans-first:0.78 14:52:58 INFO - PROCESS | 13000 | Values: 109.0 117.0 117.0 115.0 116.0 115.0 116.0 116.0 115.0 116.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:134.00 stddev:13.28 (9.9%) stddev-sans-first:12.76 14:52:58 INFO - PROCESS | 13000 | Values: 155.0 131.0 128.0 164.0 133.0 130.0 129.0 131.0 154.0 135.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#2] audio-dft Cycles:10 Average:165.70 Median:165.50 stddev:2.83 (1.7%) stddev-sans-first:2.83 14:52:58 INFO - PROCESS | 13000 | Values: 163.0 164.0 166.0 171.0 167.0 170.0 163.0 164.0 164.0 165.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#3] audio-fft Cycles:10 Average:82.20 Median:78.00 stddev:10.45 (13.4%) stddev-sans-first:10.76 14:52:58 INFO - PROCESS | 13000 | Values: 75.0 77.0 76.0 106.0 78.0 78.0 79.0 78.0 97.0 78.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:84.00 stddev:11.36 (13.5%) stddev-sans-first:11.84 14:52:58 INFO - PROCESS | 13000 | Values: 85.0 84.0 102.0 84.0 84.0 83.0 106.0 85.0 84.0 113.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:114.50 stddev:1.85 (1.6%) stddev-sans-first:1.92 14:52:58 INFO - PROCESS | 13000 | Values: 114.0 114.0 114.0 115.0 114.0 119.0 114.0 115.0 114.0 118.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#6] imaging-darkroom Cycles:10 Average:152.50 Median:151.50 stddev:4.01 (2.6%) stddev-sans-first:4.25 14:52:58 INFO - PROCESS | 13000 | Values: 152.0 155.0 151.0 151.0 150.0 151.0 149.0 152.0 151.0 163.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 14:52:58 INFO - PROCESS | 13000 | Values: 96.0 97.0 97.0 95.0 96.0 97.0 96.0 96.0 96.0 96.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#8] json-parse-financial Cycles:10 Average:66.20 Median:67.00 stddev:1.40 (2.1%) stddev-sans-first:1.48 14:52:58 INFO - PROCESS | 13000 | Values: 66.0 65.0 65.0 64.0 65.0 67.0 68.0 68.0 67.0 67.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 14:52:58 INFO - PROCESS | 13000 | Values: 66.0 66.0 65.0 66.0 66.0 67.0 66.0 65.0 65.0 66.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:88.00 stddev:3.95 (4.5%) stddev-sans-first:4.16 14:52:58 INFO - PROCESS | 13000 | Values: 88.0 88.0 99.0 88.0 94.0 87.0 87.0 87.0 88.0 88.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:145.00 stddev:16.09 (11.1%) stddev-sans-first:17.05 14:52:58 INFO - PROCESS | 13000 | Values: 146.0 148.0 144.0 149.0 141.0 139.0 140.0 142.0 192.0 136.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.20 Median:188.50 stddev:3.19 (1.7%) stddev-sans-first:3.28 14:52:58 INFO - PROCESS | 13000 | Values: 185.0 184.0 189.0 191.0 192.0 188.0 187.0 189.0 185.0 182.0 14:52:58 INFO - PROCESS | 13000 | 14:52:58 INFO - PROCESS | 13000 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:72.00 stddev:2.27 (3.2%) stddev-sans-first:1.73 14:52:58 INFO - PROCESS | 13000 | Values: 77.0 72.0 72.0 70.0 72.0 73.0 71.0 71.0 71.0 76.0 14:52:58 INFO - PROCESS | 13000 | -------- Summary: end -------- 14:52:58 INFO - PROCESS | 13000 | 14:52:59 INFO - TEST-INFO | 13000: exit 0 14:52:59 INFO - TEST-OK | kraken | took 64135ms 14:52:59 INFO - SUITE-END | took 1184s 14:53:00 INFO - Completed test suite (00:19:45) 14:53:00 INFO - javascript benchmark 14:53:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6264.050198437339, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.961390579141, 2602.7787638050763, 2605.9609105863406, 2619.9607005894914, 2634.7496987786153, 4759.167145749498, 4812.807487700487, 4855.490173531777, 4867.561919427251, 4886.8778280543, 4800.255960326155, 4880.926786098203, 4885.584725298345, 4930.778114984825, 4946.678465899723, 4420.557944205586, 4469.977650111745, 4476.216662084138, 4493.550644935497, 4537.546245375469, 4474.597286244238, 4553.248713962209, 4553.817847286105, 4565.315202719586, 4573.7027093239, 4382.868513944555, 4399.12017596482, 4540.8864778380475, 4594.931076033862, 4602.677812553123, 4417.18282117806, 4419.381286619876, 4488.865334039984, 4524.68327217095, 4539.659525535565, 4413.051193993275, 4432.933505997413, 4461.933071003938, 4473.552644735533, 4488.438945131858, 4805.711657300573, 4869.70781753096, 4882.072406242838, 4888.193448081062, 4902.3626928499425, 5069.518395752398, 5119.795208191668, 5150.871228219287, 5216.37403511581, 5227.137522308853, 5366.865828354284, 5373.919391209135, 5381.139017757179, 5386.084365657847, 5425.104857698475, 4971.304017437562, 4978.079055374767, 4989.750512474435, 5019.096562618691, 5020.046191223656, 5476.397596264408, 5500.532454741312, 5502.257195278587, 5504.366997795305, 5521.061419558685, 5059.8988020239385, 5106.029854327666, 5106.029854327666, 5175.741212939413, 5264.763085661154, 5194.7922083116255, 5227.3465816772905, 5233.058049551118, 5247.449017853147, 5300.522952934254, 5216.191490318931, 5217.217417387422, 5219.217117432339, 5234.659747116425, 5253.133233016586, 5001.199808030698, 5029.220470827027, 5159.458256883048, 5195.922061169085, 5226.111561034633, 4446.888827779331, 4482.058767658763, 4483.14820184164, 4504.954950450519, 4544.204764166324, 3834.9616503834604, 3856.151646637735, 3860.864869729602, 3899.6490315871706, 3899.6880249579967, 4439.933400998988, 4567.1550763108935, 4624.907501849944, 4630.421197350332, 4661.3707149534375, 5149.48505149482, 5245.423003469616, 5250.789968401297, 5291.682499050069, 5340.813071542478, 4575.313702944519, 4591.701539399995, 4592.058627981256, 4592.7014744041535, 4637.258038713857, 4761.071591039714, 4762.047590481918, 4773.856784296477, 4774.880627984258, 4808.687435316763, 4791.496892826342, 4835.323054772264, 4840.322354870251, 4885.731284779371, 4895.167821470287, 5306.044911915892, 5340.519353258225, 5344.679319240858, 5366.6243362964215, 5429.918551221814, 4855.223164293696, 4898.510148985216, 4906.509349065065, 4950.108980383421, 4966.602671786321, 5097.668651537638, 5109.8722531937, 5129.307543481582, 5142.948570514247, 5146.305248791366, 5219.243209734643, 5223.503767142115, 5229.529342359206, 5232.189010703347, 5232.712200828991, 5083.2883396323805, 5089.6691715038405, 5094.057599344133, 5095.337606111182, 5098.2862399263395, 5073.416557095834, 5080.263361812443, 5081.695098294114, 5082.593392528515, 5154.329937108152, 4916.557509824133, 4967.155583550732, 5025.572826309804, 5070.594352451869, 5112.590992720648, 5106.438291787975, 5109.795608175631, 5151.1243088675765, 5159.148740457783, 5199.48005199477, 5002.499750024968, 5045.0, 5049.7727602257255, 5052.040112378636, 5100.209467532539], "value": 4809.046806071924, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.8873642166934, 538.284082170713, 554.0746952589176, 554.8002719021151, 620.1101419463074, 32714.345713085753, 36836.63163368365, 36899.26201475972, 36978.0, 36988.630113698855, 36930.0, 37356.25287494248, 37416.625833741724, 37479.62520374796, 37508.812455937754, 7520.360769334613, 7736.1877002914725, 7750.7674769756995, 7751.961240193791, 8676.566171691422, 8660.480371177719, 8866.955665221698, 8872.37893347466, 8887.911120888806, 8897.68858089967, 8891.199792018717, 8900.37697361185, 8902.643894244222, 8918.73243802687, 8975.95512022439, 8895.911040889572, 8939.32955028369, 8963.058878817728, 8975.506347150904, 8984.955075224647, 9045.502497362642, 9104.453732775988, 9114.225290850285, 9164.633414663405, 9173.862392064124, 31478.37043259122, 31631.367372652418, 31646.841765791254, 31767.0, 31836.497985221606, 3307.7684562080663, 3360.6573725044414, 3398.7790793598338, 3418.3505134024454, 3432.1591210153424, 24034.27897163088, 24122.39694007646, 24166.637500437508, 24622.384440388956, 24808.0, 3263.2494526258856, 3381.543491628623, 3382.3404436134965, 3437.242256516246, 3471.060346506708, 216743.0, 218372.9999999984, 218579.0, 218951.0, 219965.0, 37444.62555374411, 38513.229735405694, 43815.34276985848, 44418.55581444209, 52270.47729522732, 51782.22326665103, 52072.0, 56091.439085608625, 56521.43478565162, 56943.0, 12840.678983025498, 12956.67608309781, 12980.221186728826, 12998.220106793433, 13027.804582931263, 10361.222908281758, 10367.377957322586, 10445.373277603368, 10477.633282835064, 10477.738056548495, 18265.17806698675, 18485.260589576534, 18524.25902963893, 18533.443996680122, 18549.721754173697, 6811.352921472452, 7080.150381954096, 7220.530665506725, 7297.233790452032, 7435.888461673079, 743.1379599664364, 750.4746677325894, 761.432732614206, 773.1688434932357, 778.4200770426069, 25523.489530209292, 26620.467590648466, 26880.193594192206, 27020.864895675397, 27117.728822711913, 929.697848199338, 935.5556110847293, 944.5654998700675, 946.8978866915437, 983.8573406856109, 13215.60353189406, 13713.588592342247, 14062.71874562503, 14224.288785560888, 14377.6405589859, 335180.0, 335255.0, 336935.0, 340423.0, 340504.0, 40927.38608920869, 41046.589534104874, 41085.0, 41219.96950076212, 41260.381094283606, 1140.7547377313917, 1191.7914364986266, 1198.8321634971112, 1204.7470031293526, 1206.7827790997706, 23450.765492344857, 23869.522609548058, 24555.631665524674, 24590.631140532547, 24758.38104047413, 35929.82035089808, 36207.275854482235, 36437.635623644484, 36532.63467365292, 36619.0, 292296.0, 294626.0, 295053.0, 295497.0, 298975.0, 22652.0, 22944.196953106562, 23631.291061268188, 23637.409064773517, 24748.752512474646, 34764.82617586896, 34775.304493909476, 35526.822365888, 35537.28925421529, 35652.108697281736, 28865.71134288714, 29306.853465732536, 29406.0, 30014.69985300119, 30474.238144046576, 28836.855815720788, 29064.0, 29096.0, 29232.41535169327, 30075.097747067623, 29646.11061668067, 30179.849100755233, 30271.789128434615, 30465.695343046285, 30563.694363056085, 25219.0, 27025.1892443227, 27090.510021948983, 27295.86352068227, 27782.861085694443, 21158.10369102693, 21198.36404907855, 21389.037493312528, 21510.462238444165, 21544.353669389944], "value": 16314.621296906502, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.78619972815244, 392.6070599694862, 392.84089943572843, 401.4259608759474, 417.4614746976401, 12069.336186509738, 12446.626601201955, 17197.22612482438, 17722.822771772277, 17829.0, 13022.413991370388, 18158.63682726348, 18167.364142255024, 18234.361797337097, 18522.907385463088, 5044.924326135111, 5281.9207711884355, 5282.022825777224, 5289.735513224343, 5300.469953004707, 5044.571211447032, 5236.659617124894, 5256.053910296145, 5299.735013249341, 5300.178472336775, 5238.738063096849, 5253.133233016548, 5259.395169555492, 5279.788808447657, 5289.576833853302, 5048.217526283432, 5218.817341393033, 5262.947370526284, 5286.656367336111, 5290.312259406291, 4755.762211889391, 4765.451973023112, 4770.904581908377, 4779.522047795227, 4783.976080119577, 21056.894715526323, 21559.892200539052, 21667.67498487524, 21850.453738656503, 22073.779262207332, 2366.7396586375485, 2489.9751002489925, 2516.937076573082, 2528.759767822054, 2544.6819147606548, 13139.0, 13619.523316683868, 13900.791488127687, 14309.427622895071, 14398.0, 1641.532163333456, 1713.366054559821, 1731.4026660802128, 1744.9912750436295, 1861.1810803246663, 72900.0, 73494.63252683755, 75104.24895751027, 75765.0, 76897.0, 10437.32157409766, 10553.630622928315, 10591.205659575562, 10725.678229653124, 10864.185186110915, 15289.541313760605, 15340.622203558243, 15340.92329538345, 15409.152496612569, 15425.0, 6966.268541803038, 7036.014957905897, 7287.890681639779, 7292.307230813064, 7335.596542190231, 6479.254885688112, 6504.544681872224, 6523.0, 6720.563163394364, 6738.764143254963, 4614.76926153691, 4624.28323609841, 4680.297955306732, 4698.248280275139, 4724.551167639069, 1862.8789128706592, 1944.0765636322633, 1975.427126133434, 1984.176566724822, 1993.4318719165258, 935.7520257131865, 965.3773316211004, 972.2805124208035, 978.6966040527459, 980.127686359141, 16713.247903844356, 17319.0, 17335.393261236048, 18066.728999065024, 18429.723554146698, 922.5617831530104, 932.6922115701802, 936.4147407870081, 954.9188318992964, 965.4448692002127, 9420.811583768285, 9967.0, 10029.949850250701, 10071.841808135287, 10173.898261017443, 452365.0, 463766.0, 482937.0, 484934.0, 485961.0, 64022.67988660027, 64989.0, 70202.29797701958, 84224.0, 86180.56909715412, 910.7859652981545, 912.056022017209, 947.0482165423842, 949.3971328206638, 950.7813202963218, 15075.396984120951, 15159.848401516285, 15599.766003509729, 15892.284847182136, 16045.512945493467, 37238.25523489569, 37391.43912841255, 37483.625163748016, 37515.249695006496, 37543.43684844675, 46396.53603463922, 48503.27245091256, 48694.0, 48745.0, 48831.0, 7915.9208407915185, 7935.2064793520185, 7998.360131189607, 8033.0, 8752.343574232085, 9132.771680708036, 12063.81904271419, 12141.15011949155, 12311.13822032485, 12322.691932701755, 10204.744881378025, 10249.692509224735, 10278.936964638664, 10741.24811263204, 10748.838767418338, 10166.135878450414, 10475.633352832614, 10531.368117912949, 10551.841722374016, 10882.455877206266, 10418.895811042095, 10458.372497650165, 10827.566897324019, 10832.133429325793, 11165.106791456501, 7939.84120317602, 8066.153053929487, 8124.67501299965, 8431.072582015851, 8495.95752021236, 9361.485118318558, 9483.14651681352, 9948.054934781185, 10038.698839034842, 10941.45292735344], "value": 8544.85872577098, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1352.5401363536396, 1358.252960871521, 1365.5015919189486, 1373.6016555198992, 1375.3054707372776, 7096.325849044346, 7493.288137626931, 7522.736704215346, 7530.284622960817, 7727.72952946647, 7607.581583012932, 7686.769396918087, 7687.192844751305, 7717.807054823618, 7720.652570634321, 1967.1049672399024, 1994.8803071815737, 2007.3977806658017, 2011.8792872427628, 2016.6168427998707, 1918.4436513411094, 1979.1094007696522, 1984.0575726529928, 1989.8109679580416, 1992.9402117936486, 1919.4913347962802, 1944.7471828662324, 1963.8904019229162, 1967.1836187982042, 1969.8719583227116, 1960.794116617756, 1972.339266345783, 1973.8124878136555, 1975.3481351154085, 1976.357683752786, 1930.9903450482798, 1988.1947811136513, 1992.950176245591, 2004.4587961250381, 2034.888081155536, 7672.232776722283, 7735.071791385069, 7742.922570774333, 7749.0, 7754.302112809818, 4295.849645262432, 4457.777111144463, 4466.039801442673, 4471.552844715502, 4471.731696098244, 2526.6967963844454, 2635.9604605930926, 2678.9464210715873, 2681.6647919010124, 2695.6226128342046, 1058.164050400183, 1083.1334932054403, 1086.4839201379361, 1099.1042300525046, 1120.467777805544, 8651.394402391836, 9041.954790226073, 10108.292419530635, 10114.089731924161, 10190.337628054152, 3652.8538858445895, 3756.549214094326, 3769.4911186989407, 3802.9239415212096, 3806.06751345922, 8167.387445941578, 8179.141190174946, 8185.222403871623, 8200.343972482187, 8204.58977051157, 4342.32693932441, 4348.28253338202, 4362.738235705868, 4364.279893817485, 4367.912641747147, 3914.9804250978564, 3986.123052928351, 4039.434479172918, 4058.2492238935893, 4088.141490287073, 3135.639401468815, 3178.3484385700876, 3201.6478187399366, 3242.821644809558, 3252.4308246056844, 930.1767935377239, 948.2793077261331, 965.7392504024015, 966.2462630325936, 969.8981606931312, 755.8186035351476, 766.7239793674273, 777.7355699062233, 785.2971590426537, 786.3669745854528, 4616.884577885579, 4631.467381251199, 4695.694779839299, 4796.184648609745, 4841.080194762983, 496.9652124351333, 511.34037092151453, 516.2849453506919, 517.6221358408308, 524.2529395611239, 2999.970000299969, 3057.143999680093, 3059.663437021926, 3086.66046734859, 3127.702868227515, 8852.203301702877, 9254.583543740548, 9254.86117708235, 9266.675666351646, 9288.071192880792, 9098.727038188865, 9105.681301154427, 9135.77160570991, 9178.082191780768, 9187.908120918839, 6764.3573860483175, 7138.678759455837, 7182.7126914923865, 7206.0, 7207.567545947155, 4847.684900481458, 4986.825461108989, 5145.176771716508, 5204.115300398866, 5252.0, 8050.114487406264, 8182.549959752271, 8190.631421586162, 8302.045264794626, 8314.625841837253, 7476.962615186889, 8269.255766981001, 8398.874016889873, 8526.104759000464, 8536.871946920677, 5791.276090488689, 6474.935250647433, 6524.5432819704065, 6629.303923088007, 6631.966840165961, 6973.3375329343635, 7283.453740969342, 7316.487845850739, 7318.597477138808, 7359.595222262827, 6494.350564943468, 6590.373914478117, 6603.075569420189, 6610.405063544112, 6705.865882682221, 6420.302360727976, 6466.353364663496, 6471.838204044937, 6627.900581491185, 6679.666016699049, 5131.358580177478, 5149.0474262261605, 5182.24857395683, 5190.6107041971245, 5206.765695543634, 4196.853110141131, 4218.894527636834, 4320.330348795942, 4332.523422423594, 4421.911561768811, 4381.802818873095, 4382.58365455294, 4402.229609818333, 4414.977925110354, 4540.863774086651], "value": 3815.886550512677, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.51173240139775, 337.8158903397648, 338.2052177383149, 338.67318038093237, 342.7686311739576, 4845.632285561527, 4964.156093464111, 4994.825181118662, 5069.645124841258, 5095.43950165482, 4902.4607293197805, 4975.184057818898, 4976.427710813257, 4994.427869647135, 5050.419201791795, 3995.62041606047, 4004.899877503057, 4015.6787457003443, 4015.7791321477307, 4023.7384570002855, 3689.8524059037604, 4002.75983440993, 4004.799760011988, 4020.8592699255532, 4021.296273152201, 3820.36963900956, 3828.5979972102946, 3840.9423858642144, 3847.4228865670093, 3857.9228415431676, 3818.4845045918723, 3836.040989752576, 3848.5766565677754, 3850.653441190292, 3857.614238576148, 3358.7313014958745, 3361.344537815127, 3372.0726800129914, 3386.255023894739, 3395.6604339566093, 4749.453812811536, 4924.0, 4927.965127323267, 4954.355933728592, 4999.700017998932, 4381.956180438218, 4389.056352884145, 4393.132918509653, 4408.559144085598, 4409.250427427313, 5244.790208391698, 5248.381424346589, 5250.658707184059, 5267.894642107175, 5270.868228294285, 3570.321638888603, 3656.597774244831, 3668.3396988542054, 3678.0, 3678.6873115785193, 5493.478119578634, 5537.335519737617, 5544.0, 5546.611737178399, 5552.555795536347, 3692.7415080944074, 3705.9073523162137, 3716.646918542734, 3720.2187540616233, 3721.404575267944, 3781.6596506314563, 3791.3933770596573, 3792.4311353296675, 3799.6200379961783, 3809.504764380613, 4254.808533615995, 4361.629261512807, 4374.321980093091, 4400.251957167226, 4410.757408342572, 5306.52241298285, 5316.893662126736, 5324.6805191687845, 5339.385970613429, 5341.118715411992, 1229.1887354346088, 1249.4065318973596, 1252.8935040521476, 1254.83927367185, 1274.7386785708907, 818.2594751749676, 850.6299759604592, 856.4047986649219, 857.8412993596205, 859.2481578618708, 4481.641468682563, 4496.64026877849, 4498.437695288089, 4498.977505112453, 4534.478534968455, 5239.659422137549, 5250.632455728062, 5272.261883336336, 5286.629935904527, 5290.92063619046, 4537.931931021038, 4541.591256786905, 4549.431321084864, 4566.086782643484, 4589.839355622537, 2630.5396555603074, 2809.8735556899987, 2820.830750154997, 2822.8870845166375, 2828.4343131373807, 4982.975085124551, 4995.300657907896, 4996.625253106032, 5018.444282272507, 5029.547340739351, 5243.4232234454175, 5418.022421816087, 5443.101888188484, 5467.316585426822, 5470.538257783934, 4921.876953076058, 4924.3105965164905, 4926.211806111077, 4938.2839488274, 4938.777755001033, 5221.712805795718, 5225.817096401608, 5289.5503882168905, 5315.787368505216, 5341.759620816995, 4957.752112394438, 5213.791448342024, 5227.61468210932, 5245.973770131125, 5247.55395791362, 4920.975395123001, 4947.975260123676, 4971.850844474671, 4987.326710894128, 5004.649674522894, 3621.6378362163573, 3636.927261454809, 3665.9266814663024, 3670.394384926564, 3694.0949467380665, 5157.510036546522, 5318.361796584435, 5327.334083239595, 5334.973325133349, 5451.509364157245, 5287.603429742708, 5295.90189739007, 5304.734763261744, 5310.442403547572, 5335.546478549211, 5310.946890531045, 5319.893602128013, 5320.14877619579, 5349.786008559614, 5350.4382039886805, 5069.543741063322, 5179.326687530597, 5223.817166399158, 5255.0, 5270.683758974474, 5375.56995440356, 5389.622726409114, 5400.972995134999, 5410.188471729272, 5429.375621803385, 5270.209468579744, 5280.20796880471, 5285.2336411219385, 5311.787528498971, 5323.707196104251], "value": 3923.2453186686607, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.9485641148708, 653.6466340669916, 719.6365835253197, 734.8677238097142, 750.613434081448, 11517.136214783883, 11653.184277100607, 11713.941430292858, 11769.941150294248, 11861.703457413569, 12101.455434505446, 12305.815412768794, 12378.690532736686, 12420.627381178556, 12425.0, 806.1656185847648, 825.533573530953, 827.7020272701852, 828.5443006346516, 828.8508068547659, 816.2939057715064, 832.7626626411474, 838.9622466988969, 845.1168528887297, 847.4618617178074, 882.6557642519423, 885.1900511032397, 887.5429153985702, 888.404768804899, 888.8666699994988, 866.3545658484406, 875.7810547363159, 878.1438097854605, 878.5431575580662, 881.0528681667201, 642.1235014205575, 643.9195100612424, 646.0309535696455, 648.6108334998963, 649.2792999770252, 13474.191548507022, 13504.594862154152, 13816.171029738249, 13874.236916969563, 13932.442702291895, 12971.449418043921, 13648.522301719393, 13794.241316727575, 13836.377363018688, 13843.30783460823, 9705.466199359033, 9716.854247186298, 9798.216142708567, 9946.35348702332, 9998.900010999869, 3295.2750394913082, 3310.2220978070136, 3310.2717402171484, 3320.8007519548905, 3432.7597068205237, 60949.86675466337, 60954.695226524025, 61041.38958610446, 61100.69449652768, 61811.381886181014, 9704.369216001078, 9815.312928095107, 10060.346077504939, 10082.44546549932, 10331.380117192992, 19677.0, 19806.801931980495, 19816.504587385432, 19852.50368740793, 19872.801271987384, 6302.779402720975, 6451.228355999692, 6477.914388664865, 6695.4308883745425, 6710.0, 3366.915827104342, 3412.744044196695, 3430.1596108952967, 3444.4661077533024, 3557.7865328080397, 2728.563429851214, 2796.0, 2815.42283831814, 2820.0411859967708, 2822.463731890934, 470.80885360039156, 474.9976250118796, 476.7473239183169, 476.8235752771497, 483.47059969333526, 597.4742226840352, 620.950323974081, 621.8352136683692, 622.6886556721639, 633.1389310537666, 8066.556339401389, 8126.674933002732, 8322.791930201793, 8395.24442800151, 8466.195711407407, 566.8157848699107, 581.9272590926134, 596.6092209602717, 600.7236671131241, 600.9519238460913, 3834.559025712079, 3975.5030621172355, 4035.9506528302854, 4065.1463192729857, 4156.106437115974, 59535.404645952985, 60146.0, 62320.37679623236, 63488.36511634824, 63926.36073639204, 53613.195802063, 54046.45953540493, 54967.725161374736, 55019.17471237935, 56958.71520642453, 48504.27243591349, 49335.0, 50041.0, 50707.0, 51418.74290628523, 7315.709528827916, 7366.400063666243, 7647.235276472531, 7695.307422332017, 7842.882356764539, 34761.65238347584, 35176.64823351836, 36083.27833443264, 36336.0, 36589.634103659686, 41047.0, 44110.33834492549, 44238.557614423444, 44642.55357446384, 45346.54653453423, 10012.499375031364, 10108.342957707726, 10156.593736250466, 10244.692659220234, 10581.418022008864, 10471.267011309135, 10675.786484270116, 10790.838137427787, 10823.621173258898, 11009.834852477059, 9630.903690963001, 9675.806483870143, 9949.751256218653, 10288.639897603547, 10308.889880509574, 8996.415233009833, 9151.084891510796, 9206.125418085272, 9220.44677319363, 9261.305402094735, 8970.551472426483, 9081.500517471339, 9314.301427392837, 9583.424994500352, 9702.563384647849, 6909.481788865955, 7223.8555228896175, 7403.629818509161, 7533.359664428366, 7639.656215470428, 7492.925070749441, 7965.163657815846, 9214.44713317203, 9469.810603787748, 9533.112714804123], "value": 6018.678573825275, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 117.0, 117.0, 115.0, 116.0, 115.0, 116.0, 116.0, 115.0, 116.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 131.0, 128.0, 164.0, 133.0, 130.0, 129.0, 131.0, 154.0, 135.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 166.0, 171.0, 167.0, 170.0, 163.0, 164.0, 164.0, 165.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 76.0, 106.0, 78.0, 78.0, 79.0, 78.0, 97.0, 78.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 102.0, 84.0, 84.0, 83.0, 106.0, 85.0, 84.0, 113.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 115.0, 114.0, 119.0, 114.0, 115.0, 114.0, 118.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 155.0, 151.0, 151.0, 150.0, 151.0, 149.0, 152.0, 151.0, 163.0], "value": 152.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 95.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 64.0, 65.0, 67.0, 68.0, 68.0, 67.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 65.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 99.0, 88.0, 94.0, 87.0, 87.0, 87.0, 88.0, 88.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 144.0, 149.0, 141.0, 139.0, 140.0, 142.0, 192.0, 136.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 189.0, 191.0, 192.0, 188.0, 187.0, 189.0, 185.0, 182.0], "value": 187.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 72.0, 70.0, 72.0, 73.0, 71.0, 71.0, 71.0, 76.0], "value": 72.5, "unit": "score"}]}]} 14:53:00 INFO - Return code: 0 14:53:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:53:00 ERROR - # TBPL SUCCESS # 14:53:00 INFO - Running post-action listener: _resource_record_post_action 14:53:00 INFO - [mozharness: 2017-02-17 22:53:00.379088Z] Finished run-tests step (success) 14:53:00 INFO - Running post-run listener: _resource_record_post_run 14:53:00 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 151552; Write bytes: 362221568; Read time: 196; Write time: 227748 14:53:00 INFO - TinderboxPrint: CPU usage
13.5% 14:53:00 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 196 14:53:00 INFO - TinderboxPrint: I/O write bytes / time
362,221,568 / 227,748 14:53:00 INFO - TinderboxPrint: CPU idle
8,291.5 (86.5%) 14:53:00 INFO - TinderboxPrint: CPU user
1,236.9 (12.9%) 14:53:00 INFO - TinderboxPrint: Swap in / out
0 / 0 14:53:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105676800; Read time: 0; Write time: 85600 14:53:00 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 151552; Write bytes: 256544768; Read time: 196; Write time: 142148 14:53:01 INFO - Running post-run listener: _upload_blobber_files 14:53:01 INFO - Blob upload gear active. 14:53:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:53:01 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 14:53:01 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'] 14:53:01 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 14:53:01 INFO - (blobuploader) - INFO - Open directory for files ... 14:53:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:53:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:53:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:02 INFO - (blobuploader) - INFO - Done attempting. 14:53:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:53:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:05 INFO - (blobuploader) - INFO - Done attempting. 14:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:53:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:06 INFO - (blobuploader) - INFO - Done attempting. 14:53:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:53:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:53:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:53:07 INFO - (blobuploader) - INFO - Done attempting. 14:53:07 INFO - (blobuploader) - INFO - Iteration through files over. 14:53:07 INFO - Return code: 0 14:53:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:53:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:53:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca3c1402874a6907b32151e982ae614d9df30fb3e4731d12942b7f911435993a545d6ccfde6bba53a094b4beaa4b7b626e53b8483407d1b43b931ce86139a5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d303a07cf18060178f0785922e5c6f6349a8fa2278feb736dd8c4297740b332d4f1e590bc80a639405b8a6fb5473d9324f1d76fa53d01f9b099e9c82dc0aec7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6371adfe09678ab9c986b6f6f61704dbd1dc59ab816fcfaa8cdd73ed410913881ff4f8f94de6c04ab61fc1a53f891e07a879bc50fea0da3010260ecfd1e733d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f34c788f9def724b7f2783444cc324f9ba4f297e44b1567f695f472f85694624711b8bbf02a308ae8462e46b070b5e58b06f0e913b25b474ad4fc9be03c77cda"} 14:53:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:53:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:53:07 INFO - Contents: 14:53:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca3c1402874a6907b32151e982ae614d9df30fb3e4731d12942b7f911435993a545d6ccfde6bba53a094b4beaa4b7b626e53b8483407d1b43b931ce86139a5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d303a07cf18060178f0785922e5c6f6349a8fa2278feb736dd8c4297740b332d4f1e590bc80a639405b8a6fb5473d9324f1d76fa53d01f9b099e9c82dc0aec7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6371adfe09678ab9c986b6f6f61704dbd1dc59ab816fcfaa8cdd73ed410913881ff4f8f94de6c04ab61fc1a53f891e07a879bc50fea0da3010260ecfd1e733d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f34c788f9def724b7f2783444cc324f9ba4f297e44b1567f695f472f85694624711b8bbf02a308ae8462e46b070b5e58b06f0e913b25b474ad4fc9be03c77cda"} 14:53:07 INFO - Running post-run listener: copy_logs_to_upload_dir 14:53:07 INFO - Copying logs to upload dir... 14:53:07 INFO - mkdir: /builds/slave/test/build/upload/logs 14:53:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.435474 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-17 14:53:07.605705) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:53:07.608822) ========= 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=923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca3c1402874a6907b32151e982ae614d9df30fb3e4731d12942b7f911435993a545d6ccfde6bba53a094b4beaa4b7b626e53b8483407d1b43b931ce86139a5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d303a07cf18060178f0785922e5c6f6349a8fa2278feb736dd8c4297740b332d4f1e590bc80a639405b8a6fb5473d9324f1d76fa53d01f9b099e9c82dc0aec7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6371adfe09678ab9c986b6f6f61704dbd1dc59ab816fcfaa8cdd73ed410913881ff4f8f94de6c04ab61fc1a53f891e07a879bc50fea0da3010260ecfd1e733d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f34c788f9def724b7f2783444cc324f9ba4f297e44b1567f695f472f85694624711b8bbf02a308ae8462e46b070b5e58b06f0e913b25b474ad4fc9be03c77cda"} build_url:https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011354 build_url: 'https://queue.taskcluster.net/v1/task/JuvUKJ0xRCS_MIz4Y2dtkA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca3c1402874a6907b32151e982ae614d9df30fb3e4731d12942b7f911435993a545d6ccfde6bba53a094b4beaa4b7b626e53b8483407d1b43b931ce86139a5dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d303a07cf18060178f0785922e5c6f6349a8fa2278feb736dd8c4297740b332d4f1e590bc80a639405b8a6fb5473d9324f1d76fa53d01f9b099e9c82dc0aec7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e6371adfe09678ab9c986b6f6f61704dbd1dc59ab816fcfaa8cdd73ed410913881ff4f8f94de6c04ab61fc1a53f891e07a879bc50fea0da3010260ecfd1e733d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f34c788f9def724b7f2783444cc324f9ba4f297e44b1567f695f472f85694624711b8bbf02a308ae8462e46b070b5e58b06f0e913b25b474ad4fc9be03c77cda"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:53:07.633261) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:53:07.633581) ========= 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=923d835db2ba3686ebe126b700000192-1487370712.294494-1075581059 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:53:07.717146) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:53:07.717482) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:53:07.717774) ========= ========= Total master_lag: 0.16 =========